2025-05-14T19:32:00.150Z,1747251120.150 [CommandExec](IMPORTANT): got command restart logs 2025-05-14T19:32:21.116Z,1747251141.116 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.20 m/s, pitch 20.00 deg, mass-position 36.20 mm (2 active estimators). 2025-05-14T19:33:37.677Z,1747251217.677 [ElevatorServo](FAULT): Overload Error 2025-05-14T19:33:37.677Z,1747251217.677 [ElevatorServo] Hardware Fault, FailCount= 1 2025-05-14T19:33:37.677Z,1747251217.677 [ElevatorServo](ERROR): Hardware Fault 2025-05-14T19:33:37.856Z,1747251217.856 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-05-14T19:33:38.071Z,1747251218.071 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-14T19:33:38.071Z,1747251218.071 [ElevatorServo](INFO): Powering down 2025-05-14T19:33:38.855Z,1747251218.855 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-05-14T19:33:38.970Z,1747251218.970 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-05-14T19:33:39.120Z,1747251219.120 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-05-14T19:33:39.120Z,1747251219.120 [ElevatorServo] No Fault, FailCount= 1 2025-05-14T19:33:55.097Z,1747251235.097 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:34:26.229Z,1747251266.229 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:34:30.149Z,1747251270.149 [Radio_Surface](INFO): Powering down 2025-05-14T19:34:57.353Z,1747251297.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:34:58.357Z,1747251298.357 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-14T19:36:03.629Z,1747251363.629 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.737595 2025-05-14T19:43:57.968Z,1747251837.968 [transit:NeedComms] Running Loop=1 2025-05-14T19:43:57.968Z,1747251837.968 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-05-14T19:43:57.968Z,1747251837.968 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-05-14T19:43:57.968Z,1747251837.968 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T19:43:57.969Z,1747251837.969 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-05-14T19:43:57.969Z,1747251837.969 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-05-14T19:43:57.969Z,1747251837.969 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-05-14T19:43:57.970Z,1747251837.970 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-05-14T19:43:57.970Z,1747251837.970 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T19:43:57.970Z,1747251837.970 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T19:43:57.971Z,1747251837.971 [transit:NeedComms:A] Running Loop=1 2025-05-14T19:43:57.973Z,1747251837.973 [transit:NeedComms:A](INFO): last time_fix was: 1747250755.000000 second since 1970/01/01T00:00:00Z 2025-05-14T19:43:57.973Z,1747251837.973 [transit:NeedComms:A] Stopped 2025-05-14T19:44:00.733Z,1747251840.733 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T19:44:00.829Z,1747251840.829 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T19:44:00.829Z,1747251840.829 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T19:44:00.829Z,1747251840.829 [ThrusterHE] Communications Fault, FailCount= 1 2025-05-14T19:44:00.829Z,1747251840.829 [ThrusterHE](ERROR): Communications Fault 2025-05-14T19:44:00.836Z,1747251840.836 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T19:44:01.194Z,1747251841.194 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T19:44:01.194Z,1747251841.194 [ThrusterHE](INFO): Powering down 2025-05-14T19:44:01.858Z,1747251841.858 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T19:44:01.978Z,1747251841.978 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T19:44:01.981Z,1747251841.981 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T19:44:01.981Z,1747251841.981 [ThrusterHE] No Fault, FailCount= 1 2025-05-14T19:44:05.316Z,1747251845.316 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,+00018,+0 2025-05-14T19:44:05.316Z,1747251845.316 [DVL_micro](ERROR): Failed to parse: :BI,+00018,+0 2025-05-14T19:44:17.051Z,1747251857.051 [transit:NeedComms:C] Running Loop=1 2025-05-14T19:44:17.435Z,1747251857.435 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-05-14T19:44:17.797Z,1747251857.797 [Radio_Surface](INFO): Powering up 2025-05-14T19:44:23.665Z,1747251863.665 [DataOverHttps](INFO): Radio surface powered ON. 2025-05-14T19:44:44.095Z,1747251884.095 [NAL9602](INFO): SBD MO Status=1, MOMSN=20705, MT Status=0, MTMSN=0 2025-05-14T19:44:44.095Z,1747251884.095 [NAL9602](INFO): No messages in MT queue 2025-05-14T19:44:45.304Z,1747251885.304 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194444.00,A,3648.37619,N,12150.40625,W,1.089,85.46,140525,,,A*4D 2025-05-14T19:44:45.306Z,1747251885.306 [NAL9602](INFO): GPS fix at 20250514T194444: (36.806270, -121.840104) 2025-05-14T19:44:45.347Z,1747251885.347 [UniversalFixResidualReporter](INFO): Fix residual: 22.7 %DT, over the last 1064.2 m. Residual distance 241.5 m at bearing -71.6 degrees. Fix at (36.8063, -121.8401) with 915.6 m made good. 2025-05-14T19:44:45.355Z,1747251885.355 [transit:NeedComms:C] Stopped 2025-05-14T19:44:45.355Z,1747251885.355 [transit:NeedComms:D] Running Loop=1 2025-05-14T19:44:45.744Z,1747251885.744 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-05-14T19:44:53.785Z,1747251893.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:45:07.643Z,1747251907.643 [NAL9602](INFO): SBD MO Status=1, MOMSN=20706, MT Status=0, MTMSN=0 2025-05-14T19:45:07.689Z,1747251907.689 [NAL9602](INFO): Sent 40 bytes from file Logs/20250514T001523/Courier0033.lzma 2025-05-14T19:45:07.689Z,1747251907.689 [NAL9602](INFO): Packets left to send: 0 2025-05-14T19:45:16.642Z,1747251916.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=20707, MT Status=0, MTMSN=0 2025-05-14T19:45:16.693Z,1747251916.693 [NAL9602](INFO): Sent 325 bytes from file Logs/20250514T193200/Courier0000.lzma 2025-05-14T19:45:16.693Z,1747251916.693 [NAL9602](INFO): Packets left to send: 0 2025-05-14T19:45:24.909Z,1747251924.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:45:25.558Z,1747251925.558 [NAL9602](INFO): SBD MO Status=1, MOMSN=20708, MT Status=0, MTMSN=0 2025-05-14T19:45:25.609Z,1747251925.609 [NAL9602](INFO): Sent 332 bytes from file Logs/20250514T001523/Express0034.lzma 2025-05-14T19:45:25.609Z,1747251925.609 [NAL9602](INFO): Packets left to send: 1 2025-05-14T19:45:36.928Z,1747251936.928 [NAL9602](INFO): SBD MO Status=1, MOMSN=20709, MT Status=0, MTMSN=0 2025-05-14T19:45:36.981Z,1747251936.981 [NAL9602](INFO): Sent 192 bytes from file Logs/20250514T001523/Express0034.lzma 2025-05-14T19:45:36.981Z,1747251936.981 [NAL9602](INFO): Packets left to send: 0 2025-05-14T19:45:47.082Z,1747251947.082 [NAL9602](INFO): SBD MO Status=1, MOMSN=20710, MT Status=0, MTMSN=0 2025-05-14T19:45:47.137Z,1747251947.137 [NAL9602](INFO): Sent 332 bytes from file Logs/20250514T193200/Express0001.lzma 2025-05-14T19:45:47.137Z,1747251947.137 [NAL9602](INFO): Packets left to send: 2 2025-05-14T19:45:56.041Z,1747251956.041 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:46:10.174Z,1747251970.174 [NAL9602](INFO): SBD MO Status=1, MOMSN=20711, MT Status=0, MTMSN=0 2025-05-14T19:46:10.243Z,1747251970.243 [NAL9602](INFO): Sent 332 bytes from file Logs/20250514T193200/Express0001.lzma 2025-05-14T19:46:10.243Z,1747251970.243 [NAL9602](INFO): Packets left to send: 1 2025-05-14T19:46:27.165Z,1747251987.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:46:28.450Z,1747251988.450 [NAL9602](INFO): SBD MO Status=2, MOMSN=20712, MT Status=2, MTMSN=0 2025-05-14T19:46:28.450Z,1747251988.450 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:46:37.688Z,1747251997.688 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004781 2025-05-14T19:46:45.300Z,1747252005.300 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250514T193200/Courier0003.lzma 2025-05-14T19:46:45.309Z,1747252005.309 [NAL9602](INFO): SBD MO Status=2, MOMSN=20712, MT Status=2, MTMSN=0 2025-05-14T19:46:45.309Z,1747252005.309 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:46:46.302Z,1747252006.302 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0003.lzma.bak 2025-05-14T19:46:46.303Z,1747252006.303 [DataOverHttps](INFO): SBD MOMSN=24875706 2025-05-14T19:47:02.794Z,1747252022.794 [DataOverHttps](INFO): Sending 730 bytes from file Logs/20250514T193200/Express0001.lzma 2025-05-14T19:47:03.791Z,1747252023.791 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0001.lzma.bak 2025-05-14T19:47:03.792Z,1747252023.792 [DataOverHttps](INFO): SBD MOMSN=24875709 2025-05-14T19:47:16.006Z,1747252036.006 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T19:47:20.420Z,1747252040.420 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250514T193200/Express0004.lzma 2025-05-14T19:47:21.423Z,1747252041.423 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0004.lzma.bak 2025-05-14T19:47:21.424Z,1747252041.424 [DataOverHttps](INFO): SBD MOMSN=24875716 2025-05-14T19:47:22.905Z,1747252042.905 [transit:NeedComms:D] Stopped 2025-05-14T19:47:22.906Z,1747252042.906 [transit:NeedComms:E] Running Loop=1 2025-05-14T19:47:23.292Z,1747252043.292 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-05-14T19:47:24.896Z,1747252044.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194724.00,A,3648.38271,N,12150.37719,W,1.497,83.95,140525,,,A*40 2025-05-14T19:47:24.898Z,1747252044.898 [NAL9602](INFO): GPS fix at 20250514T194724: (36.806379, -121.839620) 2025-05-14T19:47:24.948Z,1747252044.948 [transit:NeedComms:E] Stopped 2025-05-14T19:47:24.954Z,1747252044.954 [transit:NeedComms](INFO): Completed transit:NeedComms 2025-05-14T19:47:24.954Z,1747252044.954 [transit:NeedComms] Stopped 2025-05-14T19:47:24.954Z,1747252044.954 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-05-14T19:47:24.954Z,1747252044.954 [transit:NeedComms:B.GoToSurface] Stopped 2025-05-14T19:47:24.954Z,1747252044.954 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T19:47:29.805Z,1747252049.805 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T19:47:41.199Z,1747252061.199 [NAL9602](INFO): SBD MO Status=1, MOMSN=20712, MT Status=0, MTMSN=0 2025-05-14T19:47:41.199Z,1747252061.199 [NAL9602](INFO): No messages in MT queue 2025-05-14T19:47:46.141Z,1747252066.141 [ElevatorServo](FAULT): Overload Error 2025-05-14T19:47:46.141Z,1747252066.141 [ElevatorServo] Hardware Fault, FailCount= 1 2025-05-14T19:47:46.141Z,1747252066.141 [ElevatorServo](ERROR): Hardware Fault 2025-05-14T19:47:46.332Z,1747252066.332 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-05-14T19:47:46.519Z,1747252066.519 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-14T19:47:46.519Z,1747252066.519 [ElevatorServo](INFO): Powering down 2025-05-14T19:47:47.334Z,1747252067.334 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-05-14T19:47:47.456Z,1747252067.456 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-05-14T19:47:47.472Z,1747252067.472 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-05-14T19:47:47.472Z,1747252067.472 [ElevatorServo] No Fault, FailCount= 1 2025-05-14T19:47:52.177Z,1747252072.177 [ElevatorServo](FAULT): Overload Error 2025-05-14T19:47:52.177Z,1747252072.177 [ElevatorServo] Hardware Fault, FailCount= 2 2025-05-14T19:47:52.177Z,1747252072.177 [ElevatorServo](ERROR): Hardware Fault 2025-05-14T19:47:52.304Z,1747252072.304 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-05-14T19:47:52.570Z,1747252072.570 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-14T19:47:52.570Z,1747252072.570 [ElevatorServo](INFO): Powering down 2025-05-14T19:47:53.426Z,1747252073.426 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-05-14T19:47:53.546Z,1747252073.546 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-05-14T19:47:53.628Z,1747252073.628 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-05-14T19:47:53.628Z,1747252073.628 [ElevatorServo] No Fault, FailCount= 2 2025-05-14T19:48:11.719Z,1747252091.719 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T19:48:55.285Z,1747252135.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:49:03.081Z,1747252143.081 [Radio_Surface](INFO): Powering down 2025-05-14T19:49:26.417Z,1747252166.417 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:49:27.421Z,1747252167.421 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-14T19:50:30.673Z,1747252230.673 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.215347 2025-05-14T19:57:42.837Z,1747252662.837 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-05-14T19:57:42.837Z,1747252662.837 [DVL_micro] Communications Fault, FailCount= 1 2025-05-14T19:57:42.837Z,1747252662.837 [DVL_micro](ERROR): Communications Fault 2025-05-14T19:57:42.837Z,1747252662.837 [DVL_micro](ERROR): Failed to parse: 2025-05-14T19:57:42.936Z,1747252662.936 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-05-14T19:57:43.273Z,1747252663.273 [DVL_micro](INFO): Powering down 2025-05-14T19:57:44.092Z,1747252664.092 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-05-14T19:57:44.093Z,1747252664.093 [DVL_micro] No Fault, FailCount= 1 2025-05-14T19:58:22.433Z,1747252702.433 [DVL_micro](ERROR): Failed to parse: :0000000.00,+00000000.00,+00000000.00,0045.00,000.00 2025-05-14T20:05:26.147Z,1747253126.147 [transit:NeedComms] Running Loop=1 2025-05-14T20:05:26.147Z,1747253126.147 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-05-14T20:05:26.147Z,1747253126.147 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-05-14T20:05:26.147Z,1747253126.147 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T20:05:26.147Z,1747253126.147 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-05-14T20:05:26.148Z,1747253126.148 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-05-14T20:05:26.148Z,1747253126.148 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-05-14T20:05:26.149Z,1747253126.149 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-05-14T20:05:26.149Z,1747253126.149 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T20:05:26.149Z,1747253126.149 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T20:05:26.150Z,1747253126.150 [transit:NeedComms:A] Running Loop=1 2025-05-14T20:05:26.151Z,1747253126.151 [transit:NeedComms:A](INFO): last time_fix was: 1747252044.000000 second since 1970/01/01T00:00:00Z 2025-05-14T20:05:26.152Z,1747253126.152 [transit:NeedComms:A] Stopped 2025-05-14T20:05:28.529Z,1747253128.529 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T20:05:28.625Z,1747253128.625 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T20:05:28.625Z,1747253128.625 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T20:05:28.625Z,1747253128.625 [ThrusterHE] Communications Fault, FailCount= 1 2025-05-14T20:05:28.625Z,1747253128.625 [ThrusterHE](ERROR): Communications Fault 2025-05-14T20:05:28.629Z,1747253128.629 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T20:05:28.921Z,1747253128.921 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T20:05:28.921Z,1747253128.921 [ThrusterHE](INFO): Powering down 2025-05-14T20:05:29.578Z,1747253129.578 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T20:05:29.698Z,1747253129.698 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T20:05:29.701Z,1747253129.701 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T20:05:29.701Z,1747253129.701 [ThrusterHE] No Fault, FailCount= 1 2025-05-14T20:05:47.307Z,1747253147.307 [transit:NeedComms:C] Running Loop=1 2025-05-14T20:05:47.861Z,1747253147.861 [Radio_Surface](INFO): Powering up 2025-05-14T20:05:53.561Z,1747253153.561 [DataOverHttps](INFO): Radio surface powered ON. 2025-05-14T20:06:23.681Z,1747253183.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:06:54.805Z,1747253214.805 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:06:59.600Z,1747253219.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=20713, MT Status=2, MTMSN=0 2025-05-14T20:06:59.600Z,1747253219.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:07:25.929Z,1747253245.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:07:28.267Z,1747253248.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=20713, MT Status=2, MTMSN=0 2025-05-14T20:07:28.267Z,1747253248.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:07:44.019Z,1747253264.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=20713, MT Status=2, MTMSN=0 2025-05-14T20:07:44.019Z,1747253264.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:07:57.053Z,1747253277.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:08:04.240Z,1747253284.240 [NAL9602](INFO): SBD MO Status=1, MOMSN=20713, MT Status=0, MTMSN=0 2025-05-14T20:08:04.245Z,1747253284.245 [NAL9602](INFO): No messages in MT queue 2025-05-14T20:08:05.567Z,1747253285.567 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200804.00,A,3648.43010,N,12149.61032,W,0.369,318.03,140525,,,D*73 2025-05-14T20:08:05.574Z,1747253285.574 [NAL9602](INFO): GPS fix at 20250514T200804: (36.807168, -121.826839) 2025-05-14T20:08:05.616Z,1747253285.616 [UniversalFixResidualReporter](INFO): Fix residual: 2.4 %DT, over the last 1238.6 m. Residual distance 29.7 m at bearing -122.9 degrees. Fix at (36.8072, -121.8268) with 1141.3 m made good. 2025-05-14T20:08:05.638Z,1747253285.638 [transit:NeedComms:C] Stopped 2025-05-14T20:08:05.638Z,1747253285.638 [transit:NeedComms:D] Running Loop=1 2025-05-14T20:08:06.198Z,1747253286.198 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003485 2025-05-14T20:08:14.360Z,1747253294.360 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250514T193200/Courier0006.lzma 2025-05-14T20:08:15.362Z,1747253295.362 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0006.lzma.bak 2025-05-14T20:08:15.363Z,1747253295.363 [DataOverHttps](INFO): SBD MOMSN=24876010 2025-05-14T20:08:31.913Z,1747253311.913 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20250514T193200/Express0007.lzma 2025-05-14T20:08:32.915Z,1747253312.915 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0007.lzma.bak 2025-05-14T20:08:32.915Z,1747253312.915 [DataOverHttps](INFO): SBD MOMSN=24876015 2025-05-14T20:08:34.217Z,1747253314.217 [transit:NeedComms:D] Stopped 2025-05-14T20:08:34.218Z,1747253314.218 [transit:NeedComms:E] Running Loop=1 2025-05-14T20:08:36.200Z,1747253316.200 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200835.00,A,3648.42975,N,12149.60982,W,0.330,175.49,140525,,,A*77 2025-05-14T20:08:36.202Z,1747253316.202 [NAL9602](INFO): GPS fix at 20250514T200835: (36.807162, -121.826830) 2025-05-14T20:08:36.249Z,1747253316.249 [transit:NeedComms:E] Stopped 2025-05-14T20:08:36.250Z,1747253316.250 [transit:NeedComms](INFO): Completed transit:NeedComms 2025-05-14T20:08:36.250Z,1747253316.250 [transit:NeedComms] Stopped 2025-05-14T20:08:36.250Z,1747253316.250 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-05-14T20:08:36.250Z,1747253316.250 [transit:NeedComms:B.GoToSurface] Stopped 2025-05-14T20:08:36.250Z,1747253316.250 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T20:08:40.949Z,1747253320.949 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T20:08:41.046Z,1747253321.046 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T20:08:41.046Z,1747253321.046 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T20:08:41.046Z,1747253321.046 [ThrusterHE] Communications Fault, FailCount= 2 2025-05-14T20:08:41.046Z,1747253321.046 [ThrusterHE](ERROR): Communications Fault 2025-05-14T20:08:41.051Z,1747253321.051 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T20:08:41.269Z,1747253321.269 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T20:08:41.270Z,1747253321.270 [ThrusterHE](INFO): Powering down 2025-05-14T20:08:42.173Z,1747253322.173 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T20:08:42.294Z,1747253322.294 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T20:08:42.299Z,1747253322.299 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T20:08:42.299Z,1747253322.299 [ThrusterHE] No Fault, FailCount= 2 2025-05-14T20:09:08.510Z,1747253348.510 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T20:10:06.233Z,1747253406.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:10:13.325Z,1747253413.325 [Radio_Surface](INFO): Powering down 2025-05-14T20:10:37.361Z,1747253437.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:10:38.365Z,1747253438.365 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-14T20:10:52.147Z,1747253452.147 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-05-14T20:11:42.629Z,1747253502.629 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.367218 2025-05-14T20:12:00.893Z,1747253520.893 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-14T20:12:00.893Z,1747253520.893 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-14T20:12:00.894Z,1747253520.894 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-05-14T20:12:00.897Z,1747253520.897 [BPC1](INFO): Received data from all battery sticks. 2025-05-14T20:13:16.909Z,1747253596.909 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.806965,-121.824326 2025-05-14T20:13:16.909Z,1747253596.909 [transit:Transit:Wpt1.Waypoint] Stopped 2025-05-14T20:13:16.909Z,1747253596.909 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-05-14T20:13:16.909Z,1747253596.909 [transit:Transit:PhoneHome] Running Loop=1 2025-05-14T20:13:16.909Z,1747253596.909 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome 2025-05-14T20:13:17.298Z,1747253597.298 [transit:NeedComms] Running Loop=1 2025-05-14T20:13:17.299Z,1747253597.299 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-05-14T20:13:17.299Z,1747253597.299 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-05-14T20:13:17.299Z,1747253597.299 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T20:13:17.299Z,1747253597.299 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-05-14T20:13:17.300Z,1747253597.300 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-05-14T20:13:17.300Z,1747253597.300 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-05-14T20:13:17.300Z,1747253597.300 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-05-14T20:13:17.301Z,1747253597.301 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T20:13:17.301Z,1747253597.301 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T20:13:17.301Z,1747253597.301 [transit:NeedComms:A] Running Loop=1 2025-05-14T20:13:17.303Z,1747253597.303 [transit:NeedComms:A](INFO): last time_fix was: 1747253315.000000 second since 1970/01/01T00:00:00Z 2025-05-14T20:13:17.303Z,1747253597.303 [transit:NeedComms:A] Stopped 2025-05-14T20:13:19.713Z,1747253599.713 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T20:13:19.809Z,1747253599.809 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T20:13:19.809Z,1747253599.809 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T20:13:19.809Z,1747253599.809 [ThrusterHE] Communications Fault, FailCount= 1 2025-05-14T20:13:19.809Z,1747253599.809 [ThrusterHE](ERROR): Communications Fault 2025-05-14T20:13:19.815Z,1747253599.815 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T20:13:20.110Z,1747253600.110 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T20:13:20.110Z,1747253600.110 [ThrusterHE](INFO): Powering down 2025-05-14T20:13:20.854Z,1747253600.854 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T20:13:20.974Z,1747253600.974 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T20:13:20.978Z,1747253600.978 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T20:13:20.978Z,1747253600.978 [ThrusterHE] No Fault, FailCount= 1 2025-05-14T20:13:37.263Z,1747253617.263 [transit:NeedComms:C] Running Loop=1 2025-05-14T20:13:37.753Z,1747253617.753 [Radio_Surface](INFO): Powering up 2025-05-14T20:13:43.157Z,1747253623.157 [DataOverHttps](INFO): Radio surface powered ON. 2025-05-14T20:13:52.691Z,1747253632.691 [NAL9602](INFO): SBD MO Status=1, MOMSN=20714, MT Status=0, MTMSN=0 2025-05-14T20:13:52.692Z,1747253632.692 [NAL9602](INFO): No messages in MT queue 2025-05-14T20:13:53.847Z,1747253633.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201353.00,A,3648.42290,N,12149.43933,W,0.700,53.63,140525,,,A*4C 2025-05-14T20:13:53.851Z,1747253633.851 [NAL9602](INFO): GPS fix at 20250514T201353: (36.807048, -121.823989) 2025-05-14T20:13:53.871Z,1747253633.871 [transit:NeedComms:C] Stopped 2025-05-14T20:13:53.871Z,1747253633.871 [transit:NeedComms:D] Running Loop=1 2025-05-14T20:14:13.277Z,1747253653.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:14:17.843Z,1747253657.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=20715, MT Status=2, MTMSN=0 2025-05-14T20:14:17.843Z,1747253657.843 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:14:44.401Z,1747253684.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:15:04.357Z,1747253704.357 [NAL9602](INFO): SBD MO Status=2, MOMSN=20715, MT Status=2, MTMSN=0 2025-05-14T20:15:04.357Z,1747253704.357 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:15:15.525Z,1747253715.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:15:46.649Z,1747253746.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T20:15:55.218Z,1747253755.218 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003481 2025-05-14T20:16:02.901Z,1747253762.901 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250514T193200/Courier0009.lzma 2025-05-14T20:16:02.991Z,1747253762.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=20715, MT Status=2, MTMSN=0 2025-05-14T20:16:02.991Z,1747253762.991 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:16:03.902Z,1747253763.902 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0009.lzma.bak 2025-05-14T20:16:03.903Z,1747253763.903 [DataOverHttps](INFO): SBD MOMSN=24876087 2025-05-14T20:16:20.193Z,1747253780.193 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20250514T193200/Express0010.lzma 2025-05-14T20:16:21.203Z,1747253781.203 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0010.lzma.bak 2025-05-14T20:16:21.203Z,1747253781.203 [DataOverHttps](INFO): SBD MOMSN=24876090 2025-05-14T20:16:22.411Z,1747253782.411 [transit:NeedComms:D] Stopped 2025-05-14T20:16:22.412Z,1747253782.412 [transit:NeedComms:E] Running Loop=1 2025-05-14T20:16:24.408Z,1747253784.408 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201623.00,A,3648.42350,N,12149.42554,W,1.011,294.24,140525,,,D*76 2025-05-14T20:16:24.414Z,1747253784.414 [NAL9602](INFO): GPS fix at 20250514T201623: (36.807058, -121.823759) 2025-05-14T20:16:24.436Z,1747253784.436 [transit:NeedComms:E] Stopped 2025-05-14T20:16:24.462Z,1747253784.462 [transit:NeedComms](INFO): Completed transit:NeedComms 2025-05-14T20:16:24.462Z,1747253784.462 [transit:NeedComms] Stopped 2025-05-14T20:16:24.462Z,1747253784.462 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-05-14T20:16:24.462Z,1747253784.462 [transit:NeedComms:B.GoToSurface] Stopped 2025-05-14T20:16:24.462Z,1747253784.462 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T20:16:24.853Z,1747253784.853 [transit:Transit:PhoneHome](INFO): Completed transit:Transit:PhoneHome 2025-05-14T20:16:24.853Z,1747253784.853 [transit:Transit:PhoneHome] Stopped 2025-05-14T20:16:24.853Z,1747253784.853 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome 2025-05-14T20:16:24.854Z,1747253784.854 [transit:Transit](INFO): Completed transit:Transit 2025-05-14T20:16:24.854Z,1747253784.854 [transit:Transit] Stopped 2025-05-14T20:16:24.854Z,1747253784.854 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2025-05-14T20:16:24.854Z,1747253784.854 [transit:Transit:A.Pitch] Stopped 2025-05-14T20:16:24.854Z,1747253784.854 [transit:Transit:B.SetSpeed] Stopped 2025-05-14T20:16:24.854Z,1747253784.854 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2025-05-14T20:16:24.856Z,1747253784.856 [transit](INFO): Completed transit 2025-05-14T20:16:24.889Z,1747253784.889 [MissionManager](INFO): transit is completed. 2025-05-14T20:16:24.889Z,1747253784.889 [MissionManager](INFO): Uninitializing Mission transit 2025-05-14T20:16:24.889Z,1747253784.889 [transit] Stopped 2025-05-14T20:16:24.889Z,1747253784.889 [transit](DEBUG): Aggregate::uninitialize transit 2025-05-14T20:16:24.889Z,1747253784.889 [transit:StandardEnvelopes] Stopped 2025-05-14T20:16:24.889Z,1747253784.889 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2025-05-14T20:16:24.889Z,1747253784.889 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-05-14T20:16:24.889Z,1747253784.889 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-05-14T20:16:24.889Z,1747253784.889 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-05-14T20:16:24.890Z,1747253784.890 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-05-14T20:16:24.890Z,1747253784.890 [transit:BackseatDriver] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2025-05-14T20:16:24.890Z,1747253784.890 [transit:BackseatDriver:A.BackseatDriver] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:PowerOnly] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2025-05-14T20:16:24.890Z,1747253784.890 [transit:PowerOnly:E.Wait] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T20:16:24.890Z,1747253784.890 [transit:BuoyancyHold.Buoyancy] Stopped 2025-05-14T20:16:24.890Z,1747253784.890 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-05-14T20:16:24.890Z,1747253784.890 [transit:MassHold.Pitch] Stopped 2025-05-14T20:16:25.249Z,1747253785.249 [MissionManager](IMPORTANT): Started mission Default 2025-05-14T20:16:25.250Z,1747253785.250 [Default] Running Loop=1 2025-05-14T20:16:25.250Z,1747253785.250 [Default](DEBUG): Aggregate::initialize Default 2025-05-14T20:16:25.250Z,1747253785.250 [Default:B.GoToSurface] Running Loop=1 2025-05-14T20:16:25.250Z,1747253785.250 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T20:16:25.250Z,1747253785.250 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-05-14T20:16:25.250Z,1747253785.250 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-05-14T20:16:25.251Z,1747253785.251 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-05-14T20:16:25.251Z,1747253785.251 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-05-14T20:16:25.251Z,1747253785.251 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T20:16:25.252Z,1747253785.252 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T20:16:25.252Z,1747253785.252 [Default:A.Wait] Running Loop=1 2025-05-14T20:16:25.252Z,1747253785.252 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-05-14T20:16:38.570Z,1747253798.570 [Default:A.Wait](INFO): Done Waiting. 2025-05-14T20:16:38.570Z,1747253798.570 [Default:A.Wait] Stopped 2025-05-14T20:16:38.570Z,1747253798.570 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T20:16:38.957Z,1747253798.957 [Default:CheckIn] Running Loop=1 2025-05-14T20:16:38.957Z,1747253798.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T20:16:38.957Z,1747253798.957 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T20:16:40.969Z,1747253800.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201640.00,A,3648.42347,N,12149.42397,W,0.039,45.99,140525,,,D*4F 2025-05-14T20:16:40.971Z,1747253800.971 [NAL9602](INFO): GPS fix at 20250514T201640: (36.807058, -121.823733) 2025-05-14T20:16:40.982Z,1747253800.982 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T20:16:40.982Z,1747253800.982 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T20:16:48.900Z,1747253808.900 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250514T193200/Courier0012.lzma 2025-05-14T20:16:49.903Z,1747253809.903 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0012.lzma.bak 2025-05-14T20:16:49.903Z,1747253809.903 [DataOverHttps](INFO): SBD MOMSN=24876101 2025-05-14T20:17:07.305Z,1747253827.305 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20250514T193200/Express0013.lzma 2025-05-14T20:17:08.306Z,1747253828.306 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0013.lzma.bak 2025-05-14T20:17:08.307Z,1747253828.307 [DataOverHttps](INFO): SBD MOMSN=24876104 2025-05-14T20:17:09.677Z,1747253829.677 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T20:17:09.677Z,1747253829.677 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-14T20:17:09.677Z,1747253829.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-14T20:17:13.702Z,1747253833.702 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T20:20:52.257Z,1747254052.257 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-05-14T20:22:10.235Z,1747254130.235 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-14T20:22:10.236Z,1747254130.236 [Default:CheckIn:C.Wait] Stopped 2025-05-14T20:22:10.236Z,1747254130.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T20:22:10.236Z,1747254130.236 [Default:CheckIn:D] Running Loop=1 2025-05-14T20:22:10.639Z,1747254130.639 [Default:CheckIn:D] Stopped 2025-05-14T20:22:10.639Z,1747254130.639 [Default:CheckIn:E] Running Loop=1 2025-05-14T20:22:11.054Z,1747254131.054 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.756491 min 2025-05-14T20:22:11.054Z,1747254131.054 [Default:CheckIn:E] Stopped 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn] Stopped 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn](INFO): Running loop #2 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn] Running Loop=2 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T20:22:11.055Z,1747254131.055 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T20:22:13.070Z,1747254133.070 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202212.00,A,3648.41461,N,12149.40521,W,1.050,98.20,140525,,,D*4A 2025-05-14T20:22:13.073Z,1747254133.073 [NAL9602](INFO): GPS fix at 20250514T202212: (36.806910, -121.823420) 2025-05-14T20:22:13.083Z,1747254133.083 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T20:22:13.084Z,1747254133.084 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T20:22:20.846Z,1747254140.846 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250514T193200/Courier0015.lzma 2025-05-14T20:22:21.846Z,1747254141.846 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0015.lzma.bak 2025-05-14T20:22:21.847Z,1747254141.847 [DataOverHttps](INFO): SBD MOMSN=24876173 2025-05-14T20:22:38.449Z,1747254158.449 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20250514T193200/Express0016.lzma 2025-05-14T20:22:39.451Z,1747254159.451 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0016.lzma.bak 2025-05-14T20:22:39.451Z,1747254159.451 [DataOverHttps](INFO): SBD MOMSN=24876176 2025-05-14T20:22:40.948Z,1747254160.948 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T20:22:40.948Z,1747254160.948 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-14T20:22:40.948Z,1747254160.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-14T20:23:18.919Z,1747254198.919 [NAL9602](INFO): SBD MO Status=1, MOMSN=20715, MT Status=0, MTMSN=0 2025-05-14T20:23:18.919Z,1747254198.919 [NAL9602](INFO): No messages in MT queue 2025-05-14T20:23:49.611Z,1747254229.611 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T20:27:41.540Z,1747254461.540 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-14T20:27:41.540Z,1747254461.540 [Default:CheckIn:C.Wait] Stopped 2025-05-14T20:27:41.540Z,1747254461.540 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T20:27:41.540Z,1747254461.540 [Default:CheckIn:D] Running Loop=1 2025-05-14T20:27:41.950Z,1747254461.950 [Default:CheckIn:D] Stopped 2025-05-14T20:27:41.950Z,1747254461.950 [Default:CheckIn:E] Running Loop=1 2025-05-14T20:27:42.368Z,1747254462.368 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.278333 min 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn:E] Stopped 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn] Stopped 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn](INFO): Running loop #3 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn] Running Loop=3 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T20:27:42.369Z,1747254462.369 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T20:27:44.399Z,1747254464.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202743.00,A,3648.40253,N,12149.38573,W,0.486,144.07,140525,,,D*7E 2025-05-14T20:27:44.401Z,1747254464.401 [NAL9602](INFO): GPS fix at 20250514T202743: (36.806709, -121.823095) 2025-05-14T20:27:44.412Z,1747254464.412 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T20:27:44.412Z,1747254464.412 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T20:27:51.684Z,1747254471.684 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250514T193200/Courier0018.lzma 2025-05-14T20:27:52.686Z,1747254472.686 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0018.lzma.bak 2025-05-14T20:27:52.687Z,1747254472.687 [DataOverHttps](INFO): SBD MOMSN=24876197 2025-05-14T20:28:08.709Z,1747254488.709 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20250514T193200/Express0019.lzma 2025-05-14T20:28:09.712Z,1747254489.712 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0019.lzma.bak 2025-05-14T20:28:09.712Z,1747254489.713 [DataOverHttps](INFO): SBD MOMSN=24876226 2025-05-14T20:28:11.041Z,1747254491.041 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T20:28:11.041Z,1747254491.041 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-14T20:28:11.041Z,1747254491.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-14T20:28:51.423Z,1747254531.423 [NAL9602](INFO): SBD MO Status=1, MOMSN=20716, MT Status=0, MTMSN=0 2025-05-14T20:28:51.423Z,1747254531.423 [NAL9602](INFO): No messages in MT queue 2025-05-14T20:29:22.141Z,1747254562.141 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T20:30:52.637Z,1747254652.637 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-05-14T20:33:11.633Z,1747254791.633 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-14T20:33:11.633Z,1747254791.633 [Default:CheckIn:C.Wait] Stopped 2025-05-14T20:33:11.634Z,1747254791.634 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T20:33:11.634Z,1747254791.634 [Default:CheckIn:D] Running Loop=1 2025-05-14T20:33:12.039Z,1747254792.039 [Default:CheckIn:D] Stopped 2025-05-14T20:33:12.039Z,1747254792.039 [Default:CheckIn:E] Running Loop=1 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.779820 min 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn:E] Stopped 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn] Stopped 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn](INFO): Running loop #4 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn] Running Loop=4 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T20:33:12.427Z,1747254792.427 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T20:33:14.436Z,1747254794.436 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203313.00,A,3648.38936,N,12149.36942,W,1.575,262.83,140525,,,D*7E 2025-05-14T20:33:14.438Z,1747254794.438 [NAL9602](INFO): GPS fix at 20250514T203313: (36.806489, -121.822824) 2025-05-14T20:33:14.449Z,1747254794.449 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T20:33:14.449Z,1747254794.449 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T20:33:22.436Z,1747254802.436 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250514T193200/Courier0021.lzma 2025-05-14T20:33:23.439Z,1747254803.439 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Courier0021.lzma.bak 2025-05-14T20:33:23.439Z,1747254803.439 [DataOverHttps](INFO): SBD MOMSN=24876303 2025-05-14T20:33:39.901Z,1747254819.901 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250514T193200/Express0022.lzma 2025-05-14T20:33:40.902Z,1747254820.902 [DataOverHttps](INFO): Moved sent file to Logs/20250514T193200/Express0022.lzma.bak 2025-05-14T20:33:40.903Z,1747254820.903 [DataOverHttps](INFO): SBD MOMSN=24876306 2025-05-14T20:33:42.315Z,1747254822.315 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T20:33:42.315Z,1747254822.315 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-14T20:33:42.315Z,1747254822.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-14T20:35:09.170Z,1747254909.170 [NAL9602](INFO): SBD MO Status=2, MOMSN=20717, MT Status=2, MTMSN=0 2025-05-14T20:35:09.170Z,1747254909.170 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T20:35:21.634Z,1747254921.634 [DataOverHttps](IMPORTANT): SBD MTMSN=20250514T203520 2025-05-14T20:35:29.168Z,1747254929.168 [DataOverHttps](INFO): Received command: restart logs