2025-06-06T06:42:25.496Z,1749192145.496 [CommandExec](IMPORTANT): got command restart logs 2025-06-06T06:42:55.489Z,1749192175.489 [NAL9602](INFO): SBD MO Status=0, MOMSN=21082, MT Status=0, MTMSN=0 2025-06-06T06:42:55.489Z,1749192175.489 [NAL9602](INFO): No messages in MT queue 2025-06-06T06:42:56.702Z,1749192176.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064255.00,A,3645.99062,N,12155.41811,W,0.719,154.50,060625,,,D*7B 2025-06-06T06:42:56.705Z,1749192176.705 [NAL9602](INFO): GPS fix at 20250606T064255: (36.766510, -121.923635) 2025-06-06T06:42:56.716Z,1749192176.716 [UniversalFixResidualReporter](INFO): Fix residual: 37.0 %DT, over the last 1026.3 m. Residual distance 380.0 m at bearing -62.7 degrees. Fix at (36.7665, -121.9236) with 553.9 m made good. 2025-06-06T06:42:56.717Z,1749192176.717 [Default:CheckIn:Read_GPS] Stopped 2025-06-06T06:42:56.717Z,1749192176.717 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-06T06:43:05.154Z,1749192185.154 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250605T121700/Courier0060.lzma 2025-06-06T06:43:06.156Z,1749192186.156 [DataOverHttps](INFO): Moved sent file to Logs/20250605T121700/Courier0060.lzma.bak 2025-06-06T06:43:06.156Z,1749192186.156 [DataOverHttps](INFO): SBD MOMSN=25110738 2025-06-06T06:43:23.299Z,1749192203.299 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20250606T064225/Courier0000.lzma 2025-06-06T06:43:24.300Z,1749192204.300 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Courier0000.lzma.bak 2025-06-06T06:43:24.300Z,1749192204.300 [DataOverHttps](INFO): SBD MOMSN=25110740 2025-06-06T06:43:29.437Z,1749192209.437 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T06:43:41.043Z,1749192221.043 [DataOverHttps](INFO): Sending 587 bytes from file Logs/20250605T121700/Express0061.lzma 2025-06-06T06:43:42.044Z,1749192222.044 [DataOverHttps](INFO): Moved sent file to Logs/20250605T121700/Express0061.lzma.bak 2025-06-06T06:43:42.044Z,1749192222.044 [DataOverHttps](INFO): SBD MOMSN=25110745 2025-06-06T06:44:02.995Z,1749192242.995 [DataOverHttps](INFO): Sending 490 bytes from file Logs/20250606T064225/Express0001.lzma 2025-06-06T06:44:03.996Z,1749192243.996 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0001.lzma.bak 2025-06-06T06:44:03.996Z,1749192243.996 [DataOverHttps](INFO): SBD MOMSN=25110762 2025-06-06T06:44:05.403Z,1749192245.403 [Default:CheckIn:Read_Iridium] Stopped 2025-06-06T06:44:05.403Z,1749192245.403 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-06T06:44:05.403Z,1749192245.403 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-06T06:46:14.955Z,1749192374.955 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T064613 2025-06-06T06:46:22.738Z,1749192382.738 [DataOverHttps](INFO): Received command: configSet CBIT.runNavErrorCritical 0 bool persist 2025-06-06T06:46:23.177Z,1749192383.177 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-06-06T06:47:56.360Z,1749192476.360 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T064755 2025-06-06T06:48:04.082Z,1749192484.082 [DataOverHttps](INFO): Received command: sched 20250606T1217 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs" 2025-06-06T06:48:04.094Z,1749192484.094 [CommandExec](IMPORTANT): got command schedule 20250606T121700 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs" 2025-06-06T06:48:04.095Z,1749192484.095 [CommandExec](IMPORTANT): Scheduled #29: "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs", AT TIME: 2025-06-06T12:17:00Z 2025-06-06T06:48:04.095Z,1749192484.095 [CommandExec](FAULT): Scheduling was paused by an error at 2025-06-06T06:15:49Z 2025-06-06T06:49:06.020Z,1749192546.020 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-06T06:49:06.021Z,1749192546.021 [Default:CheckIn:C.Wait] Stopped 2025-06-06T06:49:06.021Z,1749192546.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-06T06:49:06.021Z,1749192546.021 [Default:CheckIn:D] Running Loop=1 2025-06-06T06:49:06.438Z,1749192546.438 [Default:CheckIn:D] Stopped 2025-06-06T06:49:06.438Z,1749192546.438 [Default:CheckIn:E] Running Loop=1 2025-06-06T06:49:06.831Z,1749192546.831 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.812374 min 2025-06-06T06:49:06.831Z,1749192546.831 [Default:CheckIn:E] Stopped 2025-06-06T06:49:06.831Z,1749192546.831 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-06T06:49:06.831Z,1749192546.831 [Default:CheckIn] Stopped 2025-06-06T06:49:06.832Z,1749192546.832 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-06T06:49:06.832Z,1749192546.832 [Default:CheckIn](INFO): Running loop #2 2025-06-06T06:49:06.832Z,1749192546.832 [Default:CheckIn] Running Loop=2 2025-06-06T06:49:06.832Z,1749192546.832 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-06T06:49:06.832Z,1749192546.832 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-06T06:49:08.861Z,1749192548.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064908.00,A,3645.96370,N,12155.40026,W,1.225,164.92,060625,,,D*7C 2025-06-06T06:49:08.864Z,1749192548.864 [NAL9602](INFO): GPS fix at 20250606T064908: (36.766062, -121.923338) 2025-06-06T06:49:08.874Z,1749192548.874 [Default:CheckIn:Read_GPS] Stopped 2025-06-06T06:49:08.874Z,1749192548.874 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-06T06:49:16.914Z,1749192556.914 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250606T064225/Courier0005.lzma 2025-06-06T06:49:17.916Z,1749192557.916 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Courier0005.lzma.bak 2025-06-06T06:49:17.916Z,1749192557.916 [DataOverHttps](INFO): SBD MOMSN=25110776 2025-06-06T06:49:36.487Z,1749192576.487 [DataOverHttps](INFO): Sending 451 bytes from file Logs/20250606T064225/Express0007.lzma 2025-06-06T06:49:37.488Z,1749192577.488 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0007.lzma.bak 2025-06-06T06:49:37.488Z,1749192577.488 [DataOverHttps](INFO): SBD MOMSN=25110779 2025-06-06T06:49:53.862Z,1749192593.862 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250606T064225/Express0010.lzma 2025-06-06T06:49:54.864Z,1749192594.864 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0010.lzma.bak 2025-06-06T06:49:54.864Z,1749192594.864 [DataOverHttps](INFO): SBD MOMSN=25110788 2025-06-06T06:49:56.149Z,1749192596.149 [Default:CheckIn:Read_Iridium] Stopped 2025-06-06T06:49:56.150Z,1749192596.150 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-06T06:49:56.168Z,1749192596.168 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-06T06:50:03.804Z,1749192603.804 [NAL9602](INFO): SBD MO Status=2, MOMSN=21083, MT Status=2, MTMSN=0 2025-06-06T06:50:03.804Z,1749192603.804 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T06:50:34.741Z,1749192634.741 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T065033 2025-06-06T06:50:42.586Z,1749192642.586 [DataOverHttps](INFO): Received command: sched 20250606T1217 "load Science/sci2.tl;set sci2.MissionTimeout 17 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 36.79815 degree;set sci2.Lon1 -122.05519 degree;set sci2.Lat2 36.82262 degree;set sci2.Lon2 -121.96198 degree" 2bv04 1 4 2025-06-06T06:50:42.593Z,1749192642.593 [CommandExec](IMPORTANT): got command schedule 20250606T121700 "load Science/sci2.tl;set sci2.MissionTimeout 17 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 36.79815 degree;set sci2.Lon1 -122.05519 degree;set sci2.Lat2 36.82262 degree;set sci2.Lon2 -121.96198 degree" 2bv04 1 4.000000 2025-06-06T06:50:42.593Z,1749192642.593 [CommandExec](IMPORTANT): Scheduling command #1 of 4 with id=2bv04 2025-06-06T06:50:42.595Z,1749192642.595 [CommandExec](IMPORTANT): Scheduled #30 (#1 of 4 with id='2bv04'): "load Science/sci2.tl;set sci2.MissionTimeout 17 h;set sci2.NeedCommsTime 90 min;set sci2.Lat1 36.79815 degree;set sci2.Lon1 -122.05519 degree;set sci2.Lat2 36.82262 degree;set sci2.Lon2 -121.96198 degree", AT TIME: 2025-06-06T12:17:00Z 2025-06-06T06:50:42.595Z,1749192642.595 [CommandExec](FAULT): Scheduling was paused by an error at 2025-06-06T06:15:49Z 2025-06-06T06:50:43.589Z,1749192643.589 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T065042 2025-06-06T06:50:51.406Z,1749192651.406 [DataOverHttps](INFO): Received command: sched 20250606T1217 "set sci2.Lat3 36.8468 degree;set sci2.Lon3 -121.97365 degree;set sci2.Lat4 36.85834 degree;set sci2.Lon4 -121.9479 degree;set sci2.Lat5 36.87083 degree;set sci2.Lon5 -121.9582 degree;set sci2.Lat6 36.86575 degree" 2bv04 2 4 2025-06-06T06:50:51.464Z,1749192651.464 [CommandExec](IMPORTANT): got command schedule 20250606T121700 "set sci2.Lat3 36.8468 degree;set sci2.Lon3 -121.97365 degree;set sci2.Lat4 36.85834 degree;set sci2.Lon4 -121.9479 degree;set sci2.Lat5 36.87083 degree;set sci2.Lon5 -121.9582 degree;set sci2.Lat6 36.86575 degree" 2bv04 2 4.000000 2025-06-06T06:50:51.465Z,1749192651.465 [CommandExec](IMPORTANT): Scheduling command #2 of 4 with id=2bv04 2025-06-06T06:50:51.467Z,1749192651.467 [CommandExec](IMPORTANT): Scheduled #31 (#2 of 4 with id='2bv04'): "set sci2.Lat3 36.8468 degree;set sci2.Lon3 -121.97365 degree;set sci2.Lat4 36.85834 degree;set sci2.Lon4 -121.9479 degree;set sci2.Lat5 36.87083 degree;set sci2.Lon5 -121.9582 degree;set sci2.Lat6 36.86575 degree", AT TIME: 2025-06-06T12:17:00Z 2025-06-06T06:50:51.467Z,1749192651.467 [CommandExec](FAULT): Scheduling was paused by an error at 2025-06-06T06:15:49Z 2025-06-06T06:50:52.408Z,1749192652.408 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T065051 2025-06-06T06:50:55.951Z,1749192655.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=21083, MT Status=2, MTMSN=0 2025-06-06T06:50:55.952Z,1749192655.952 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T06:51:00.406Z,1749192660.406 [DataOverHttps](INFO): Received command: sched 20250606T1217 "set sci2.Lon6 -121.96661 degree;set sci2.Lat7 36.84501 degree;set sci2.Lon7 -121.95168 degree;set sci2.Speed 1 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.SinkOffSurface 1 bool;set sci2.SinkDepth 3 m" 2bv04 3 4 2025-06-06T06:51:00.413Z,1749192660.413 [CommandExec](IMPORTANT): got command schedule 20250606T121700 "set sci2.Lon6 -121.96661 degree;set sci2.Lat7 36.84501 degree;set sci2.Lon7 -121.95168 degree;set sci2.Speed 1 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.SinkOffSurface 1 bool;set sci2.SinkDepth 3 m" 2bv04 3 4.000000 2025-06-06T06:51:00.414Z,1749192660.414 [CommandExec](IMPORTANT): Scheduling command #3 of 4 with id=2bv04 2025-06-06T06:51:00.415Z,1749192660.415 [CommandExec](IMPORTANT): Scheduled #32 (#3 of 4 with id='2bv04'): "set sci2.Lon6 -121.96661 degree;set sci2.Lat7 36.84501 degree;set sci2.Lon7 -121.95168 degree;set sci2.Speed 1 m/s;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 40 m;set sci2.SinkOffSurface 1 bool;set sci2.SinkDepth 3 m", AT TIME: 2025-06-06T12:17:00Z 2025-06-06T06:51:00.415Z,1749192660.415 [CommandExec](FAULT): Scheduling was paused by an error at 2025-06-06T06:15:49Z 2025-06-06T06:51:01.408Z,1749192661.408 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T065100 2025-06-06T06:51:09.306Z,1749192669.306 [DataOverHttps](INFO): Received command: sched 20250606T1217 "set sci2.MaxDepth 55 m;set sci2:NeedComms.CommsTimeout 10 min;run" 2bv04 4 4 2025-06-06T06:51:09.379Z,1749192669.379 [CommandExec](IMPORTANT): got command schedule 20250606T121700 "set sci2.MaxDepth 55 m;set sci2:NeedComms.CommsTimeout 10 min;run" 2bv04 4 4.000000 2025-06-06T06:51:09.380Z,1749192669.380 [CommandExec](IMPORTANT): Scheduling command #4 of 4 with id=2bv04 2025-06-06T06:51:09.381Z,1749192669.381 [CommandExec](IMPORTANT): Scheduled #33 (#4 of 4 with id='2bv04'): "set sci2.MaxDepth 55 m;set sci2:NeedComms.CommsTimeout 10 min;run", AT TIME: 2025-06-06T12:17:00Z 2025-06-06T06:51:09.381Z,1749192669.381 [CommandExec](FAULT): Scheduling was paused by an error at 2025-06-06T06:15:49Z 2025-06-06T06:51:10.308Z,1749192670.308 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T065109 2025-06-06T06:51:18.026Z,1749192678.026 [DataOverHttps](INFO): Received command: schedule resume 2025-06-06T06:51:18.035Z,1749192678.035 [CommandExec](IMPORTANT): got command schedule resume 2025-06-06T06:51:18.036Z,1749192678.036 [CommandExec](IMPORTANT): Scheduling is resumed 2025-06-06T06:51:24.208Z,1749192684.208 [NAL9602](INFO): SBD MO Status=0, MOMSN=21083, MT Status=0, MTMSN=0 2025-06-06T06:51:24.209Z,1749192684.209 [NAL9602](INFO): No messages in MT queue 2025-06-06T06:51:54.905Z,1749192714.905 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T06:52:01.776Z,1749192721.776 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-06T06:52:01.777Z,1749192721.777 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-06T06:52:01.780Z,1749192721.780 [BPC1](INFO): Received data from all battery sticks. 2025-06-06T06:52:47.831Z,1749192767.831 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000,1489.0,000 2025-06-06T06:52:47.831Z,1749192767.831 [DVL_micro](ERROR): Failed to parse: :TS,00000000,1489.0,000 2025-06-06T06:54:53.781Z,1749192893.781 [DataOverHttps](IMPORTANT): SBD MTMSN=20250606T065452 2025-06-06T06:54:56.711Z,1749192896.711 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-06T06:54:56.712Z,1749192896.712 [Default:CheckIn:C.Wait] Stopped 2025-06-06T06:54:56.712Z,1749192896.712 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-06T06:54:56.712Z,1749192896.712 [Default:CheckIn:D] Running Loop=1 2025-06-06T06:54:57.138Z,1749192897.138 [Default:CheckIn:D] Stopped 2025-06-06T06:54:57.142Z,1749192897.142 [Default:CheckIn:E] Running Loop=1 2025-06-06T06:54:57.543Z,1749192897.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.657374 min 2025-06-06T06:54:57.543Z,1749192897.543 [Default:CheckIn:E] Stopped 2025-06-06T06:54:57.543Z,1749192897.543 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-06T06:54:57.544Z,1749192897.544 [Default:CheckIn] Stopped 2025-06-06T06:54:57.544Z,1749192897.544 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-06T06:54:57.544Z,1749192897.544 [Default:CheckIn](INFO): Running loop #3 2025-06-06T06:54:57.544Z,1749192897.544 [Default:CheckIn] Running Loop=3 2025-06-06T06:54:57.544Z,1749192897.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-06T06:54:57.544Z,1749192897.544 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-06T06:54:59.530Z,1749192899.530 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065458.00,A,3645.94354,N,12155.39047,W,0.603,167.90,060625,,,D*78 2025-06-06T06:54:59.532Z,1749192899.532 [NAL9602](INFO): GPS fix at 20250606T065458: (36.765726, -121.923175) 2025-06-06T06:54:59.547Z,1749192899.547 [Default:CheckIn:Read_GPS] Stopped 2025-06-06T06:54:59.547Z,1749192899.547 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-06T06:55:01.046Z,1749192901.046 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250606T064225/Courier0012.lzma 2025-06-06T06:55:01.049Z,1749192901.049 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 7 h;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.81245 degree;set keepstation.Longitude -122.06257 degree;set keepstation.Depth 20 m" 2bv7d 1 2 2025-06-06T06:55:01.091Z,1749192901.091 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 7 h;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.81245 degree;set keepstation.Longitude -122.06257 degree;set keepstation.Depth 20 m" 2bv7d 1 2.000000 2025-06-06T06:55:01.092Z,1749192901.092 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=2bv7d 2025-06-06T06:55:01.093Z,1749192901.093 [CommandExec](IMPORTANT): Scheduled #34 (#1 of 2 with id='2bv7d'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 7 h;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.81245 degree;set keepstation.Longitude -122.06257 degree;set keepstation.Depth 20 m", ASAP 2025-06-06T06:55:02.038Z,1749192902.038 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Courier0012.lzma.bak 2025-06-06T06:55:02.038Z,1749192902.038 [DataOverHttps](IMPORTANT): SBD MOMSN=25110816, MTMSN=20250606T065501 2025-06-06T06:55:09.498Z,1749192909.498 [DataOverHttps](INFO): Received command: sched asap "set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 2bv7d 2 2 2025-06-06T06:55:09.570Z,1749192909.570 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 2bv7d 2 2.000000 2025-06-06T06:55:09.571Z,1749192909.571 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=2bv7d 2025-06-06T06:55:09.572Z,1749192909.572 [CommandExec](IMPORTANT): Scheduled #35 (#2 of 2 with id='2bv7d'): "set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run", ASAP 2025-06-06T06:55:09.695Z,1749192909.695 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-06-06T06:55:09.696Z,1749192909.696 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-06-06T06:55:09.696Z,1749192909.696 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-06-06T06:55:09.719Z,1749192909.719 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-06-06T06:55:11.581Z,1749192911.581 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-06-06T06:55:11.589Z,1749192911.589 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-06-06T06:55:11.592Z,1749192911.592 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-06-06T06:55:11.594Z,1749192911.594 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-06-06T06:55:11.596Z,1749192911.596 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-06-06T06:55:11.599Z,1749192911.599 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-06-06T06:55:11.601Z,1749192911.601 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-06-06T06:55:11.604Z,1749192911.604 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-06-06T06:55:11.606Z,1749192911.606 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-06-06T06:55:11.608Z,1749192911.608 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-06-06T06:55:11.610Z,1749192911.610 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-06-06T06:55:11.612Z,1749192911.612 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-06-06T06:55:11.615Z,1749192911.615 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-06-06T06:55:11.617Z,1749192911.617 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-06-06T06:55:11.619Z,1749192911.619 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-06-06T06:55:11.622Z,1749192911.622 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-06-06T06:55:11.624Z,1749192911.624 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-06-06T06:55:11.655Z,1749192911.655 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-06-06T06:55:11.667Z,1749192911.667 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-06-06T06:55:11.677Z,1749192911.677 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-06-06T06:55:11.696Z,1749192911.696 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-06-06T06:55:11.697Z,1749192911.697 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-06-06T06:55:11.697Z,1749192911.697 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-06-06T06:55:11.703Z,1749192911.703 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-06-06T06:55:12.094Z,1749192912.094 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-06-06T06:55:12.102Z,1749192912.102 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-06-06T06:55:12.105Z,1749192912.105 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-06-06T06:55:12.107Z,1749192912.107 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-06-06T06:55:12.109Z,1749192912.109 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-06-06T06:55:12.112Z,1749192912.112 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-06-06T06:55:12.114Z,1749192912.114 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-06-06T06:55:12.116Z,1749192912.116 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-06-06T06:55:12.118Z,1749192912.118 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-06-06T06:55:12.124Z,1749192912.124 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-06T06:55:12.140Z,1749192912.140 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-06-06T06:55:12.140Z,1749192912.140 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-06-06T06:55:12.142Z,1749192912.142 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-06-06T06:55:12.327Z,1749192912.327 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-06-06T06:55:12.331Z,1749192912.331 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-06-06T06:55:12.333Z,1749192912.333 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-06-06T06:55:12.348Z,1749192912.348 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-06-06T06:55:12.351Z,1749192912.351 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-06-06T06:55:12.352Z,1749192912.352 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-06-06T06:55:12.358Z,1749192912.358 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-06-06T06:55:12.364Z,1749192912.364 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-06-06T06:55:12.372Z,1749192912.372 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-06-06T06:55:12.372Z,1749192912.372 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-06-06T06:55:12.373Z,1749192912.373 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-06-06T06:55:12.431Z,1749192912.431 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-06-06T06:55:12.434Z,1749192912.434 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-06-06T06:55:12.436Z,1749192912.436 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-06-06T06:55:12.447Z,1749192912.447 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-06-06T06:55:12.448Z,1749192912.448 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-06-06T06:55:12.449Z,1749192912.449 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-06-06T06:55:12.583Z,1749192912.583 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-06-06T06:55:12.587Z,1749192912.587 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-06-06T06:55:12.589Z,1749192912.589 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-06-06T06:55:12.591Z,1749192912.591 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-06-06T06:55:12.593Z,1749192912.593 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-06-06T06:55:12.652Z,1749192912.652 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-06-06T06:55:12.666Z,1749192912.666 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-06-06T06:55:12.668Z,1749192912.668 [keepstation:H.Pitch](DEBUG): Construct. 2025-06-06T06:55:12.732Z,1749192912.732 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-06T06:55:12.740Z,1749192912.740 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-06-06T06:55:12.744Z,1749192912.744 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-06-06T06:55:12.761Z,1749192912.761 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-06T06:55:12.764Z,1749192912.764 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-06-06T06:55:12.769Z,1749192912.769 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-06-06T06:55:12.774Z,1749192912.774 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-06-06T06:55:12.779Z,1749192912.779 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-06-06T06:55:12.787Z,1749192912.787 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-06-06T06:55:12.799Z,1749192912.799 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-06-06T06:55:12.842Z,1749192912.842 [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 priorityHere=false 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" priorityHere=false 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 priorityHere=false 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" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false 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-06-06T06:55:12.843Z,1749192912.843 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-06-06T06:55:21.916Z,1749192921.916 [DataOverHttps](INFO): Sending 819 bytes from file Logs/20250606T064225/Express0013.lzma 2025-06-06T06:55:22.916Z,1749192922.916 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0013.lzma.bak 2025-06-06T06:55:22.916Z,1749192922.916 [DataOverHttps](INFO): SBD MOMSN=25110821 2025-06-06T06:55:34.108Z,1749192934.108 [NAL9602](INFO): SBD MO Status=0, MOMSN=21084, MT Status=0, MTMSN=0 2025-06-06T06:55:34.108Z,1749192934.108 [NAL9602](INFO): No messages in MT queue 2025-06-06T06:55:37.362Z,1749192937.362 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-06-06T06:55:37.362Z,1749192937.362 [DVL_micro] Communications Fault, FailCount= 1 2025-06-06T06:55:37.362Z,1749192937.362 [DVL_micro](ERROR): Communications Fault 2025-06-06T06:55:37.363Z,1749192937.363 [DVL_micro](ERROR): Failed to parse: 2025-06-06T06:55:37.455Z,1749192937.455 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-06-06T06:55:37.782Z,1749192937.782 [DVL_micro](INFO): Powering down 2025-06-06T06:55:38.571Z,1749192938.571 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-06-06T06:55:38.571Z,1749192938.571 [DVL_micro] No Fault, FailCount= 1 2025-06-06T06:55:47.962Z,1749192947.962 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250606T064225/Courier0021.lzma 2025-06-06T06:55:48.965Z,1749192948.965 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Courier0021.lzma.bak 2025-06-06T06:55:48.965Z,1749192948.965 [DataOverHttps](INFO): SBD MOMSN=25110848 2025-06-06T06:55:56.281Z,1749192956.281 [DVL_micro](ERROR): Failed to parse: :WI,+00012,-00116,+00170,+00000,A 2025-06-06T06:55:58.186Z,1749192958.186 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 7 hour 2025-06-06T06:55:58.195Z,1749192958.195 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute 2025-06-06T06:55:58.196Z,1749192958.196 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.81245 degree 2025-06-06T06:55:58.196Z,1749192958.196 [CommandExec](IMPORTANT): got command set keepstation.Longitude -122.06257 degree 2025-06-06T06:55:58.197Z,1749192958.197 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter 2025-06-06T06:56:04.756Z,1749192964.756 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T06:56:12.102Z,1749192972.102 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250606T064225/Express0016.lzma 2025-06-06T06:56:13.104Z,1749192973.104 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0016.lzma.bak 2025-06-06T06:56:13.104Z,1749192973.104 [DataOverHttps](INFO): SBD MOMSN=25110851 2025-06-06T06:56:38.895Z,1749192998.895 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250606T064225/Express0019.lzma 2025-06-06T06:56:39.896Z,1749192999.896 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0019.lzma.bak 2025-06-06T06:56:39.896Z,1749192999.896 [DataOverHttps](INFO): SBD MOMSN=25110853 2025-06-06T06:56:42.479Z,1749193002.479 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool 2025-06-06T06:56:42.480Z,1749193002.480 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter 2025-06-06T06:56:42.480Z,1749193002.480 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second 2025-06-06T06:56:42.481Z,1749193002.481 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute 2025-06-06T06:56:42.482Z,1749193002.482 [CommandExec](IMPORTANT): got command run 2025-06-06T06:56:42.496Z,1749193002.496 [CommandExec](IMPORTANT): Running 2025-06-06T06:56:42.828Z,1749193002.828 [Default] Stopped 2025-06-06T06:56:42.828Z,1749193002.828 [Default](DEBUG): Aggregate::uninitialize Default 2025-06-06T06:56:42.828Z,1749193002.828 [Default:B.GoToSurface] Stopped 2025-06-06T06:56:42.828Z,1749193002.828 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T06:56:42.828Z,1749193002.828 [Default:CheckIn] Stopped 2025-06-06T06:56:42.828Z,1749193002.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-06T06:56:42.828Z,1749193002.828 [Default:CheckIn:Read_Iridium] Stopped 2025-06-06T06:56:42.828Z,1749193002.828 [MissionManager](IMPORTANT): Started mission keepstation 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation] Running Loop=1 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation:StandardEnvelopes] Running Loop=1 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-06T06:56:42.829Z,1749193002.829 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:BackseatDriver] Running Loop=1 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:PowerOnly] Running Loop=1 2025-06-06T06:56:42.830Z,1749193002.830 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:PowerOnly:A] Running Loop=1 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:PowerOnly:B] Running Loop=1 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:PowerOnly:C] Running Loop=1 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:PowerOnly:D] Running Loop=1 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:H.Pitch] Running Loop=1 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:H.Pitch](DEBUG): Initialize. 2025-06-06T06:56:42.831Z,1749193002.831 [keepstation:B] Running Loop=1 2025-06-06T06:56:42.832Z,1749193002.832 [keepstation:H.Pitch] Running Loop=1 2025-06-06T06:56:42.832Z,1749193002.832 [keepstation:PowerOnly] Running Loop=1 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:PowerOnly:D] Stopped 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:PowerOnly:C] Stopped 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:PowerOnly:B] Stopped 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:PowerOnly:A] Stopped 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:BackseatDriver] Running Loop=1 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:StandardEnvelopes] Running Loop=1 2025-06-06T06:56:42.833Z,1749193002.833 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-06T06:56:42.834Z,1749193002.834 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-06T06:56:42.834Z,1749193002.834 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-06T06:56:42.835Z,1749193002.835 [keepstation:B] Stopped 2025-06-06T06:56:42.835Z,1749193002.835 [keepstation:D] Running Loop=1 2025-06-06T06:56:43.180Z,1749193003.180 [keepstation:D] Stopped 2025-06-06T06:56:43.180Z,1749193003.180 [keepstation:E] Running Loop=1 2025-06-06T06:56:43.620Z,1749193003.620 [keepstation:E] Stopped 2025-06-06T06:56:43.620Z,1749193003.620 [keepstation:StartingMission] Running Loop=1 2025-06-06T06:56:43.621Z,1749193003.621 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-06-06T06:56:44.037Z,1749193004.037 [keepstation:SurfaceComms] Running Loop=1 2025-06-06T06:56:44.037Z,1749193004.037 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-06T06:56:44.037Z,1749193004.037 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-06T06:56:44.037Z,1749193004.037 [keepstation:SurfaceComms:A] Stopped 2025-06-06T06:56:44.037Z,1749193004.037 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-06T06:56:44.037Z,1749193004.037 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-06T06:56:44.463Z,1749193004.463 [keepstation:NeedComms] Running Loop=1 2025-06-06T06:56:44.464Z,1749193004.464 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-06T06:56:44.464Z,1749193004.464 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-06T06:56:44.464Z,1749193004.464 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-06T06:56:44.464Z,1749193004.464 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-06T06:56:44.464Z,1749193004.464 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-06T06:56:44.465Z,1749193004.465 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-06T06:56:44.465Z,1749193004.465 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-06T06:56:44.465Z,1749193004.465 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-06T06:56:44.466Z,1749193004.466 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-06T06:56:44.474Z,1749193004.474 [keepstation:NeedComms:A] Running Loop=1 2025-06-06T06:56:44.476Z,1749193004.476 [keepstation:NeedComms:A](INFO): last time_fix was: 1749192898.000000 second since 1970/01/01T00:00:00Z 2025-06-06T06:56:44.476Z,1749193004.476 [keepstation:NeedComms:A] Stopped 2025-06-06T06:56:44.837Z,1749193004.837 [keepstation:NeedComms:C] Running Loop=1 2025-06-06T06:56:45.229Z,1749193005.229 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-06T06:56:46.816Z,1749193006.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065646.00,A,3645.93902,N,12155.38934,W,0.952,202.43,060625,,,D*72 2025-06-06T06:56:46.823Z,1749193006.823 [NAL9602](INFO): GPS fix at 20250606T065646: (36.765650, -121.923156) 2025-06-06T06:56:46.848Z,1749193006.848 [keepstation:NeedComms:C] Stopped 2025-06-06T06:56:46.848Z,1749193006.848 [keepstation:NeedComms:D] Running Loop=1 2025-06-06T06:56:47.239Z,1749193007.239 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-06T06:56:56.883Z,1749193016.883 [DataOverHttps](INFO): Sending 350 bytes from file Logs/20250606T064225/Express0022.lzma 2025-06-06T06:56:57.884Z,1749193017.884 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0022.lzma.bak 2025-06-06T06:56:57.885Z,1749193017.885 [DataOverHttps](INFO): SBD MOMSN=25110864 2025-06-06T06:57:14.178Z,1749193034.178 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250606T064225/Courier0024.lzma 2025-06-06T06:57:15.099Z,1749193035.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=21085, MT Status=2, MTMSN=0 2025-06-06T06:57:15.099Z,1749193035.099 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T06:57:15.180Z,1749193035.180 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Courier0024.lzma.bak 2025-06-06T06:57:15.180Z,1749193035.180 [DataOverHttps](INFO): SBD MOMSN=25110871 2025-06-06T06:57:31.760Z,1749193051.760 [DataOverHttps](INFO): Sending 430 bytes from file Logs/20250606T064225/Express0025.lzma 2025-06-06T06:57:32.760Z,1749193052.760 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0025.lzma.bak 2025-06-06T06:57:32.760Z,1749193052.760 [DataOverHttps](INFO): SBD MOMSN=25110874 2025-06-06T06:57:33.312Z,1749193053.312 [NAL9602](INFO): SBD MO Status=2, MOMSN=21085, MT Status=2, MTMSN=0 2025-06-06T06:57:33.312Z,1749193053.312 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T06:57:34.538Z,1749193054.538 [keepstation:NeedComms:D] Stopped 2025-06-06T06:57:34.538Z,1749193054.538 [keepstation:NeedComms:E] Running Loop=1 2025-06-06T06:57:34.960Z,1749193054.960 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-06T06:57:41.770Z,1749193061.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=21085, MT Status=0, MTMSN=0 2025-06-06T06:57:41.771Z,1749193061.771 [NAL9602](INFO): No messages in MT queue 2025-06-06T06:57:42.973Z,1749193062.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065742.00,A,3645.93738,N,12155.38774,W,0.019,167.85,060625,,,D*76 2025-06-06T06:57:42.976Z,1749193062.976 [NAL9602](INFO): GPS fix at 20250606T065742: (36.765623, -121.923129) 2025-06-06T06:57:43.009Z,1749193063.009 [keepstation:NeedComms:E] Stopped 2025-06-06T06:57:43.010Z,1749193063.010 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-06T06:57:43.010Z,1749193063.010 [keepstation:NeedComms] Stopped 2025-06-06T06:57:43.010Z,1749193063.010 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-06T06:57:43.010Z,1749193063.010 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-06T06:57:43.010Z,1749193063.010 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T06:57:43.415Z,1749193063.415 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-06T06:57:43.415Z,1749193063.415 [keepstation:SurfaceComms:B] Stopped 2025-06-06T06:57:43.415Z,1749193063.415 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-06T06:57:43.415Z,1749193063.415 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-06T06:57:43.415Z,1749193063.415 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-06T06:57:43.415Z,1749193063.415 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-06T06:57:43.808Z,1749193063.808 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-06T06:57:43.808Z,1749193063.808 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-06T06:57:43.808Z,1749193063.808 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-06T06:57:43.808Z,1749193063.808 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-06T06:57:43.808Z,1749193063.808 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-06T06:57:43.808Z,1749193063.808 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-06T06:57:44.208Z,1749193064.208 [keepstation:SurfaceComms:setStation] Stopped 2025-06-06T06:57:44.208Z,1749193064.208 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-06T06:57:44.208Z,1749193064.208 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-06T06:57:44.208Z,1749193064.208 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-06T06:57:44.208Z,1749193064.208 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-06T06:57:44.209Z,1749193064.209 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-06T06:57:44.600Z,1749193064.600 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-06T06:57:44.600Z,1749193064.600 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-06T06:57:44.601Z,1749193064.601 [keepstation:SurfaceComms:setSink] Stopped 2025-06-06T06:57:44.601Z,1749193064.601 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-06T06:57:44.601Z,1749193064.601 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-06T06:57:44.601Z,1749193064.601 [keepstation:SurfaceComms] Stopped 2025-06-06T06:57:44.601Z,1749193064.601 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-06T06:57:45.013Z,1749193065.013 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:StartingMission] Stopped 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:TransitToStation] Running Loop=1 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-06T06:57:45.014Z,1749193065.014 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-06T06:57:45.015Z,1749193065.015 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-06T06:57:45.015Z,1749193065.015 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-06-06T06:57:45.015Z,1749193065.015 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-06-06T06:57:45.016Z,1749193065.016 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.812450,-122.062569 2025-06-06T06:57:45.415Z,1749193065.415 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.812450,-122.062569 2025-06-06T06:57:45.416Z,1749193065.416 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T06:57:45.416Z,1749193065.416 [keepstation:DiveCmd] Running Loop=1 2025-06-06T06:57:45.416Z,1749193065.416 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-06-06T06:57:45.416Z,1749193065.416 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T06:57:45.416Z,1749193065.416 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T06:57:45.417Z,1749193065.417 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T06:57:45.418Z,1749193065.418 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-06T06:57:45.419Z,1749193065.419 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-06T06:57:45.420Z,1749193065.420 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T06:57:45.421Z,1749193065.421 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-06T06:57:45.422Z,1749193065.422 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-06T06:57:45.422Z,1749193065.422 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-06T06:57:45.422Z,1749193065.422 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-06T06:57:45.422Z,1749193065.422 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T06:57:45.422Z,1749193065.422 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T06:58:15.743Z,1749193095.743 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T07:00:07.402Z,1749193207.402 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T07:00:38.426Z,1749193238.426 [Radio_Surface](INFO): Powering down 2025-06-06T07:00:38.527Z,1749193238.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T07:00:39.531Z,1749193239.531 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-06T07:02:14.935Z,1749193334.935 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.751282 2025-06-06T07:02:34.300Z,1749193354.300 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-06T07:02:34.300Z,1749193354.300 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-06T07:02:34.300Z,1749193354.300 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T07:02:34.300Z,1749193354.300 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T07:02:34.680Z,1749193354.680 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-06T07:02:35.081Z,1749193355.081 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T07:02:35.081Z,1749193355.081 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T07:02:35.081Z,1749193355.081 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T07:02:35.082Z,1749193355.082 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T07:02:35.083Z,1749193355.083 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T07:02:35.083Z,1749193355.083 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T07:02:35.083Z,1749193355.083 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T07:02:35.083Z,1749193355.083 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T07:02:35.084Z,1749193355.084 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-06T07:02:35.484Z,1749193355.484 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position 31.28 mm (1 active estimators). 2025-06-06T07:02:40.325Z,1749193360.325 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-06-06T07:03:40.525Z,1749193420.525 [DVL_micro](ERROR): Failed to parse: :9.99,000.00 2025-06-06T07:04:53.726Z,1749193493.726 [ElevatorServo](FAULT): Overload Error 2025-06-06T07:04:53.726Z,1749193493.726 [ElevatorServo] Hardware Fault, FailCount= 1 2025-06-06T07:04:53.727Z,1749193493.727 [ElevatorServo](ERROR): Hardware Fault 2025-06-06T07:04:53.907Z,1749193493.907 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-06-06T07:04:54.093Z,1749193494.093 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-06-06T07:04:54.093Z,1749193494.093 [ElevatorServo](INFO): Powering down 2025-06-06T07:04:54.918Z,1749193494.918 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-06-06T07:04:55.039Z,1749193495.039 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-06-06T07:04:55.057Z,1749193495.057 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-06-06T07:04:55.057Z,1749193495.057 [ElevatorServo] No Fault, FailCount= 1 2025-06-06T07:16:59.144Z,1749194219.144 [DVL_micro](ERROR): Failed to parse: :SA,-03.49,+01.88,276.5 2025-06-06T07:28:58.786Z,1749194938.786 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-06-06T07:28:58.786Z,1749194938.786 [DVL_micro] Communications Fault, FailCount= 1 2025-06-06T07:28:58.786Z,1749194938.786 [DVL_micro](ERROR): Communications Fault 2025-06-06T07:28:58.787Z,1749194938.787 [DVL_micro](ERROR): Failed to parse: 2025-06-06T07:28:58.906Z,1749194938.906 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-06-06T07:28:59.210Z,1749194939.210 [DVL_micro](INFO): Powering down 2025-06-06T07:29:00.046Z,1749194940.046 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-06-06T07:29:00.046Z,1749194940.046 [DVL_micro] No Fault, FailCount= 1 2025-06-06T07:49:54.557Z,1749196194.557 [DVL_micro](ERROR): Failed to parse: :WI,+00042,+00616,-00004,+00000,A 2025-06-06T07:49:58.611Z,1749196198.611 [DVL_micro](ERROR): Failed to parse: :WI,+00008,+00539,+00044,+00000,A 2025-06-06T07:50:00.633Z,1749196200.633 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-06-06T07:50:02.657Z,1749196202.657 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-06-06T07:53:27.882Z,1749196407.882 [DVL_micro](ERROR): Failed to parse: :SA,+02.31,+00.56,275.2 2025-06-06T07:57:43.020Z,1749196663.020 [keepstation:SurfaceComms] Running Loop=1 2025-06-06T07:57:43.020Z,1749196663.020 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-06T07:57:43.020Z,1749196663.020 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-06T07:57:43.021Z,1749196663.021 [keepstation:SurfaceComms:A] Stopped 2025-06-06T07:57:43.021Z,1749196663.021 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-06T07:57:43.021Z,1749196663.021 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-06T07:57:43.397Z,1749196663.397 [keepstation:TransitToStation:Dive] Stopped 2025-06-06T07:57:43.397Z,1749196663.397 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-06T07:57:43.397Z,1749196663.397 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-06T07:57:43.397Z,1749196663.397 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-06T07:57:43.397Z,1749196663.397 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-06T07:57:43.397Z,1749196663.397 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-06T07:57:43.398Z,1749196663.398 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-06T07:57:43.400Z,1749196663.400 [keepstation:NeedComms] Running Loop=1 2025-06-06T07:57:43.400Z,1749196663.400 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-06T07:57:43.400Z,1749196663.400 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-06T07:57:43.400Z,1749196663.400 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-06T07:57:43.401Z,1749196663.401 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-06T07:57:43.401Z,1749196663.401 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-06T07:57:43.401Z,1749196663.401 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-06T07:57:43.402Z,1749196663.402 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-06T07:57:43.402Z,1749196663.402 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-06T07:57:43.403Z,1749196663.403 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-06T07:57:43.403Z,1749196663.403 [keepstation:NeedComms:A] Running Loop=1 2025-06-06T07:57:43.404Z,1749196663.404 [keepstation:NeedComms:A](INFO): last time_fix was: 1749193062.000000 second since 1970/01/01T00:00:00Z 2025-06-06T07:57:43.405Z,1749196663.405 [keepstation:NeedComms:A] Stopped 2025-06-06T07:58:11.019Z,1749196691.019 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-06-06T07:58:37.361Z,1749196717.361 [keepstation:NeedComms:C] Running Loop=1 2025-06-06T07:58:37.950Z,1749196717.950 [Radio_Surface](INFO): Powering up 2025-06-06T07:58:44.035Z,1749196724.035 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-06T07:59:14.155Z,1749196754.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T07:59:35.915Z,1749196775.915 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-06T07:59:35.916Z,1749196775.916 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-06-06T07:59:35.920Z,1749196775.920 [BPC1](INFO): Received data from all battery sticks. 2025-06-06T07:59:45.279Z,1749196785.279 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T08:00:01.774Z,1749196801.774 [NAL9602](INFO): SBD MO Status=2, MOMSN=21086, MT Status=2, MTMSN=0 2025-06-06T08:00:01.774Z,1749196801.774 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T08:00:14.303Z,1749196814.303 [NAL9602](INFO): SBD MO Status=0, MOMSN=21086, MT Status=0, MTMSN=0 2025-06-06T08:00:14.304Z,1749196814.304 [NAL9602](INFO): No messages in MT queue 2025-06-06T08:00:15.495Z,1749196815.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080014.00,A,3646.42261,N,12156.39369,W,1.205,227.87,060625,,,A*7B 2025-06-06T08:00:15.497Z,1749196815.497 [NAL9602](INFO): GPS fix at 20250606T080014: (36.773710, -121.939895) 2025-06-06T08:00:15.510Z,1749196815.510 [UniversalFixResidualReporter](INFO): Fix residual: 30.3 %DT, over the last 2486.0 m. Residual distance 752.5 m at bearing -88.3 degrees. Fix at (36.7737, -121.9399) with 1743.2 m made good. 2025-06-06T08:00:15.513Z,1749196815.513 [keepstation:NeedComms:C] Stopped 2025-06-06T08:00:15.514Z,1749196815.514 [keepstation:NeedComms:D] Running Loop=1 2025-06-06T08:00:16.403Z,1749196816.402 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T08:00:34.580Z,1749196834.580 [NAL9602](INFO): SBD MO Status=1, MOMSN=21087, MT Status=0, MTMSN=0 2025-06-06T08:00:34.634Z,1749196834.634 [NAL9602](INFO): Sent 98 bytes from file Logs/20250606T064225/Courier0027.lzma 2025-06-06T08:00:34.635Z,1749196834.635 [NAL9602](INFO): Packets left to send: 0 2025-06-06T08:00:47.539Z,1749196847.539 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T08:00:52.195Z,1749196852.195 [NAL9602](INFO): SBD MO Status=1, MOMSN=21088, MT Status=0, MTMSN=0 2025-06-06T08:00:52.260Z,1749196852.260 [NAL9602](INFO): Sent 332 bytes from file Logs/20250606T064225/Express0028.lzma 2025-06-06T08:00:52.260Z,1749196852.260 [NAL9602](INFO): Packets left to send: 1 2025-06-06T08:00:56.639Z,1749196856.639 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003248 2025-06-06T08:01:04.575Z,1749196864.575 [DataOverHttps](INFO): Sending 644 bytes from file Logs/20250606T064225/Express0028.lzma 2025-06-06T08:01:05.576Z,1749196865.576 [DataOverHttps](INFO): Moved sent file to Logs/20250606T064225/Express0028.lzma.bak 2025-06-06T08:01:05.576Z,1749196865.576 [DataOverHttps](INFO): SBD MOMSN=25110963 2025-06-06T08:01:07.082Z,1749196867.082 [keepstation:NeedComms:D] Stopped 2025-06-06T08:01:07.082Z,1749196867.082 [keepstation:NeedComms:E] Running Loop=1 2025-06-06T08:01:10.689Z,1749196870.689 [NAL9602](INFO): SBD MO Status=1, MOMSN=21089, MT Status=0, MTMSN=0 2025-06-06T08:01:10.735Z,1749196870.735 [NAL9602](INFO): Sent 312 bytes from file Logs/20250606T064225/Express0028.lzma 2025-06-06T08:01:10.735Z,1749196870.735 [NAL9602](INFO): Packets left to send: 0 2025-06-06T08:01:11.898Z,1749196871.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080111.00,A,3646.41934,N,12156.39155,W,0.719,155.84,060625,,,A*76 2025-06-06T08:01:11.900Z,1749196871.900 [NAL9602](INFO): GPS fix at 20250606T080111: (36.773656, -121.939859) 2025-06-06T08:01:11.957Z,1749196871.957 [keepstation:NeedComms:E] Stopped 2025-06-06T08:01:11.963Z,1749196871.963 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-06T08:01:11.963Z,1749196871.963 [keepstation:NeedComms] Stopped 2025-06-06T08:01:11.963Z,1749196871.963 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-06T08:01:11.963Z,1749196871.963 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-06T08:01:11.963Z,1749196871.963 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T08:01:12.384Z,1749196872.384 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-06T08:01:12.384Z,1749196872.384 [keepstation:SurfaceComms:B] Stopped 2025-06-06T08:01:12.384Z,1749196872.384 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-06T08:01:12.384Z,1749196872.384 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-06T08:01:12.384Z,1749196872.384 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-06T08:01:12.384Z,1749196872.384 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-06T08:01:12.735Z,1749196872.735 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-06T08:01:12.735Z,1749196872.735 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-06T08:01:12.735Z,1749196872.735 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-06T08:01:12.735Z,1749196872.735 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-06T08:01:12.735Z,1749196872.735 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-06T08:01:12.735Z,1749196872.735 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-06T08:01:13.117Z,1749196873.117 [keepstation:SurfaceComms:setStation] Stopped 2025-06-06T08:01:13.118Z,1749196873.118 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-06T08:01:13.118Z,1749196873.118 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-06T08:01:13.118Z,1749196873.118 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-06T08:01:13.118Z,1749196873.118 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-06T08:01:13.119Z,1749196873.119 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-06T08:01:13.524Z,1749196873.524 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-06T08:01:13.524Z,1749196873.524 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-06T08:01:13.524Z,1749196873.524 [keepstation:SurfaceComms:setSink] Stopped 2025-06-06T08:01:13.524Z,1749196873.524 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-06T08:01:13.524Z,1749196873.524 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-06T08:01:13.524Z,1749196873.524 [keepstation:SurfaceComms] Stopped 2025-06-06T08:01:13.525Z,1749196873.525 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-06T08:01:13.941Z,1749196873.941 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T08:01:13.941Z,1749196873.941 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-06T08:01:13.941Z,1749196873.941 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-06T08:01:13.942Z,1749196873.942 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-06T08:01:13.942Z,1749196873.942 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T08:01:13.942Z,1749196873.942 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-06T08:01:13.943Z,1749196873.943 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-06T08:01:13.944Z,1749196873.944 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-06T08:01:13.944Z,1749196873.944 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-06T08:01:13.944Z,1749196873.944 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-06T08:01:13.944Z,1749196873.944 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-06T08:01:44.642Z,1749196904.642 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T08:03:07.915Z,1749196987.915 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T08:03:39.047Z,1749197019.047 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T08:03:51.862Z,1749197031.862 [Radio_Surface](INFO): Powering down 2025-06-06T08:04:04.442Z,1749197044.442 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000069,+00014,+00000,I 2025-06-06T08:04:04.442Z,1749197044.442 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000069,+00014,+00000,I 2025-06-06T08:04:10.179Z,1749197050.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T08:04:11.183Z,1749197051.183 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-06T08:05:16.479Z,1749197116.479 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.783325 2025-06-06T08:05:45.439Z,1749197145.439 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-06T08:05:45.439Z,1749197145.439 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-06T08:05:45.439Z,1749197145.439 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T08:05:45.439Z,1749197145.439 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T08:05:45.853Z,1749197145.853 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-06T08:05:46.252Z,1749197146.252 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T08:05:46.252Z,1749197146.252 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T08:05:46.252Z,1749197146.252 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T08:05:46.252Z,1749197146.252 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T08:05:46.253Z,1749197146.253 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T08:05:46.253Z,1749197146.253 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T08:05:46.253Z,1749197146.253 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T08:05:46.253Z,1749197146.253 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T08:05:46.253Z,1749197146.253 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T08:05:46.253Z,1749197146.253 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T08:05:46.254Z,1749197146.254 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-06T08:05:46.254Z,1749197146.254 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-06T08:05:46.254Z,1749197146.254 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-06T08:05:46.254Z,1749197146.254 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T08:05:46.255Z,1749197146.255 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-06T08:05:46.646Z,1749197146.646 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 31.28 mm (2 active estimators). 2025-06-06T08:17:54.049Z,1749197874.049 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,0000000000040,+00000,A 2025-06-06T08:17:54.049Z,1749197874.049 [DVL_micro](ERROR): Failed to parse: :TS,0000000000040,+00000,A 2025-06-06T08:21:42.438Z,1749198102.438 [DVL_micro](ERROR): Failed to parse: 00.00,9999.99,000.00 2025-06-06T08:21:44.415Z,1749198104.415 [DVL_micro](ERROR): Failed to parse: :SA,+02.25,-00.45,290.6 2025-06-06T08:21:46.427Z,1749198106.427 [DVL_micro](ERROR): Failed to parse: :SA,+01.62,-00.75,290.1 2025-06-06T08:53:07.893Z,1749199987.893 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,348,+00411,+00090,+00000,I 2025-06-06T08:53:07.893Z,1749199987.893 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,348,+00411,+00090,+00000,I 2025-06-06T09:01:11.953Z,1749200471.953 [keepstation:SurfaceComms] Running Loop=1 2025-06-06T09:01:11.953Z,1749200471.953 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-06T09:01:11.953Z,1749200471.953 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-06T09:01:11.954Z,1749200471.954 [keepstation:SurfaceComms:A] Stopped 2025-06-06T09:01:11.954Z,1749200471.954 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-06T09:01:11.954Z,1749200471.954 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-06T09:01:12.343Z,1749200472.343 [keepstation:TransitToStation:Dive] Stopped 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T09:01:12.344Z,1749200472.344 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-06T09:01:12.345Z,1749200472.345 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T09:01:12.345Z,1749200472.345 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-06T09:01:12.345Z,1749200472.345 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-06T09:01:12.345Z,1749200472.345 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-06T09:01:12.347Z,1749200472.347 [keepstation:NeedComms] Running Loop=1 2025-06-06T09:01:12.347Z,1749200472.347 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-06T09:01:12.347Z,1749200472.347 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-06T09:01:12.347Z,1749200472.347 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-06T09:01:12.347Z,1749200472.347 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-06T09:01:12.348Z,1749200472.348 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-06T09:01:12.348Z,1749200472.348 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-06T09:01:12.348Z,1749200472.348 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-06T09:01:12.349Z,1749200472.349 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-06T09:01:12.349Z,1749200472.349 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-06T09:01:12.349Z,1749200472.349 [keepstation:NeedComms:A] Running Loop=1 2025-06-06T09:01:12.351Z,1749200472.351 [keepstation:NeedComms:A](INFO): last time_fix was: 1749196871.000000 second since 1970/01/01T00:00:00Z 2025-06-06T09:01:12.351Z,1749200472.351 [keepstation:NeedComms:A] Stopped 2025-06-06T09:02:13.726Z,1749200533.726 [keepstation:NeedComms:C] Running Loop=1 2025-06-06T09:02:14.286Z,1749200534.286 [Radio_Surface](INFO): Powering up 2025-06-06T09:02:19.786Z,1749200539.786 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-06T09:02:26.672Z,1749200546.672 [NAL9602](INFO): SBD MO Status=0, MOMSN=21090, MT Status=0, MTMSN=0 2025-06-06T09:02:26.672Z,1749200546.672 [NAL9602](INFO): No messages in MT queue 2025-06-06T09:02:27.881Z,1749200547.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090227.00,A,3646.50499,N,12157.37912,W,0.875,0.00,060625,,,A*77 2025-06-06T09:02:27.884Z,1749200547.884 [NAL9602](INFO): GPS fix at 20250606T090227: (36.775083, -121.956319) 2025-06-06T09:02:27.912Z,1749200547.912 [UniversalFixResidualReporter](INFO): Fix residual: 23.8 %DT, over the last 2779.2 m. Residual distance 662.2 m at bearing -54.0 degrees. Fix at (36.7751, -121.9563) with 1474.6 m made good. 2025-06-06T09:02:27.915Z,1749200547.915 [keepstation:NeedComms:C] Stopped 2025-06-06T09:02:27.916Z,1749200547.916 [keepstation:NeedComms:D] Running Loop=1 2025-06-06T09:02:44.849Z,1749200564.849 [NAL9602](INFO): SBD MO Status=1, MOMSN=21091, MT Status=0, MTMSN=0 2025-06-06T09:02:44.902Z,1749200564.902 [NAL9602](INFO): Sent 85 bytes from file Logs/20250606T064225/Courier0030.lzma 2025-06-06T09:02:44.903Z,1749200564.903 [NAL9602](INFO): Packets left to send: 0 2025-06-06T09:02:49.907Z,1749200569.907 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:02:58.733Z,1749200578.733 [NAL9602](INFO): SBD MO Status=1, MOMSN=21092, MT Status=0, MTMSN=0 2025-06-06T09:02:58.782Z,1749200578.782 [NAL9602](INFO): Sent 332 bytes from file Logs/20250606T064225/Express0031.lzma 2025-06-06T09:02:58.783Z,1749200578.783 [NAL9602](INFO): Packets left to send: 1 2025-06-06T09:03:11.821Z,1749200591.821 [NAL9602](INFO): SBD MO Status=1, MOMSN=21093, MT Status=0, MTMSN=0 2025-06-06T09:03:11.878Z,1749200591.878 [NAL9602](INFO): Sent 191 bytes from file Logs/20250606T064225/Express0031.lzma 2025-06-06T09:03:11.879Z,1749200591.879 [NAL9602](INFO): Packets left to send: 0 2025-06-06T09:03:21.031Z,1749200601.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:03:21.529Z,1749200601.529 [NAL9602](INFO): SBD MO Status=0, MOMSN=21094, MT Status=0, MTMSN=0 2025-06-06T09:03:21.620Z,1749200601.620 [keepstation:NeedComms:D] Stopped 2025-06-06T09:03:21.620Z,1749200601.620 [keepstation:NeedComms:E] Running Loop=1 2025-06-06T09:03:23.972Z,1749200603.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090323.00,A,3646.50236,N,12157.38281,W,0.680,50.28,060625,,,A*44 2025-06-06T09:03:23.975Z,1749200603.975 [NAL9602](INFO): GPS fix at 20250606T090323: (36.775039, -121.956380) 2025-06-06T09:03:23.988Z,1749200603.988 [keepstation:NeedComms:E] Stopped 2025-06-06T09:03:23.989Z,1749200603.989 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-06T09:03:23.989Z,1749200603.989 [keepstation:NeedComms] Stopped 2025-06-06T09:03:23.990Z,1749200603.990 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-06T09:03:23.990Z,1749200603.990 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-06T09:03:23.990Z,1749200603.990 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T09:03:24.381Z,1749200604.381 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-06T09:03:24.381Z,1749200604.381 [keepstation:SurfaceComms:B] Stopped 2025-06-06T09:03:24.381Z,1749200604.381 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-06T09:03:24.381Z,1749200604.381 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-06T09:03:24.381Z,1749200604.381 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-06T09:03:24.381Z,1749200604.381 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-06T09:03:24.763Z,1749200604.763 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-06T09:03:24.763Z,1749200604.763 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-06T09:03:24.763Z,1749200604.763 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-06T09:03:24.763Z,1749200604.763 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-06T09:03:24.764Z,1749200604.764 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-06T09:03:24.764Z,1749200604.764 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-06T09:03:25.186Z,1749200605.186 [keepstation:SurfaceComms:setStation] Stopped 2025-06-06T09:03:25.187Z,1749200605.187 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-06T09:03:25.187Z,1749200605.187 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-06T09:03:25.187Z,1749200605.187 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-06T09:03:25.187Z,1749200605.187 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-06T09:03:25.187Z,1749200605.187 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-06T09:03:25.592Z,1749200605.592 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-06T09:03:25.593Z,1749200605.593 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-06T09:03:25.593Z,1749200605.593 [keepstation:SurfaceComms:setSink] Stopped 2025-06-06T09:03:25.593Z,1749200605.593 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-06T09:03:25.593Z,1749200605.593 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-06T09:03:25.593Z,1749200605.593 [keepstation:SurfaceComms] Stopped 2025-06-06T09:03:25.593Z,1749200605.593 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-06T09:03:26.000Z,1749200606.000 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T09:03:26.000Z,1749200606.000 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-06T09:03:26.000Z,1749200606.000 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-06T09:03:26.000Z,1749200606.000 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-06T09:03:26.001Z,1749200606.001 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T09:03:26.001Z,1749200606.001 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-06T09:03:26.001Z,1749200606.001 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-06T09:03:26.001Z,1749200606.001 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T09:03:26.001Z,1749200606.001 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T09:03:26.001Z,1749200606.001 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T09:03:26.002Z,1749200606.002 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T09:03:26.002Z,1749200606.002 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-06T09:03:26.002Z,1749200606.002 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-06T09:03:26.007Z,1749200606.007 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-06T09:03:26.007Z,1749200606.007 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-06T09:03:26.007Z,1749200606.007 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-06T09:03:26.007Z,1749200606.007 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-06T09:03:52.155Z,1749200632.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:03:56.680Z,1749200636.680 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T09:04:23.307Z,1749200663.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:04:32.095Z,1749200672.095 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003454 2025-06-06T09:05:25.331Z,1749200725.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:05:56.467Z,1749200756.467 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:06:23.154Z,1749200783.154 [Radio_Surface](INFO): Powering down 2025-06-06T09:06:27.594Z,1749200787.594 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T09:06:28.598Z,1749200788.598 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-06T09:07:16.277Z,1749200836.277 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-06T09:07:16.279Z,1749200836.279 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-06T09:07:16.299Z,1749200836.299 [BPC1](INFO): Received data from all battery sticks. 2025-06-06T09:07:32.883Z,1749200852.883 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.791046 2025-06-06T09:07:48.192Z,1749200868.192 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-06T09:07:48.194Z,1749200868.194 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-06T09:07:48.197Z,1749200868.197 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T09:07:48.197Z,1749200868.197 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-06T09:07:48.599Z,1749200868.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-06T09:07:48.599Z,1749200868.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T09:07:48.599Z,1749200868.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-06T09:07:48.599Z,1749200868.599 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T09:07:48.600Z,1749200868.600 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-06T09:07:48.600Z,1749200868.600 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T09:07:48.600Z,1749200868.600 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-06T09:07:48.975Z,1749200868.975 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T09:07:48.975Z,1749200868.975 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T09:07:48.975Z,1749200868.975 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T09:07:48.976Z,1749200868.976 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T09:07:48.977Z,1749200868.977 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-06T09:07:48.977Z,1749200868.977 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-06T09:07:48.977Z,1749200868.977 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-06T09:07:48.977Z,1749200868.977 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T09:07:48.977Z,1749200868.977 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-06T09:07:48.978Z,1749200868.978 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T09:07:48.978Z,1749200868.978 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-06T09:07:48.978Z,1749200868.978 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-06T09:07:48.978Z,1749200868.978 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-06T09:07:48.978Z,1749200868.978 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T09:07:48.978Z,1749200868.978 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-06T09:26:08.347Z,1749201968.347 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00088,+00417,+00005,+00000RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-06-06T09:26:08.347Z,1749201968.347 [DVL_micro](ERROR): Failed to parse: :BI,-00088,+00417,+00005,+00000RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-06-06T09:54:57.114Z,1749203697.114 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-06-06T10:03:24.136Z,1749204204.136 [keepstation:SurfaceComms] Running Loop=1 2025-06-06T10:03:24.136Z,1749204204.136 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-06T10:03:24.136Z,1749204204.136 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-06T10:03:24.137Z,1749204204.137 [keepstation:SurfaceComms:A] Stopped 2025-06-06T10:03:24.137Z,1749204204.137 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-06T10:03:24.137Z,1749204204.137 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-06T10:03:24.540Z,1749204204.540 [keepstation:TransitToStation:Dive] Stopped 2025-06-06T10:03:24.540Z,1749204204.540 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-06T10:03:24.540Z,1749204204.540 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-06T10:03:24.540Z,1749204204.540 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-06T10:03:24.541Z,1749204204.541 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-06T10:03:24.543Z,1749204204.543 [keepstation:NeedComms] Running Loop=1 2025-06-06T10:03:24.543Z,1749204204.543 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-06T10:03:24.543Z,1749204204.543 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-06T10:03:24.544Z,1749204204.544 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-06T10:03:24.544Z,1749204204.544 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-06T10:03:24.544Z,1749204204.544 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-06T10:03:24.545Z,1749204204.545 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-06T10:03:24.545Z,1749204204.545 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-06T10:03:24.545Z,1749204204.545 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-06T10:03:24.546Z,1749204204.546 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-06T10:03:24.546Z,1749204204.546 [keepstation:NeedComms:A] Running Loop=1 2025-06-06T10:03:24.548Z,1749204204.548 [keepstation:NeedComms:A](INFO): last time_fix was: 1749200603.000000 second since 1970/01/01T00:00:00Z 2025-06-06T10:03:24.548Z,1749204204.548 [keepstation:NeedComms:A] Stopped 2025-06-06T10:04:22.116Z,1749204262.116 [keepstation:NeedComms:C] Running Loop=1 2025-06-06T10:04:22.518Z,1749204262.518 [Radio_Surface](INFO): Powering up 2025-06-06T10:04:28.111Z,1749204268.111 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-06T10:04:30.955Z,1749204270.955 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-06-06T10:04:58.239Z,1749204298.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:05:15.792Z,1749204315.792 [NAL9602](INFO): SBD MO Status=0, MOMSN=21095, MT Status=0, MTMSN=0 2025-06-06T10:05:15.792Z,1749204315.792 [NAL9602](INFO): No messages in MT queue 2025-06-06T10:05:17.060Z,1749204317.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,100516.00,A,3646.68179,N,12158.10896,W,0.272,37.47,060625,,,D*42 2025-06-06T10:05:17.067Z,1749204317.067 [NAL9602](INFO): GPS fix at 20250606T100516: (36.778030, -121.968483) 2025-06-06T10:05:17.085Z,1749204317.085 [UniversalFixResidualReporter](INFO): Fix residual: 53.2 %DT, over the last 2512.7 m. Residual distance 1337.5 m at bearing -55.5 degrees. Fix at (36.7780, -121.9685) with 1128.0 m made good. 2025-06-06T10:05:17.113Z,1749204317.113 [keepstation:NeedComms:C] Stopped 2025-06-06T10:05:17.113Z,1749204317.113 [keepstation:NeedComms:D] Running Loop=1 2025-06-06T10:05:29.363Z,1749204329.363 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:05:33.668Z,1749204333.668 [NAL9602](INFO): SBD MO Status=2, MOMSN=21096, MT Status=2, MTMSN=0 2025-06-06T10:05:33.669Z,1749204333.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T10:05:52.362Z,1749204352.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=21096, MT Status=0, MTMSN=0 2025-06-06T10:05:52.418Z,1749204352.418 [NAL9602](INFO): Sent 96 bytes from file Logs/20250606T064225/Courier0033.lzma 2025-06-06T10:05:52.419Z,1749204352.419 [NAL9602](INFO): Packets left to send: 0 2025-06-06T10:06:00.495Z,1749204360.495 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:06:07.018Z,1749204367.018 [NAL9602](INFO): SBD MO Status=1, MOMSN=21097, MT Status=0, MTMSN=0 2025-06-06T10:06:07.071Z,1749204367.071 [NAL9602](INFO): Sent 332 bytes from file Logs/20250606T064225/Express0034.lzma 2025-06-06T10:06:07.071Z,1749204367.071 [NAL9602](INFO): Packets left to send: 1 2025-06-06T10:06:18.021Z,1749204378.021 [NAL9602](INFO): SBD MO Status=1, MOMSN=21098, MT Status=0, MTMSN=0 2025-06-06T10:06:18.070Z,1749204378.070 [NAL9602](INFO): Sent 150 bytes from file Logs/20250606T064225/Express0034.lzma 2025-06-06T10:06:18.071Z,1749204378.071 [NAL9602](INFO): Packets left to send: 0 2025-06-06T10:06:31.618Z,1749204391.618 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:06:36.192Z,1749204396.192 [NAL9602](INFO): SBD MO Status=2, MOMSN=21099, MT Status=2, MTMSN=0 2025-06-06T10:06:36.192Z,1749204396.192 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T10:06:41.043Z,1749204401.043 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003267 2025-06-06T10:06:42.700Z,1749204402.700 [keepstation:NeedComms:D] Stopped 2025-06-06T10:06:42.700Z,1749204402.700 [keepstation:NeedComms:E] Running Loop=1 2025-06-06T10:06:49.561Z,1749204409.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=21099, MT Status=0, MTMSN=0 2025-06-06T10:06:50.740Z,1749204410.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,100649.00,A,3646.68286,N,12158.10200,W,0.428,93.91,060625,,,D*41 2025-06-06T10:06:50.743Z,1749204410.743 [NAL9602](INFO): GPS fix at 20250606T100649: (36.778048, -121.968367) 2025-06-06T10:06:50.756Z,1749204410.756 [keepstation:NeedComms:E] Stopped 2025-06-06T10:06:50.757Z,1749204410.757 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-06T10:06:50.757Z,1749204410.757 [keepstation:NeedComms] Stopped 2025-06-06T10:06:50.758Z,1749204410.758 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-06T10:06:50.758Z,1749204410.758 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-06T10:06:50.758Z,1749204410.758 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T10:06:51.158Z,1749204411.158 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-06T10:06:51.158Z,1749204411.158 [keepstation:SurfaceComms:B] Stopped 2025-06-06T10:06:51.158Z,1749204411.158 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-06T10:06:51.158Z,1749204411.158 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-06T10:06:51.158Z,1749204411.158 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-06T10:06:51.159Z,1749204411.159 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-06T10:06:51.551Z,1749204411.551 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-06T10:06:51.551Z,1749204411.551 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-06T10:06:51.552Z,1749204411.552 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-06T10:06:51.552Z,1749204411.552 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-06T10:06:51.552Z,1749204411.552 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-06T10:06:51.552Z,1749204411.552 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-06T10:06:51.954Z,1749204411.954 [keepstation:SurfaceComms:setStation] Stopped 2025-06-06T10:06:51.954Z,1749204411.954 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-06T10:06:51.954Z,1749204411.954 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-06T10:06:51.954Z,1749204411.954 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-06T10:06:51.954Z,1749204411.954 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-06T10:06:51.955Z,1749204411.955 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-06T10:06:52.361Z,1749204412.361 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-06T10:06:52.361Z,1749204412.361 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-06T10:06:52.361Z,1749204412.361 [keepstation:SurfaceComms:setSink] Stopped 2025-06-06T10:06:52.361Z,1749204412.361 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-06T10:06:52.361Z,1749204412.361 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-06T10:06:52.362Z,1749204412.362 [keepstation:SurfaceComms] Stopped 2025-06-06T10:06:52.362Z,1749204412.362 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-06T10:06:52.786Z,1749204412.786 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T10:06:52.787Z,1749204412.787 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-06T10:06:52.787Z,1749204412.787 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-06T10:06:52.787Z,1749204412.787 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-06T10:06:52.787Z,1749204412.787 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T10:06:52.787Z,1749204412.787 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-06T10:06:52.788Z,1749204412.788 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-06T10:06:52.789Z,1749204412.789 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-06T10:06:52.789Z,1749204412.789 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-06T10:06:52.789Z,1749204412.789 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-06T10:06:52.789Z,1749204412.789 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-06T10:07:23.482Z,1749204443.482 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T10:08:45.519Z,1749204525.519 [DVL_micro](ERROR): Failed to parse: :BI,-02246,-03101,-00038,+00000,A 2025-06-06T10:09:15.202Z,1749204555.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:09:44.911Z,1749204584.911 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-06-06T10:09:46.343Z,1749204586.343 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:09:59.146Z,1749204599.146 [Radio_Surface](INFO): Powering down 2025-06-06T10:10:17.468Z,1749204617.468 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T10:10:18.470Z,1749204618.470 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-06T10:11:22.755Z,1749204682.755 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.763718 2025-06-06T10:11:36.900Z,1749204696.900 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-06T10:11:36.901Z,1749204696.901 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-06T10:11:36.901Z,1749204696.901 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T10:11:36.901Z,1749204696.901 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-06T10:11:37.267Z,1749204697.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-06T10:11:37.267Z,1749204697.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T10:11:37.267Z,1749204697.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-06T10:11:37.267Z,1749204697.267 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T10:11:37.268Z,1749204697.268 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-06T10:11:37.268Z,1749204697.268 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T10:11:37.268Z,1749204697.268 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-06T10:11:37.685Z,1749204697.685 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T10:11:37.685Z,1749204697.685 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T10:11:37.685Z,1749204697.685 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T10:11:37.685Z,1749204697.685 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T10:11:37.686Z,1749204697.686 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T10:11:37.686Z,1749204697.686 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T10:11:37.686Z,1749204697.686 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T10:11:37.686Z,1749204697.686 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T10:11:37.687Z,1749204697.687 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T10:11:37.687Z,1749204697.687 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T10:11:37.688Z,1749204697.688 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-06T10:11:37.688Z,1749204697.688 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-06T10:11:37.688Z,1749204697.688 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T10:11:37.689Z,1749204697.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-06T10:13:39.806Z,1749204819.806 [ElevatorServo](FAULT): Overload Error 2025-06-06T10:13:39.806Z,1749204819.806 [ElevatorServo] Hardware Fault, FailCount= 1 2025-06-06T10:13:39.806Z,1749204819.806 [ElevatorServo](ERROR): Hardware Fault 2025-06-06T10:13:39.930Z,1749204819.930 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-06-06T10:13:40.140Z,1749204820.140 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-06-06T10:13:40.140Z,1749204820.140 [ElevatorServo](INFO): Powering down 2025-06-06T10:13:40.953Z,1749204820.953 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-06-06T10:13:41.070Z,1749204821.070 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-06-06T10:13:41.282Z,1749204821.282 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-06-06T10:13:41.282Z,1749204821.282 [ElevatorServo] No Fault, FailCount= 1 2025-06-06T10:14:53.652Z,1749204893.652 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-06T10:14:53.653Z,1749204893.653 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-06T10:14:53.656Z,1749204893.656 [BPC1](INFO): Received data from all battery sticks. 2025-06-06T10:32:45.261Z,1749205965.261 [DVL_micro](ERROR): Failed to parse: :SA,+02.18,+02.26,277.2 2025-06-06T10:40:01.578Z,1749206401.578 [DVL_micro](ERROR): Failed to parse: :SA,-03.02,+02.27,277.3 2025-06-06T10:40:03.585Z,1749206403.585 [DVL_micro](ERROR): Failed to parse: :SA,-03.18,+01.83,277.5 2025-06-06T11:06:50.845Z,1749208010.845 [keepstation:SurfaceComms] Running Loop=1 2025-06-06T11:06:50.845Z,1749208010.845 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-06T11:06:50.846Z,1749208010.846 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-06T11:06:50.846Z,1749208010.846 [keepstation:SurfaceComms:A] Stopped 2025-06-06T11:06:50.846Z,1749208010.846 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-06T11:06:50.847Z,1749208010.847 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-06T11:06:51.251Z,1749208011.251 [keepstation:TransitToStation:Dive] Stopped 2025-06-06T11:06:51.251Z,1749208011.251 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-06T11:06:51.251Z,1749208011.251 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-06T11:06:51.251Z,1749208011.251 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-06T11:06:51.252Z,1749208011.252 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-06T11:06:51.254Z,1749208011.254 [keepstation:NeedComms] Running Loop=1 2025-06-06T11:06:51.254Z,1749208011.254 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-06T11:06:51.254Z,1749208011.254 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-06T11:06:51.254Z,1749208011.254 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-06T11:06:51.255Z,1749208011.255 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-06T11:06:51.255Z,1749208011.255 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-06T11:06:51.256Z,1749208011.256 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-06T11:06:51.256Z,1749208011.256 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-06T11:06:51.256Z,1749208011.256 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-06T11:06:51.257Z,1749208011.257 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-06T11:06:51.257Z,1749208011.257 [keepstation:NeedComms:A] Running Loop=1 2025-06-06T11:06:51.259Z,1749208011.259 [keepstation:NeedComms:A](INFO): last time_fix was: 1749204409.000000 second since 1970/01/01T00:00:00Z 2025-06-06T11:06:51.259Z,1749208011.259 [keepstation:NeedComms:A] Stopped 2025-06-06T11:07:45.610Z,1749208065.610 [keepstation:NeedComms:C] Running Loop=1 2025-06-06T11:07:46.322Z,1749208066.322 [Radio_Surface](INFO): Powering up 2025-06-06T11:07:52.034Z,1749208072.034 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-06T11:07:58.928Z,1749208078.928 [NAL9602](INFO): SBD MO Status=0, MOMSN=21100, MT Status=0, MTMSN=0 2025-06-06T11:07:58.928Z,1749208078.928 [NAL9602](INFO): No messages in MT queue 2025-06-06T11:08:00.176Z,1749208080.176 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,110759.00,A,3646.89718,N,12158.79813,W,1.127,238.80,060625,,,A*77 2025-06-06T11:08:00.179Z,1749208080.179 [NAL9602](INFO): GPS fix at 20250606T110759: (36.781620, -121.979969) 2025-06-06T11:08:00.215Z,1749208080.215 [UniversalFixResidualReporter](INFO): Fix residual: 44.0 %DT, over the last 2624.1 m. Residual distance 1155.5 m at bearing -51.9 degrees. Fix at (36.7816, -121.9800) with 1107.0 m made good. 2025-06-06T11:08:00.230Z,1749208080.230 [keepstation:NeedComms:C] Stopped 2025-06-06T11:08:00.231Z,1749208080.231 [keepstation:NeedComms:D] Running Loop=1 2025-06-06T11:08:08.701Z,1749208088.701 [NAL9602](INFO): SBD MO Status=1, MOMSN=21101, MT Status=0, MTMSN=0 2025-06-06T11:08:08.758Z,1749208088.758 [NAL9602](INFO): Sent 97 bytes from file Logs/20250606T064225/Courier0036.lzma 2025-06-06T11:08:08.759Z,1749208088.759 [NAL9602](INFO): Packets left to send: 0 2025-06-06T11:08:20.511Z,1749208100.511 [NAL9602](INFO): SBD MO Status=1, MOMSN=21102, MT Status=0, MTMSN=0 2025-06-06T11:08:20.560Z,1749208100.560 [NAL9602](INFO): Sent 332 bytes from file Logs/20250606T064225/Express0037.lzma 2025-06-06T11:08:20.560Z,1749208100.560 [NAL9602](INFO): Packets left to send: 1 2025-06-06T11:08:22.155Z,1749208102.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T11:08:32.785Z,1749208112.785 [NAL9602](INFO): SBD MO Status=1, MOMSN=21103, MT Status=0, MTMSN=0 2025-06-06T11:08:32.834Z,1749208112.834 [NAL9602](INFO): Sent 222 bytes from file Logs/20250606T064225/Express0037.lzma 2025-06-06T11:08:32.835Z,1749208112.835 [NAL9602](INFO): Packets left to send: 0 2025-06-06T11:08:43.273Z,1749208123.273 [NAL9602](INFO): SBD MO Status=0, MOMSN=21104, MT Status=0, MTMSN=0 2025-06-06T11:08:43.362Z,1749208123.362 [keepstation:NeedComms:D] Stopped 2025-06-06T11:08:43.377Z,1749208123.377 [keepstation:NeedComms:E] Running Loop=1 2025-06-06T11:08:45.750Z,1749208125.750 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,110844.00,A,3646.89748,N,12158.79580,W,0.175,32.13,060625,,,A*42 2025-06-06T11:08:45.756Z,1749208125.756 [NAL9602](INFO): GPS fix at 20250606T110844: (36.781625, -121.979930) 2025-06-06T11:08:45.844Z,1749208125.844 [keepstation:NeedComms:E] Stopped 2025-06-06T11:08:45.845Z,1749208125.845 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-06T11:08:45.845Z,1749208125.845 [keepstation:NeedComms] Stopped 2025-06-06T11:08:45.845Z,1749208125.845 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-06T11:08:45.845Z,1749208125.845 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-06T11:08:45.845Z,1749208125.845 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T11:08:46.156Z,1749208126.156 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-06T11:08:46.156Z,1749208126.156 [keepstation:SurfaceComms:B] Stopped 2025-06-06T11:08:46.156Z,1749208126.156 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-06T11:08:46.156Z,1749208126.156 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-06T11:08:46.156Z,1749208126.156 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-06T11:08:46.157Z,1749208126.157 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-06T11:08:46.519Z,1749208126.519 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position 31.28 mm. 2025-06-06T11:08:46.525Z,1749208126.525 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-06T11:08:46.525Z,1749208126.525 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-06T11:08:46.525Z,1749208126.525 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-06T11:08:46.525Z,1749208126.525 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-06T11:08:46.525Z,1749208126.525 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-06T11:08:46.525Z,1749208126.525 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-06T11:08:46.939Z,1749208126.939 [keepstation:SurfaceComms:setStation] Stopped 2025-06-06T11:08:46.939Z,1749208126.939 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-06T11:08:46.940Z,1749208126.940 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-06T11:08:46.940Z,1749208126.940 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-06T11:08:46.940Z,1749208126.940 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-06T11:08:46.940Z,1749208126.940 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms:setSink] Stopped 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms] Stopped 2025-06-06T11:08:47.321Z,1749208127.321 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-06T11:08:47.770Z,1749208127.770 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T11:08:47.770Z,1749208127.770 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-06T11:08:47.770Z,1749208127.770 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-06T11:08:47.771Z,1749208127.771 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-06T11:08:47.771Z,1749208127.771 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T11:08:47.771Z,1749208127.771 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-06T11:08:47.771Z,1749208127.771 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-06T11:08:47.771Z,1749208127.771 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T11:08:47.771Z,1749208127.771 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T11:08:47.772Z,1749208127.772 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T11:08:47.772Z,1749208127.772 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T11:08:47.772Z,1749208127.772 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-06T11:08:47.772Z,1749208127.772 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-06T11:08:47.772Z,1749208127.772 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-06T11:08:47.773Z,1749208127.773 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-06T11:08:47.773Z,1749208127.773 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-06T11:08:47.773Z,1749208127.773 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-06T11:08:53.282Z,1749208133.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T11:09:18.418Z,1749208158.418 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T11:09:24.410Z,1749208164.410 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T11:09:55.535Z,1749208195.535 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T11:10:04.483Z,1749208204.483 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.011867 2025-06-06T11:10:56.683Z,1749208256.683 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T11:11:24.562Z,1749208284.562 [Radio_Surface](INFO): Powering down 2025-06-06T11:11:27.807Z,1749208287.807 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T11:11:28.811Z,1749208288.811 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-06T11:13:01.436Z,1749208381.436 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-06T11:13:01.437Z,1749208381.437 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-06T11:13:01.437Z,1749208381.437 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T11:13:01.437Z,1749208381.437 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T11:13:01.851Z,1749208381.851 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-06T11:13:02.246Z,1749208382.246 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T11:13:02.246Z,1749208382.246 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T11:13:02.247Z,1749208382.247 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T11:13:02.248Z,1749208382.248 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-06T11:13:02.248Z,1749208382.248 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-06T11:13:02.248Z,1749208382.248 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T11:13:02.249Z,1749208382.249 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-06T11:13:05.215Z,1749208385.215 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743164 2025-06-06T11:21:08.793Z,1749208868.793 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-06-06T11:21:10.861Z,1749208870.861 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2025-06-06T11:22:32.427Z,1749208952.427 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-06-06T11:22:32.429Z,1749208952.429 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-06-06T11:22:32.437Z,1749208952.437 [BPC1](INFO): Received data from all battery sticks. 2025-06-06T11:33:01.985Z,1749209581.985 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-06T11:33:06.428Z,1749209586.428 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002838 CHAN A1 (24V): 0.004068 CHAN A2 (12V): -0.008963 CHAN A3 (5V): -0.006036 CHAN B0 (3.3V): -0.002838 CHAN B1 (3.15aV): -0.001744 CHAN B2 (3.15bV): -0.001562 CHAN B3 (GND): -0.001545 OPEN: 0.012194 Full Scale: +/- 1 mA 2025-06-06T12:08:45.861Z,1749211725.861 [keepstation:SurfaceComms] Running Loop=1 2025-06-06T12:08:45.861Z,1749211725.861 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-06T12:08:45.862Z,1749211725.862 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-06T12:08:45.862Z,1749211725.862 [keepstation:SurfaceComms:A] Stopped 2025-06-06T12:08:45.862Z,1749211725.862 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-06T12:08:45.862Z,1749211725.862 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-06T12:08:46.256Z,1749211726.256 [keepstation:TransitToStation:Dive] Stopped 2025-06-06T12:08:46.256Z,1749211726.256 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-06T12:08:46.256Z,1749211726.256 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-06T12:08:46.256Z,1749211726.256 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-06T12:08:46.256Z,1749211726.256 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-06T12:08:46.257Z,1749211726.257 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-06T12:08:46.259Z,1749211726.259 [keepstation:NeedComms] Running Loop=1 2025-06-06T12:08:46.259Z,1749211726.259 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-06T12:08:46.259Z,1749211726.259 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-06T12:08:46.259Z,1749211726.259 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-06T12:08:46.260Z,1749211726.260 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-06T12:08:46.260Z,1749211726.260 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-06T12:08:46.260Z,1749211726.260 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-06T12:08:46.261Z,1749211726.261 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-06T12:08:46.261Z,1749211726.261 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-06T12:08:46.261Z,1749211726.261 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-06T12:08:46.262Z,1749211726.262 [keepstation:NeedComms:A] Running Loop=1 2025-06-06T12:08:46.263Z,1749211726.263 [keepstation:NeedComms:A](INFO): last time_fix was: 1749208124.000000 second since 1970/01/01T00:00:00Z 2025-06-06T12:08:46.264Z,1749211726.264 [keepstation:NeedComms:A] Stopped 2025-06-06T12:09:49.240Z,1749211789.240 [keepstation:NeedComms:C] Running Loop=1 2025-06-06T12:09:49.910Z,1749211789.910 [Radio_Surface](INFO): Powering up 2025-06-06T12:09:55.295Z,1749211795.295 [DataOverHttps](INFO): Radio surface powered ON. 2025-06-06T12:10:21.107Z,1749211821.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=21105, MT Status=0, MTMSN=0 2025-06-06T12:10:21.107Z,1749211821.107 [NAL9602](INFO): No messages in MT queue 2025-06-06T12:10:22.310Z,1749211822.310 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121021.00,A,3646.93687,N,12159.53522,W,0.389,139.30,060625,,,A*79 2025-06-06T12:10:22.312Z,1749211822.312 [NAL9602](INFO): GPS fix at 20250606T121021: (36.782281, -121.992254) 2025-06-06T12:10:22.324Z,1749211822.324 [UniversalFixResidualReporter](INFO): Fix residual: 58.9 %DT, over the last 2519.8 m. Residual distance 1484.9 m at bearing -41.4 degrees. Fix at (36.7823, -121.9923) with 1099.9 m made good. 2025-06-06T12:10:22.328Z,1749211822.328 [keepstation:NeedComms:C] Stopped 2025-06-06T12:10:22.328Z,1749211822.328 [keepstation:NeedComms:D] Running Loop=1 2025-06-06T12:10:25.420Z,1749211825.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:10:32.915Z,1749211832.915 [NAL9602](INFO): SBD MO Status=1, MOMSN=21106, MT Status=0, MTMSN=0 2025-06-06T12:10:32.967Z,1749211832.967 [NAL9602](INFO): Sent 97 bytes from file Logs/20250606T064225/Courier0039.lzma 2025-06-06T12:10:32.967Z,1749211832.967 [NAL9602](INFO): Packets left to send: 0 2025-06-06T12:10:42.285Z,1749211842.285 [NAL9602](INFO): SBD MO Status=1, MOMSN=21107, MT Status=0, MTMSN=0 2025-06-06T12:10:42.346Z,1749211842.346 [NAL9602](INFO): Sent 332 bytes from file Logs/20250606T064225/Express0040.lzma 2025-06-06T12:10:42.347Z,1749211842.347 [NAL9602](INFO): Packets left to send: 2 2025-06-06T12:10:53.353Z,1749211853.353 [NAL9602](INFO): SBD MO Status=1, MOMSN=21108, MT Status=0, MTMSN=0 2025-06-06T12:10:53.402Z,1749211853.402 [NAL9602](INFO): Sent 332 bytes from file Logs/20250606T064225/Express0040.lzma 2025-06-06T12:10:53.403Z,1749211853.403 [NAL9602](INFO): Packets left to send: 1 2025-06-06T12:10:56.543Z,1749211856.543 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:11:09.596Z,1749211869.596 [NAL9602](INFO): SBD MO Status=2, MOMSN=21109, MT Status=2, MTMSN=0 2025-06-06T12:11:09.596Z,1749211869.596 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-06T12:11:21.172Z,1749211881.172 [NAL9602](INFO): SBD MO Status=1, MOMSN=21109, MT Status=0, MTMSN=0 2025-06-06T12:11:21.219Z,1749211881.219 [NAL9602](INFO): Sent 64 bytes from file Logs/20250606T064225/Express0040.lzma 2025-06-06T12:11:21.219Z,1749211881.219 [NAL9602](INFO): Packets left to send: 0 2025-06-06T12:11:27.670Z,1749211887.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:11:40.117Z,1749211900.117 [NAL9602](INFO): SBD MO Status=0, MOMSN=21110, MT Status=0, MTMSN=0 2025-06-06T12:11:40.209Z,1749211900.209 [keepstation:NeedComms:D] Stopped 2025-06-06T12:11:40.209Z,1749211900.209 [keepstation:NeedComms:E] Running Loop=1 2025-06-06T12:11:42.542Z,1749211902.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,121141.00,A,3646.93208,N,12159.52453,W,1.944,7.31,060625,,,A*7C 2025-06-06T12:11:42.545Z,1749211902.545 [NAL9602](INFO): GPS fix at 20250606T121141: (36.782201, -121.992075) 2025-06-06T12:11:42.563Z,1749211902.563 [keepstation:NeedComms:E] Stopped 2025-06-06T12:11:42.564Z,1749211902.564 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-06T12:11:42.564Z,1749211902.564 [keepstation:NeedComms] Stopped 2025-06-06T12:11:42.564Z,1749211902.564 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-06T12:11:42.564Z,1749211902.564 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-06T12:11:42.564Z,1749211902.564 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-06T12:11:42.980Z,1749211902.980 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-06T12:11:42.980Z,1749211902.980 [keepstation:SurfaceComms:B] Stopped 2025-06-06T12:11:42.980Z,1749211902.980 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-06T12:11:42.980Z,1749211902.980 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-06T12:11:42.980Z,1749211902.980 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-06T12:11:42.980Z,1749211902.980 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-06T12:11:43.395Z,1749211903.395 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 31.28 mm. 2025-06-06T12:11:43.401Z,1749211903.401 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-06T12:11:43.401Z,1749211903.401 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-06T12:11:43.401Z,1749211903.401 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-06T12:11:43.401Z,1749211903.401 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-06T12:11:43.401Z,1749211903.401 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-06T12:11:43.401Z,1749211903.401 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-06T12:11:43.789Z,1749211903.789 [keepstation:SurfaceComms:setStation] Stopped 2025-06-06T12:11:43.789Z,1749211903.789 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-06T12:11:43.789Z,1749211903.789 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-06T12:11:43.789Z,1749211903.789 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-06T12:11:43.790Z,1749211903.790 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-06T12:11:43.790Z,1749211903.790 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-06T12:11:44.176Z,1749211904.176 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-06T12:11:44.176Z,1749211904.176 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-06-06T12:11:44.177Z,1749211904.177 [keepstation:SurfaceComms:setSink] Stopped 2025-06-06T12:11:44.177Z,1749211904.177 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-06T12:11:44.177Z,1749211904.177 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-06T12:11:44.177Z,1749211904.177 [keepstation:SurfaceComms] Stopped 2025-06-06T12:11:44.177Z,1749211904.177 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-06T12:11:44.588Z,1749211904.588 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-06T12:11:44.588Z,1749211904.588 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-06T12:11:44.588Z,1749211904.588 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-06T12:11:44.588Z,1749211904.588 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-06T12:11:44.589Z,1749211904.589 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-06T12:11:44.590Z,1749211904.590 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-06-06T12:11:44.590Z,1749211904.590 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-06T12:11:44.590Z,1749211904.590 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-06-06T12:11:44.590Z,1749211904.590 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-06-06T12:11:58.795Z,1749211918.795 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:12:09.671Z,1749211929.671 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003262 2025-06-06T12:12:14.471Z,1749211934.471 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-06T12:12:16.484Z,1749211936.484 [DVL_micro](ERROR): Failed to parse: :RD,+0000.30,+0000.50,+9999.99,+0000.53 2025-06-06T12:12:50.025Z,1749211970.025 [DVL_micro](ERROR): Failed to parse: :SA,+01.62,+04.78,223.0 2025-06-06T12:14:02.266Z,1749212042.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:14:33.390Z,1749212073.390 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:14:39.226Z,1749212079.226 [Radio_Surface](INFO): Powering down 2025-06-06T12:15:04.515Z,1749212104.515 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-06T12:15:05.519Z,1749212105.519 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-06T12:15:44.163Z,1749212144.163 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-06-06T12:15:44.164Z,1749212144.164 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-06-06T12:15:44.164Z,1749212144.164 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T12:15:44.164Z,1749212144.164 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-06-06T12:15:44.594Z,1749212144.594 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-06-06T12:15:44.594Z,1749212144.594 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T12:15:44.594Z,1749212144.594 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-06-06T12:15:44.594Z,1749212144.594 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-06-06T12:15:44.594Z,1749212144.594 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-06-06T12:15:44.594Z,1749212144.594 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T12:15:44.595Z,1749212144.595 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-06-06T12:15:44.962Z,1749212144.962 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-06T12:15:44.962Z,1749212144.962 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-06T12:15:44.963Z,1749212144.963 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-06T12:15:44.964Z,1749212144.964 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-06T12:15:44.964Z,1749212144.964 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-06T12:15:44.964Z,1749212144.964 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-06T12:15:44.964Z,1749212144.964 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-06-06T12:15:44.965Z,1749212144.965 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-06-06T12:15:45.363Z,1749212145.363 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 31.28 mm (1 active estimators). 2025-06-06T12:15:50.618Z,1749212150.618 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,+9999.99,,+9999.99,+9999.99 2025-06-06T12:15:50.618Z,1749212150.618 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,,+9999.99,+9999.99 2025-06-06T12:16:09.775Z,1749212169.775 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.726807