2025-03-14T13:33:05.329Z,1741959185.329 [CommandExec](IMPORTANT): got command restart logs
2025-03-14T13:33:06.431Z,1741959186.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133300.00,A,3647.85049,N,12150.78725,W,0.641,330.78,140325,,,A*7D
2025-03-14T13:33:06.438Z,1741959186.438 [NAL9602](INFO): GPS fix at 20250314T133300: (36.797508, -121.846454)
2025-03-14T13:33:06.455Z,1741959186.455 [keepstation:NeedComms:C] Stopped
2025-03-14T13:33:06.455Z,1741959186.455 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T13:33:20.493Z,1741959200.493 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:33:30.391Z,1741959210.391 [NAL9602](INFO): SBD MO Status=2, MOMSN=19159, MT Status=2, MTMSN=0
2025-03-14T13:33:30.391Z,1741959210.391 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T13:33:49.136Z,1741959229.136 [NAL9602](INFO): SBD MO Status=1, MOMSN=19159, MT Status=0, MTMSN=0
2025-03-14T13:33:49.185Z,1741959229.185 [NAL9602](INFO): Sent 78 bytes from file Logs/20250313T231113/Courier0027.lzma
2025-03-14T13:33:49.185Z,1741959229.185 [NAL9602](INFO): Packets left to send: 0
2025-03-14T13:33:51.616Z,1741959231.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:34:01.018Z,1741959241.018 [NAL9602](INFO): SBD MO Status=1, MOMSN=19160, MT Status=0, MTMSN=0
2025-03-14T13:34:01.080Z,1741959241.080 [NAL9602](INFO): Sent 332 bytes from file Logs/20250313T231113/Express0028.lzma
2025-03-14T13:34:01.080Z,1741959241.080 [NAL9602](INFO): Packets left to send: 1
2025-03-14T13:34:12.084Z,1741959252.084 [NAL9602](INFO): SBD MO Status=1, MOMSN=19161, MT Status=0, MTMSN=0
2025-03-14T13:34:12.136Z,1741959252.136 [NAL9602](INFO): Sent 109 bytes from file Logs/20250313T231113/Express0028.lzma
2025-03-14T13:34:12.136Z,1741959252.136 [NAL9602](INFO): Packets left to send: 0
2025-03-14T13:34:21.759Z,1741959261.759 [NAL9602](INFO): SBD MO Status=0, MOMSN=19162, MT Status=0, MTMSN=0
2025-03-14T13:34:22.740Z,1741959262.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:34:33.572Z,1741959273.572 [NAL9602](INFO): SBD MO Status=1, MOMSN=19163, MT Status=0, MTMSN=0
2025-03-14T13:34:33.622Z,1741959273.622 [NAL9602](INFO): Sent 272 bytes from file Logs/20250314T133305/Courier0003.lzma
2025-03-14T13:34:33.622Z,1741959273.622 [NAL9602](INFO): Packets left to send: 0
2025-03-14T13:34:41.867Z,1741959281.867 [NAL9602](INFO): SBD MO Status=1, MOMSN=19164, MT Status=0, MTMSN=0
2025-03-14T13:34:41.920Z,1741959281.920 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0004.lzma
2025-03-14T13:34:41.920Z,1741959281.920 [NAL9602](INFO): Packets left to send: 1
2025-03-14T13:34:50.851Z,1741959290.851 [NAL9602](INFO): SBD MO Status=1, MOMSN=19165, MT Status=0, MTMSN=0
2025-03-14T13:34:50.908Z,1741959290.908 [NAL9602](INFO): Sent 129 bytes from file Logs/20250314T133305/Express0004.lzma
2025-03-14T13:34:50.908Z,1741959290.908 [NAL9602](INFO): Packets left to send: 0
2025-03-14T13:34:53.872Z,1741959293.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:34:56.910Z,1741959296.910 [NAL9602](INFO): SBD MO Status=0, MOMSN=19166, MT Status=0, MTMSN=0
2025-03-14T13:34:56.999Z,1741959296.999 [keepstation:NeedComms:D] Stopped
2025-03-14T13:34:57.000Z,1741959297.000 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T13:34:59.378Z,1741959299.378 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133453.00,A,3647.86285,N,12150.79061,W,0.991,306.96,140325,,,A*7C
2025-03-14T13:34:59.380Z,1741959299.380 [NAL9602](INFO): GPS fix at 20250314T133453: (36.797714, -121.846510)
2025-03-14T13:34:59.418Z,1741959299.418 [keepstation:NeedComms:E] Stopped
2025-03-14T13:34:59.419Z,1741959299.419 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T13:34:59.424Z,1741959299.424 [keepstation:NeedComms] Stopped
2025-03-14T13:34:59.424Z,1741959299.424 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T13:34:59.424Z,1741959299.424 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T13:34:59.424Z,1741959299.424 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T13:34:59.772Z,1741959299.772 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-03-14T13:34:59.772Z,1741959299.772 [keepstation:OnStation] Stopped
2025-03-14T13:34:59.772Z,1741959299.772 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation] Running Loop=1
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation:A] Running Loop=1
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-03-14T13:34:59.773Z,1741959299.773 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-03-14T13:34:59.774Z,1741959299.774 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-03-14T13:35:00.151Z,1741959300.151 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 12.41 mm.
2025-03-14T13:35:00.157Z,1741959300.157 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-03-14T13:35:00.157Z,1741959300.157 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-03-14T13:35:00.158Z,1741959300.158 [keepstation:KeepStation:A] Running Loop=1
2025-03-14T13:35:24.996Z,1741959324.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:35:31.258Z,1741959331.258 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T13:35:56.132Z,1741959356.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:36:27.256Z,1741959387.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:36:58.380Z,1741959418.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:37:29.524Z,1741959449.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:38:00.656Z,1741959480.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:38:31.800Z,1741959511.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:38:45.264Z,1741959525.264 [Radio_Surface](INFO): Powering down
2025-03-14T13:38:45.669Z,1741959525.669 [Radio_Surface](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-03-14T13:38:45.669Z,1741959525.669 [Radio_Surface] Hardware Fault, FailCount= 1
2025-03-14T13:38:45.669Z,1741959525.669 [Radio_Surface](ERROR): Hardware Fault
2025-03-14T13:38:46.008Z,1741959526.008 [CBIT](ERROR): Hardware Fault in component: Radio_Surface
2025-03-14T13:38:46.441Z,1741959526.441 [CBIT](INFO): Clearing failed state for component Radio_Surface
2025-03-14T13:38:46.441Z,1741959526.441 [Radio_Surface] No Fault, FailCount= 1
2025-03-14T13:38:46.476Z,1741959526.476 [Radio_Surface](INFO): Powering up
2025-03-14T13:38:52.536Z,1741959532.536 [Radio_Surface](INFO): Powering down
2025-03-14T13:39:02.928Z,1741959542.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T13:39:03.932Z,1741959543.932 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T13:41:09.819Z,1741959669.819 [DVL_micro](ERROR): Failed to parse:
:BI,+00161,+00090,-00010,+00000,A
2025-03-14T13:41:11.018Z,1741959671.018 [DVL_micro](ERROR): Failed to parse:
:BI,-00457,+00000,+00000,+00000,I
2025-03-14T13:57:49.761Z,1741960669.761 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-14T13:57:54.231Z,1741960674.231 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.001642
CHAN A1 (24V): 0.011165
CHAN A2 (12V): -0.020905
CHAN A3 (5V): -0.045180
CHAN B0 (3.3V): -0.005443
CHAN B1 (3.15aV): -0.003138
CHAN B2 (3.15bV): -0.043840
CHAN B3 (GND): -0.004794
OPEN: 0.012348
Full Scale: +/- 1 mA
2025-03-14T14:08:10.286Z,1741961290.286 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-03-14T14:08:10.287Z,1741961290.287 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2025-03-14T14:08:10.290Z,1741961290.290 [BPC1](INFO): Received data from all battery sticks.
2025-03-14T14:08:13.551Z,1741961293.551 [keepstation:NeedComms] Running Loop=1
2025-03-14T14:08:13.551Z,1741961293.551 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T14:08:13.551Z,1741961293.551 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T14:08:13.551Z,1741961293.551 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T14:08:13.552Z,1741961293.552 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T14:08:13.552Z,1741961293.552 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T14:08:13.553Z,1741961293.553 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T14:08:13.553Z,1741961293.553 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T14:08:13.554Z,1741961293.554 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T14:08:13.554Z,1741961293.554 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T14:08:13.554Z,1741961293.554 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T14:08:13.572Z,1741961293.572 [keepstation:NeedComms:A](INFO): last time_fix was: 1741959293.000000 second since 1970/01/01T00:00:00Z
2025-03-14T14:08:13.572Z,1741961293.572 [keepstation:NeedComms:A] Stopped
2025-03-14T14:09:36.052Z,1741961376.052 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T14:09:36.552Z,1741961376.552 [Radio_Surface](INFO): Powering up
2025-03-14T14:09:42.516Z,1741961382.516 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T14:10:10.779Z,1741961410.779 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-03-14T14:10:12.644Z,1741961412.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:10:43.785Z,1741961443.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:10:44.703Z,1741961444.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=19167, MT Status=2, MTMSN=0
2025-03-14T14:10:44.703Z,1741961444.703 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T14:11:14.908Z,1741961474.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:11:17.405Z,1741961477.405 [NAL9602](INFO): SBD MO Status=0, MOMSN=19167, MT Status=0, MTMSN=0
2025-03-14T14:11:17.406Z,1741961477.406 [NAL9602](INFO): No messages in MT queue
2025-03-14T14:11:18.640Z,1741961478.640 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141113.00,A,3647.88802,N,12150.73327,W,1.089,276.32,140325,,,A*71
2025-03-14T14:11:18.643Z,1741961478.643 [NAL9602](INFO): GPS fix at 20250314T141113: (36.798134, -121.845555)
2025-03-14T14:11:18.699Z,1741961478.699 [keepstation:NeedComms:C] Stopped
2025-03-14T14:11:18.699Z,1741961478.699 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T14:11:37.272Z,1741961497.272 [NAL9602](INFO): SBD MO Status=1, MOMSN=19168, MT Status=0, MTMSN=0
2025-03-14T14:11:37.332Z,1741961497.332 [NAL9602](INFO): Sent 205 bytes from file Logs/20250314T133305/Courier0006.lzma
2025-03-14T14:11:37.333Z,1741961497.333 [NAL9602](INFO): Packets left to send: 0
2025-03-14T14:11:46.040Z,1741961506.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:11:52.235Z,1741961512.235 [NAL9602](INFO): SBD MO Status=1, MOMSN=19169, MT Status=0, MTMSN=0
2025-03-14T14:11:52.284Z,1741961512.284 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0007.lzma
2025-03-14T14:11:52.284Z,1741961512.284 [NAL9602](INFO): Packets left to send: 2
2025-03-14T14:11:56.044Z,1741961516.044 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003222
2025-03-14T14:12:03.770Z,1741961523.770 [DataOverHttps](INFO): Sending 669 bytes from file Logs/20250314T133305/Express0007.lzma
2025-03-14T14:12:04.147Z,1741961524.147 [NAL9602](INFO): SBD MO Status=1, MOMSN=19170, MT Status=0, MTMSN=0
2025-03-14T14:12:04.204Z,1741961524.204 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0007.lzma
2025-03-14T14:12:04.204Z,1741961524.204 [NAL9602](INFO): Packets left to send: 1
2025-03-14T14:12:04.762Z,1741961524.762 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0007.lzma.bak
2025-03-14T14:12:04.762Z,1741961524.762 [DataOverHttps](INFO): SBD MOMSN=24228722
2025-03-14T14:12:06.187Z,1741961526.187 [keepstation:NeedComms:D] Stopped
2025-03-14T14:12:06.188Z,1741961526.188 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T14:12:08.187Z,1741961528.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,141202.00,A,3647.89159,N,12150.73571,W,0.914,17.23,140325,,,A*48
2025-03-14T14:12:08.190Z,1741961528.190 [NAL9602](INFO): GPS fix at 20250314T141202: (36.798193, -121.845595)
2025-03-14T14:12:08.236Z,1741961528.236 [keepstation:NeedComms:E] Stopped
2025-03-14T14:12:08.237Z,1741961528.237 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T14:12:08.237Z,1741961528.237 [keepstation:NeedComms] Stopped
2025-03-14T14:12:08.237Z,1741961528.237 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T14:12:08.237Z,1741961528.237 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T14:12:08.237Z,1741961528.237 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T14:12:40.543Z,1741961560.543 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T14:13:31.415Z,1741961611.415 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.,+00000,A
2025-03-14T14:13:31.415Z,1741961611.415 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.,+00000,A
2025-03-14T14:15:39.704Z,1741961739.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:16:10.828Z,1741961770.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:16:18.956Z,1741961778.956 [Radio_Surface](INFO): Powering down
2025-03-14T14:16:41.952Z,1741961801.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:16:42.972Z,1741961802.972 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T14:17:47.240Z,1741961867.240 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759247
2025-03-14T14:18:06.142Z,1741961886.142 [DVL_micro](ERROR): Failed to parse:
:SA,+00.99,+01.83,138.6
2025-03-14T14:18:07.350Z,1741961887.350 [DVL_micro](ERROR): Failed to parse:
:SA,+00.90,+02.13,139.2
2025-03-14T14:20:29.308Z,1741962029.308 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-03-14T14:20:29.308Z,1741962029.308 [DVL_micro] Communications Fault, FailCount= 1
2025-03-14T14:20:29.308Z,1741962029.308 [DVL_micro](ERROR): Communications Fault
2025-03-14T14:20:29.309Z,1741962029.309 [DVL_micro](ERROR): Failed to parse:
2025-03-14T14:20:29.336Z,1741962029.336 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-03-14T14:20:29.652Z,1741962029.652 [DVL_micro](INFO): Powering down
2025-03-14T14:20:30.395Z,1741962030.395 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-03-14T14:20:30.395Z,1741962030.395 [DVL_micro] No Fault, FailCount= 1
2025-03-14T14:22:51.774Z,1741962171.774 [DVL_micro](ERROR): Failed to parse:
:BI,+00026,+00000,+00000,+00000,I
2025-03-14T14:23:02.678Z,1741962182.678 [DVL_micro](ERROR): Failed to parse:
:BI,-00008,-00080,+00040,+00000,I
2025-03-14T14:25:35.387Z,1741962335.387 [DVL_micro](ERROR): Failed to parse:
:WI,+00069,-00001,+00008,+00000,A
2025-03-14T14:36:36.747Z,1741962996.747 [DVL_micro](ERROR): Failed to parse:
:BI,+00020,-00640,+00014,+00000,I
2025-03-14T14:47:42.958Z,1741963662.958 [keepstation:NeedComms] Running Loop=1
2025-03-14T14:47:42.958Z,1741963662.958 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T14:47:42.959Z,1741963662.959 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T14:47:42.959Z,1741963662.959 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T14:47:42.959Z,1741963662.959 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T14:47:42.959Z,1741963662.959 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T14:47:42.976Z,1741963662.976 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T14:47:42.976Z,1741963662.976 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T14:47:42.977Z,1741963662.977 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T14:47:42.977Z,1741963662.977 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T14:47:42.977Z,1741963662.977 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T14:47:42.979Z,1741963662.979 [keepstation:NeedComms:A](INFO): last time_fix was: 1741961522.000000 second since 1970/01/01T00:00:00Z
2025-03-14T14:47:42.979Z,1741963662.979 [keepstation:NeedComms:A] Stopped
2025-03-14T14:47:56.784Z,1741963676.784 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-03-14T14:49:08.427Z,1741963748.427 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T14:49:08.880Z,1741963748.880 [Radio_Surface](INFO): Powering up
2025-03-14T14:49:11.697Z,1741963751.697 [DVL_micro](ERROR): Failed to parse:
:WI,-00648,+00971,-00311,+00000,A
2025-03-14T14:49:12.889Z,1741963752.889 [DVL_micro](ERROR): Failed to parse:
:RD,+0000.30,+0001.10,+0000.34,+9999.99
2025-03-14T14:49:15.036Z,1741963755.036 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T14:49:19.001Z,1741963759.001 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-03-14T14:49:35.123Z,1741963775.123 [NAL9602](INFO): SBD MO Status=0, MOMSN=19171, MT Status=0, MTMSN=0
2025-03-14T14:49:35.124Z,1741963775.124 [NAL9602](INFO): No messages in MT queue
2025-03-14T14:49:36.335Z,1741963776.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144930.00,A,3647.95612,N,12150.68188,W,0.855,332.45,140325,,,A*7A
2025-03-14T14:49:36.337Z,1741963776.337 [NAL9602](INFO): GPS fix at 20250314T144930: (36.799269, -121.844698)
2025-03-14T14:49:36.383Z,1741963776.383 [keepstation:NeedComms:C] Stopped
2025-03-14T14:49:36.383Z,1741963776.383 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T14:49:45.156Z,1741963785.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:49:53.155Z,1741963793.155 [NAL9602](INFO): SBD MO Status=1, MOMSN=19172, MT Status=0, MTMSN=0
2025-03-14T14:49:53.216Z,1741963793.216 [NAL9602](INFO): Sent 76 bytes from file Logs/20250314T133305/Courier0009.lzma
2025-03-14T14:49:53.217Z,1741963793.217 [NAL9602](INFO): Packets left to send: 0
2025-03-14T14:50:03.905Z,1741963803.905 [NAL9602](INFO): SBD MO Status=1, MOMSN=19173, MT Status=0, MTMSN=0
2025-03-14T14:50:03.953Z,1741963803.953 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0010.lzma
2025-03-14T14:50:03.953Z,1741963803.953 [NAL9602](INFO): Packets left to send: 0
2025-03-14T14:50:16.280Z,1741963816.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:50:22.899Z,1741963822.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=19174, MT Status=2, MTMSN=0
2025-03-14T14:50:22.899Z,1741963822.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T14:50:39.043Z,1741963839.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=19174, MT Status=2, MTMSN=0
2025-03-14T14:50:39.043Z,1741963839.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T14:50:47.408Z,1741963847.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:50:49.543Z,1741963849.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=19174, MT Status=0, MTMSN=0
2025-03-14T14:50:49.623Z,1741963849.623 [keepstation:NeedComms:D] Stopped
2025-03-14T14:50:49.623Z,1741963849.623 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T14:50:51.961Z,1741963851.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145046.00,A,3647.96339,N,12150.68525,W,0.758,310.11,140325,,,A*7C
2025-03-14T14:50:51.963Z,1741963851.963 [NAL9602](INFO): GPS fix at 20250314T145046: (36.799390, -121.844754)
2025-03-14T14:50:51.977Z,1741963851.977 [keepstation:NeedComms:E] Stopped
2025-03-14T14:50:51.978Z,1741963851.978 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T14:50:51.978Z,1741963851.978 [keepstation:NeedComms] Stopped
2025-03-14T14:50:51.978Z,1741963851.978 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T14:50:51.978Z,1741963851.978 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T14:50:51.978Z,1741963851.978 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T14:51:18.532Z,1741963878.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:51:23.998Z,1741963883.998 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T14:51:28.173Z,1741963888.173 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003511
2025-03-14T14:51:52.882Z,1741963912.882 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 12.41 mm (2 active estimators).
2025-03-14T14:52:20.392Z,1741963940.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:52:38.689Z,1741963958.689 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-03-14T14:52:51.516Z,1741963971.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:52:53.136Z,1741963973.136 [Radio_Surface](INFO): Powering down
2025-03-14T14:53:22.642Z,1741964002.642 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T14:53:23.644Z,1741964003.644 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T14:54:28.909Z,1741964068.909 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739395
2025-03-14T14:58:29.830Z,1741964309.830 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2213,HPR45,EXT,TILT
2025-03-14T14:58:59.734Z,1741964339.734 [DVL_micro](ERROR): Failed to parse:
:SA,-01.44,+02.65,215.7
2025-03-14T15:01:45.426Z,1741964505.426 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-03-14T15:14:36.243Z,1741965276.243 [DVL_micro](ERROR): Failed to parse:
:WI,-00054,-00062,-00345,+00000,A
2025-03-14T15:15:49.369Z,1741965349.369 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-03-14T15:15:49.370Z,1741965349.370 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2025-03-14T15:15:49.373Z,1741965349.373 [BPC1](INFO): Received data from all battery sticks.
2025-03-14T15:19:37.225Z,1741965577.225 [DVL_micro](ERROR): Failed to parse::SA,-02.95,+01.69,089.3
2025-03-14T15:22:39.053Z,1741965759.053 [keepstation:NeedComms] Running Loop=1
2025-03-14T15:22:39.053Z,1741965759.053 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T15:22:39.053Z,1741965759.053 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T15:22:39.053Z,1741965759.053 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T15:22:39.054Z,1741965759.054 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T15:22:39.054Z,1741965759.054 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T15:22:39.055Z,1741965759.055 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T15:22:39.057Z,1741965759.057 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T15:22:39.059Z,1741965759.059 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T15:22:39.060Z,1741965759.060 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T15:22:39.061Z,1741965759.061 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T15:22:39.062Z,1741965759.062 [keepstation:NeedComms:A](INFO): last time_fix was: 1741963846.000000 second since 1970/01/01T00:00:00Z
2025-03-14T15:22:39.062Z,1741965759.062 [keepstation:NeedComms:A] Stopped
2025-03-14T15:24:04.018Z,1741965844.018 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T15:24:04.495Z,1741965844.495 [Radio_Surface](INFO): Powering up
2025-03-14T15:24:10.348Z,1741965850.348 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T15:24:31.519Z,1741965871.519 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-03-14T15:24:40.480Z,1741965880.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:25:07.850Z,1741965907.850 [NAL9602](INFO): SBD MO Status=0, MOMSN=19175, MT Status=0, MTMSN=0
2025-03-14T15:25:07.850Z,1741965907.850 [NAL9602](INFO): No messages in MT queue
2025-03-14T15:25:09.046Z,1741965909.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152503.00,A,3647.81129,N,12150.86709,W,1.186,0.49,140325,,,A*7C
2025-03-14T15:25:09.058Z,1741965909.058 [NAL9602](INFO): GPS fix at 20250314T152503: (36.796855, -121.847785)
2025-03-14T15:25:09.073Z,1741965909.073 [UniversalFixResidualReporter](INFO): Fix residual: 2.2 %DT, over the last 614.0 m. Residual distance 13.2 m at bearing -83.0 degrees. Fix at (36.7969, -121.8478) with 390.2 m made good.
2025-03-14T15:25:09.079Z,1741965909.079 [keepstation:NeedComms:C] Stopped
2025-03-14T15:25:09.079Z,1741965909.079 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T15:25:11.605Z,1741965911.605 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:25:35.046Z,1741965935.046 [NAL9602](INFO): SBD MO Status=1, MOMSN=19176, MT Status=0, MTMSN=0
2025-03-14T15:25:35.096Z,1741965935.096 [NAL9602](INFO): Sent 221 bytes from file Logs/20250314T133305/Courier0012.lzma
2025-03-14T15:25:35.096Z,1741965935.096 [NAL9602](INFO): Packets left to send: 0
2025-03-14T15:25:42.728Z,1741965942.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:25:53.030Z,1741965953.030 [NAL9602](INFO): SBD MO Status=1, MOMSN=19177, MT Status=0, MTMSN=0
2025-03-14T15:25:53.080Z,1741965953.080 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0013.lzma
2025-03-14T15:25:53.080Z,1741965953.080 [NAL9602](INFO): Packets left to send: 1
2025-03-14T15:25:59.490Z,1741965959.490 [NAL9602](INFO): SBD MO Status=1, MOMSN=19178, MT Status=0, MTMSN=0
2025-03-14T15:25:59.548Z,1741965959.548 [NAL9602](INFO): Sent 33 bytes from file Logs/20250314T133305/Express0013.lzma
2025-03-14T15:25:59.548Z,1741965959.548 [NAL9602](INFO): Packets left to send: 0
2025-03-14T15:26:06.790Z,1741965966.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=19179, MT Status=0, MTMSN=0
2025-03-14T15:26:06.903Z,1741965966.903 [keepstation:NeedComms:D] Stopped
2025-03-14T15:26:06.908Z,1741965966.908 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T15:26:09.203Z,1741965969.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152603.00,A,3647.81886,N,12150.87187,W,0.875,354.46,140325,,,A*7B
2025-03-14T15:26:09.205Z,1741965969.205 [NAL9602](INFO): GPS fix at 20250314T152603: (36.796981, -121.847865)
2025-03-14T15:26:09.255Z,1741965969.255 [keepstation:NeedComms:E] Stopped
2025-03-14T15:26:09.272Z,1741965969.272 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T15:26:09.272Z,1741965969.272 [keepstation:NeedComms] Stopped
2025-03-14T15:26:09.272Z,1741965969.272 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T15:26:09.272Z,1741965969.272 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T15:26:09.273Z,1741965969.273 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T15:26:13.861Z,1741965973.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:26:22.712Z,1741965982.712 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003164
2025-03-14T15:26:41.509Z,1741966001.509 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T15:28:30.193Z,1741966110.193 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T15:29:05.742Z,1741966145.742 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11+00169,+00000,A
2025-03-14T15:29:05.742Z,1741966145.742 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11+00169,+00000,A
2025-03-14T15:29:18.520Z,1741966158.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:29:40.660Z,1741966180.660 [Radio_Surface](INFO): Powering down
2025-03-14T15:29:49.657Z,1741966189.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:29:50.660Z,1741966190.660 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T15:31:26.048Z,1741966286.048 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.751266
2025-03-14T15:38:30.530Z,1741966710.530 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T15:46:09.148Z,1741967169.148 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-03-14T15:46:09.148Z,1741967169.148 [DVL_micro] Communications Fault, FailCount= 1
2025-03-14T15:46:09.148Z,1741967169.148 [DVL_micro](ERROR): Communications Fault
2025-03-14T15:46:09.148Z,1741967169.148 [DVL_micro](ERROR): Failed to parse:
2025-03-14T15:46:09.237Z,1741967169.237 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-03-14T15:46:09.572Z,1741967169.572 [DVL_micro](INFO): Powering down
2025-03-14T15:46:10.454Z,1741967170.454 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-03-14T15:46:10.455Z,1741967170.455 [DVL_micro] No Fault, FailCount= 1
2025-03-14T15:48:30.883Z,1741967310.883 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T15:49:11.306Z,1741967351.306 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+9999.999,+9999.99,+9999.99
2025-03-14T15:49:11.306Z,1741967351.306 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.999,+9999.99,+9999.99
2025-03-14T15:56:09.442Z,1741967769.442 [keepstation:NeedComms] Running Loop=1
2025-03-14T15:56:09.442Z,1741967769.442 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T15:56:09.442Z,1741967769.442 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T15:56:09.442Z,1741967769.442 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T15:56:09.443Z,1741967769.443 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T15:56:09.443Z,1741967769.443 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T15:56:09.443Z,1741967769.443 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T15:56:09.445Z,1741967769.445 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T15:56:09.447Z,1741967769.447 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T15:56:09.447Z,1741967769.447 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T15:56:09.448Z,1741967769.448 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T15:56:09.452Z,1741967769.452 [keepstation:NeedComms:A](INFO): last time_fix was: 1741965963.000000 second since 1970/01/01T00:00:00Z
2025-03-14T15:56:09.452Z,1741967769.452 [keepstation:NeedComms:A] Stopped
2025-03-14T15:57:42.727Z,1741967862.727 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T15:57:43.412Z,1741967863.412 [Radio_Surface](INFO): Powering up
2025-03-14T15:57:46.386Z,1741967866.386 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-03-14T15:57:46.386Z,1741967866.386 [NAL9602] Hardware Fault, FailCount= 1
2025-03-14T15:57:46.386Z,1741967866.386 [NAL9602](ERROR): Hardware Fault
2025-03-14T15:57:46.496Z,1741967866.496 [CBIT](ERROR): Hardware Fault in component: NAL9602
2025-03-14T15:57:46.781Z,1741967866.781 [NAL9602](INFO): Powering down
2025-03-14T15:57:47.688Z,1741967867.688 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-14T15:57:47.688Z,1741967867.688 [NAL9602] No Fault, FailCount= 1
2025-03-14T15:57:49.624Z,1741967869.624 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T15:58:17.173Z,1741967897.173 [NAL9602](INFO): Powering up NAL9602
2025-03-14T15:58:19.756Z,1741967899.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:58:28.080Z,1741967908.080 [NAL9602](INFO): NAL9602 initialized
2025-03-14T15:58:30.918Z,1741967910.918 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T15:58:33.364Z,1741967913.364 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-03-14T15:58:50.888Z,1741967930.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:58:57.572Z,1741967937.572 [DVL_micro](ERROR): Failed to parse:
:SA,-13.30,-02.58,316.2
2025-03-14T15:58:59.178Z,1741967939.178 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-03-14T15:59:22.024Z,1741967962.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T15:59:27.466Z,1741967967.466 [NAL9602](INFO): SBD MO Status=2, MOMSN=19180, MT Status=2, MTMSN=0
2025-03-14T15:59:27.467Z,1741967967.467 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T15:59:47.259Z,1741967987.259 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19180, MT Status=1, MTMSN=4258
2025-03-14T15:59:47.260Z,1741967987.260 [NAL9602](INFO): Data available in MT queue
2025-03-14T15:59:47.773Z,1741967987.773 [NAL9602](INFO): Received command: stop
2025-03-14T15:59:47.797Z,1741967987.797 [CommandExec](IMPORTANT): got command stop
2025-03-14T15:59:47.799Z,1741967987.799 [CommandExec](IMPORTANT): Scheduling is paused
2025-03-14T15:59:47.800Z,1741967987.800 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-03-14T15:59:48.098Z,1741967988.098 [MissionManager](INFO): MissionManager is completed.
2025-03-14T15:59:48.098Z,1741967988.098 [MissionManager](INFO): Uninitializing Mission keepstation
2025-03-14T15:59:48.098Z,1741967988.098 [keepstation] Stopped
2025-03-14T15:59:48.098Z,1741967988.098 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-03-14T15:59:48.098Z,1741967988.098 [keepstation:NeedComms] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:NeedComms:C] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-03-14T15:59:48.099Z,1741967988.099 [keepstation:BackseatDriver] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:PowerOnly] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:PowerOnly:B] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:PowerOnly:E.Wait] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:H.Pitch] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:KeepStation] Stopped
2025-03-14T15:59:48.104Z,1741967988.104 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-03-14T15:59:48.105Z,1741967988.105 [keepstation:KeepStation:A] Stopped
2025-03-14T15:59:48.105Z,1741967988.105 [keepstation:KeepStation:B.Pitch] Stopped
2025-03-14T15:59:48.105Z,1741967988.105 [keepstation:KeepStation:C.KeepStation] Stopped
2025-03-14T15:59:48.105Z,1741967988.105 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-03-14T15:59:48.105Z,1741967988.105 [keepstation:KeepStation:D.Wait] Stopped
2025-03-14T15:59:48.105Z,1741967988.105 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T15:59:48.519Z,1741967988.519 [MissionManager](IMPORTANT): Started mission Default
2025-03-14T15:59:48.519Z,1741967988.519 [Default] Running Loop=1
2025-03-14T15:59:48.519Z,1741967988.519 [Default](DEBUG): Aggregate::initialize Default
2025-03-14T15:59:48.520Z,1741967988.520 [Default:B.GoToSurface] Running Loop=1
2025-03-14T15:59:48.520Z,1741967988.520 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T15:59:48.520Z,1741967988.520 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-14T15:59:48.521Z,1741967988.521 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-14T15:59:48.521Z,1741967988.521 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-14T15:59:48.522Z,1741967988.522 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-14T15:59:48.522Z,1741967988.522 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T15:59:48.523Z,1741967988.523 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T15:59:48.523Z,1741967988.523 [Default:A.Wait] Running Loop=1
2025-03-14T15:59:48.525Z,1741967988.525 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-14T15:59:48.888Z,1741967988.888 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155940.00,A,3647.92300,N,12150.91648,W,0.603,322.16,140325,,,A*7B
2025-03-14T15:59:48.890Z,1741967988.890 [NAL9602](INFO): GPS fix at 20250314T155940: (36.798717, -121.848608)
2025-03-14T15:59:53.164Z,1741967993.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:00:01.764Z,1741968001.764 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003144
2025-03-14T16:00:01.817Z,1741968001.817 [Default:A.Wait](INFO): Done Waiting.
2025-03-14T16:00:01.817Z,1741968001.817 [Default:A.Wait] Stopped
2025-03-14T16:00:01.817Z,1741968001.817 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T16:00:02.220Z,1741968002.220 [Default:CheckIn] Running Loop=1
2025-03-14T16:00:02.221Z,1741968002.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-14T16:00:02.221Z,1741968002.221 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-14T16:00:04.221Z,1741968004.221 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155955.00,A,3647.92354,N,12150.91773,W,0.525,314.63,140325,,,A*77
2025-03-14T16:00:04.224Z,1741968004.224 [NAL9602](INFO): GPS fix at 20250314T155955: (36.798726, -121.848629)
2025-03-14T16:00:04.234Z,1741968004.234 [Default:CheckIn:Read_GPS] Stopped
2025-03-14T16:00:04.234Z,1741968004.234 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-14T16:00:11.908Z,1741968011.908 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250314T133305/Courier0015.lzma
2025-03-14T16:00:12.602Z,1741968012.602 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0015.lzma.bak
2025-03-14T16:00:12.602Z,1741968012.602 [DataOverHttps](INFO): SBD MOMSN=24228915
2025-03-14T16:00:28.845Z,1741968028.845 [DataOverHttps](INFO): Sending 666 bytes from file Logs/20250314T133305/Express0016.lzma
2025-03-14T16:00:29.846Z,1741968029.846 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0016.lzma.bak
2025-03-14T16:00:29.846Z,1741968029.846 [DataOverHttps](INFO): SBD MOMSN=24228919
2025-03-14T16:00:31.351Z,1741968031.351 [Default:CheckIn:Read_Iridium] Stopped
2025-03-14T16:00:31.352Z,1741968031.352 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-14T16:00:31.352Z,1741968031.352 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-14T16:00:36.196Z,1741968036.196 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T16:01:18.622Z,1741968078.622 [PowerOnly](INFO): Powering down loadControl
2025-03-14T16:05:31.958Z,1741968331.958 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-14T16:05:31.958Z,1741968331.958 [Default:CheckIn:C.Wait] Stopped
2025-03-14T16:05:31.958Z,1741968331.958 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T16:05:31.958Z,1741968331.958 [Default:CheckIn:D] Running Loop=1
2025-03-14T16:05:32.376Z,1741968332.376 [Default:CheckIn:D] Stopped
2025-03-14T16:05:32.376Z,1741968332.376 [Default:CheckIn:E] Running Loop=1
2025-03-14T16:05:32.777Z,1741968332.777 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.730940 min
2025-03-14T16:05:32.777Z,1741968332.777 [Default:CheckIn:E] Stopped
2025-03-14T16:05:32.777Z,1741968332.777 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-14T16:05:32.777Z,1741968332.777 [Default:CheckIn] Stopped
2025-03-14T16:05:32.777Z,1741968332.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-14T16:05:32.778Z,1741968332.778 [Default:CheckIn](INFO): Running loop #2
2025-03-14T16:05:32.778Z,1741968332.778 [Default:CheckIn] Running Loop=2
2025-03-14T16:05:32.778Z,1741968332.778 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-14T16:05:32.778Z,1741968332.778 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-14T16:05:34.779Z,1741968334.779 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160526.00,A,3647.95463,N,12150.95464,W,0.680,304.52,140325,,,D*76
2025-03-14T16:05:34.781Z,1741968334.781 [NAL9602](INFO): GPS fix at 20250314T160526: (36.799244, -121.849244)
2025-03-14T16:05:34.791Z,1741968334.791 [Default:CheckIn:Read_GPS] Stopped
2025-03-14T16:05:34.791Z,1741968334.791 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-14T16:05:44.775Z,1741968344.775 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250314T133305/Courier0018.lzma
2025-03-14T16:05:45.778Z,1741968345.778 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0018.lzma.bak
2025-03-14T16:05:45.778Z,1741968345.778 [DataOverHttps](INFO): SBD MOMSN=24228937
2025-03-14T16:05:46.109Z,1741968346.109 [DAT](INFO): DAT read: 16:05:43.1422
2025-03-14T16:05:46.110Z,1741968346.110 [DAT](INFO): unknown deviceResponse_: 16:05:43.1422
2025-03-14T16:05:52.957Z,1741968352.957 [NAL9602](INFO): SBD MO Status=0, MOMSN=19181, MT Status=0, MTMSN=0
2025-03-14T16:05:52.957Z,1741968352.957 [NAL9602](INFO): No messages in MT queue
2025-03-14T16:05:56.697Z,1741968356.697 [DAT](INFO): DAT read: Response Not Received
2025-03-14T16:05:56.697Z,1741968356.697 [DAT](INFO): response not received
2025-03-14T16:05:56.697Z,1741968356.697 [DAT](INFO): DAT read: diag:49>
2025-03-14T16:05:56.698Z,1741968356.698 [DAT](INFO): unknown deviceResponse_: diag:49>
2025-03-14T16:05:56.698Z,1741968356.698 [DAT](INFO): DAT read: diag:49>
2025-03-14T16:05:56.698Z,1741968356.698 [DAT](INFO): unknown deviceResponse_: diag:49>
2025-03-14T16:05:56.699Z,1741968356.699 [DAT](ERROR): No response from remote modem.
2025-03-14T16:06:02.768Z,1741968362.768 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20250314T133305/Express0019.lzma
2025-03-14T16:06:03.770Z,1741968363.770 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0019.lzma.bak
2025-03-14T16:06:03.770Z,1741968363.770 [DataOverHttps](INFO): SBD MOMSN=24228946
2025-03-14T16:06:05.506Z,1741968365.506 [Default:CheckIn:Read_Iridium] Stopped
2025-03-14T16:06:05.506Z,1741968365.506 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-14T16:06:05.506Z,1741968365.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-14T16:06:23.665Z,1741968383.665 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T16:08:30.958Z,1741968510.958 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T16:10:50.506Z,1741968650.506 [DataOverHttps](IMPORTANT): SBD MTMSN=20250314T161044
2025-03-14T16:10:58.360Z,1741968658.360 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 2 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.8156 degree;set keepstation.Longitude -121.806 degree;set keepstation.Depth 10 m" 367l0 1 2
2025-03-14T16:10:58.366Z,1741968658.366 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 2 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.8156 degree;set keepstation.Longitude -121.806 degree;set keepstation.Depth 10 m" 367l0 1 2.000000
2025-03-14T16:10:58.366Z,1741968658.366 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=367l0
2025-03-14T16:10:58.367Z,1741968658.367 [CommandExec](IMPORTANT): Scheduled #13 (#1 of 2 with id='367l0'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 2 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.8156 degree;set keepstation.Longitude -121.806 degree;set keepstation.Depth 10 m", ASAP
2025-03-14T16:10:59.362Z,1741968659.362 [DataOverHttps](IMPORTANT): SBD MTMSN=20250314T161053
2025-03-14T16:11:06.116Z,1741968666.116 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-14T16:11:06.116Z,1741968666.116 [Default:CheckIn:C.Wait] Stopped
2025-03-14T16:11:06.116Z,1741968666.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T16:11:06.117Z,1741968666.117 [Default:CheckIn:D] Running Loop=1
2025-03-14T16:11:06.492Z,1741968666.492 [Default:CheckIn:D] Stopped
2025-03-14T16:11:06.493Z,1741968666.493 [Default:CheckIn:E] Running Loop=1
2025-03-14T16:11:06.914Z,1741968666.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.299552 min
2025-03-14T16:11:06.914Z,1741968666.914 [Default:CheckIn:E] Stopped
2025-03-14T16:11:06.914Z,1741968666.914 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-14T16:11:06.914Z,1741968666.914 [Default:CheckIn] Stopped
2025-03-14T16:11:06.914Z,1741968666.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-14T16:11:06.914Z,1741968666.914 [Default:CheckIn](INFO): Running loop #3
2025-03-14T16:11:06.915Z,1741968666.915 [Default:CheckIn] Running Loop=3
2025-03-14T16:11:06.915Z,1741968666.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-14T16:11:06.915Z,1741968666.915 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-14T16:11:07.020Z,1741968667.020 [DataOverHttps](INFO): Received command: sched asap "set keepstation.ApproachDepth 10 m;set keepstation.Speed 1.3 m/s;set keepstation.MaxDepth 20 m;set keepstation.MinOffshore 0.5 km;run" 367l0 2 2
2025-03-14T16:11:07.101Z,1741968667.101 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.ApproachDepth 10 m;set keepstation.Speed 1.3 m/s;set keepstation.MaxDepth 20 m;set keepstation.MinOffshore 0.5 km;run" 367l0 2 2.000000
2025-03-14T16:11:07.101Z,1741968667.101 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=367l0
2025-03-14T16:11:07.102Z,1741968667.102 [CommandExec](IMPORTANT): Scheduled #14 (#2 of 2 with id='367l0'): "set keepstation.ApproachDepth 10 m;set keepstation.Speed 1.3 m/s;set keepstation.MaxDepth 20 m;set keepstation.MinOffshore 0.5 km;run", ASAP
2025-03-14T16:11:07.229Z,1741968667.229 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-03-14T16:11:07.229Z,1741968667.229 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-03-14T16:11:07.229Z,1741968667.229 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-03-14T16:11:07.231Z,1741968667.231 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-03-14T16:11:08.054Z,1741968668.054 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
45
2
2025-03-14T16:11:08.059Z,1741968668.059 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-03-14T16:11:08.061Z,1741968668.061 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-03-14T16:11:08.064Z,1741968668.064 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-03-14T16:11:08.066Z,1741968668.066 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-03-14T16:11:08.068Z,1741968668.068 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-03-14T16:11:08.070Z,1741968668.070 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-03-14T16:11:08.073Z,1741968668.073 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-03-14T16:11:08.075Z,1741968668.075 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-03-14T16:11:08.145Z,1741968668.145 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-03-14T16:11:08.147Z,1741968668.147 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-03-14T16:11:08.153Z,1741968668.153 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-03-14T16:11:08.154Z,1741968668.154 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-03-14T16:11:08.154Z,1741968668.154 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-03-14T16:11:08.155Z,1741968668.155 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-03-14T16:11:08.406Z,1741968668.406 [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-03-14T16:11:08.411Z,1741968668.411 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-03-14T16:11:08.413Z,1741968668.413 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-03-14T16:11:08.415Z,1741968668.415 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-03-14T16:11:08.418Z,1741968668.418 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-03-14T16:11:08.421Z,1741968668.421 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-03-14T16:11:08.423Z,1741968668.423 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-03-14T16:11:08.425Z,1741968668.425 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-03-14T16:11:08.427Z,1741968668.427 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-03-14T16:11:08.433Z,1741968668.433 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-14T16:11:08.451Z,1741968668.451 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-03-14T16:11:08.451Z,1741968668.451 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-03-14T16:11:08.476Z,1741968668.476 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-03-14T16:11:08.694Z,1741968668.694 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-03-14T16:11:08.698Z,1741968668.698 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-03-14T16:11:08.701Z,1741968668.701 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-03-14T16:11:08.703Z,1741968668.703 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-03-14T16:11:08.705Z,1741968668.705 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-03-14T16:11:08.706Z,1741968668.706 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-03-14T16:11:08.712Z,1741968668.712 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-03-14T16:11:08.719Z,1741968668.719 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-03-14T16:11:08.749Z,1741968668.749 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-03-14T16:11:08.750Z,1741968668.750 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-03-14T16:11:08.751Z,1741968668.751 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-03-14T16:11:08.788Z,1741968668.788 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-03-14T16:11:08.791Z,1741968668.791 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-03-14T16:11:08.794Z,1741968668.794 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-03-14T16:11:08.796Z,1741968668.796 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-03-14T16:11:08.796Z,1741968668.796 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-03-14T16:11:08.797Z,1741968668.797 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-03-14T16:11:09.001Z,1741968669.001 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-03-14T16:11:09.005Z,1741968669.005 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-03-14T16:11:09.007Z,1741968669.007 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-03-14T16:11:09.010Z,1741968669.010 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-03-14T16:11:09.012Z,1741968669.012 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-03-14T16:11:09.014Z,1741968669.014 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-03-14T16:11:09.021Z,1741968669.021 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-03-14T16:11:09.023Z,1741968669.023 [keepstation:H.Pitch](DEBUG): Construct.
2025-03-14T16:11:09.030Z,1741968669.030 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-03-14T16:11:09.042Z,1741968669.042 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2025-03-14T16:11:09.075Z,1741968669.075 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2025-03-14T16:11:09.082Z,1741968669.082 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-03-14T16:11:09.097Z,1741968669.097 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2025-03-14T16:11:09.101Z,1741968669.101 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2025-03-14T16:11:09.106Z,1741968669.106 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2025-03-14T16:11:09.118Z,1741968669.118 [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.
"""
MaxDepth = 45 meter
"""
Maximum allowable depth during the mission.
"""
MinOffshore = 2 kilometer
"""
Minimum distance from the shoreline to maintain
"""
}
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
}
call id="StartingMission" refId="NeedComms"
aggregate TransitToStation {
"""
Need a separate aggregate for transit if we want to specify a
different depth for the approach.
"""
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
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
}
}
call id="OnStation" refId="NeedComms"
aggregate KeepStation {
run in sequence
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
}
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-03-14T16:11:09.119Z,1741968669.119 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-03-14T16:11:10.948Z,1741968670.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161102.00,A,3647.98684,N,12150.99516,W,1.263,221.82,140325,,,A*7D
2025-03-14T16:11:10.950Z,1741968670.950 [NAL9602](INFO): GPS fix at 20250314T161102: (36.799781, -121.849919)
2025-03-14T16:11:10.997Z,1741968670.997 [Default:CheckIn:Read_GPS] Stopped
2025-03-14T16:11:10.997Z,1741968670.997 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-14T16:11:22.598Z,1741968682.598 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250314T133305/Courier0021.lzma
2025-03-14T16:11:23.598Z,1741968683.598 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0021.lzma.bak
2025-03-14T16:11:23.598Z,1741968683.598 [DataOverHttps](INFO): SBD MOMSN=24228959
2025-03-14T16:11:45.155Z,1741968705.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=19182, MT Status=0, MTMSN=0
2025-03-14T16:11:45.155Z,1741968705.155 [NAL9602](INFO): No messages in MT queue
2025-03-14T16:11:46.094Z,1741968706.094 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 2 hour
2025-03-14T16:11:46.095Z,1741968706.095 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 20 minute
2025-03-14T16:11:46.100Z,1741968706.100 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.8156 degree
2025-03-14T16:11:46.101Z,1741968706.101 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.806 degree
2025-03-14T16:11:46.101Z,1741968706.101 [CommandExec](IMPORTANT): got command set keepstation.Depth 10 meter
2025-03-14T16:11:49.317Z,1741968709.317 [DataOverHttps](INFO): Sending 628 bytes from file Logs/20250314T133305/Express0022.lzma
2025-03-14T16:11:50.319Z,1741968710.319 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0022.lzma.bak
2025-03-14T16:11:50.319Z,1741968710.319 [DataOverHttps](INFO): SBD MOMSN=24228962
2025-03-14T16:12:11.356Z,1741968731.356 [CommandExec](IMPORTANT): got command set keepstation.ApproachDepth 10 meter
2025-03-14T16:12:11.357Z,1741968731.357 [CommandExec](IMPORTANT): got command set keepstation.Speed 1.3 meter_per_second
2025-03-14T16:12:11.358Z,1741968731.358 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 20 meter
2025-03-14T16:12:11.358Z,1741968731.358 [CommandExec](IMPORTANT): got command set keepstation.MinOffshore 0.5 kilometer
2025-03-14T16:12:11.359Z,1741968731.359 [CommandExec](IMPORTANT): got command run
2025-03-14T16:12:11.365Z,1741968731.365 [CommandExec](IMPORTANT): Running
2025-03-14T16:12:11.503Z,1741968731.503 [Default] Stopped
2025-03-14T16:12:11.503Z,1741968731.503 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-14T16:12:11.503Z,1741968731.503 [Default:B.GoToSurface] Stopped
2025-03-14T16:12:11.503Z,1741968731.503 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T16:12:11.503Z,1741968731.503 [Default:CheckIn] Stopped
2025-03-14T16:12:11.503Z,1741968731.503 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-14T16:12:11.524Z,1741968731.524 [Default:CheckIn:Read_Iridium] Stopped
2025-03-14T16:12:11.524Z,1741968731.524 [MissionManager](IMPORTANT): Started mission keepstation
2025-03-14T16:12:11.524Z,1741968731.524 [keepstation] Running Loop=1
2025-03-14T16:12:11.524Z,1741968731.524 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-03-14T16:12:11.524Z,1741968731.524 [keepstation:StandardEnvelopes] Running Loop=1
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:BackseatDriver] Running Loop=1
2025-03-14T16:12:11.525Z,1741968731.525 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly:A] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly:B] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly:C] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly:D] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:H.Pitch] Running Loop=1
2025-03-14T16:12:11.526Z,1741968731.526 [keepstation:H.Pitch](DEBUG): Initialize.
2025-03-14T16:12:11.527Z,1741968731.527 [keepstation:B] Running Loop=1
2025-03-14T16:12:11.527Z,1741968731.527 [keepstation:H.Pitch] Running Loop=1
2025-03-14T16:12:11.527Z,1741968731.527 [keepstation:PowerOnly] Running Loop=1
2025-03-14T16:12:11.536Z,1741968731.536 [keepstation:PowerOnly:D] Stopped
2025-03-14T16:12:11.536Z,1741968731.536 [keepstation:PowerOnly:C] Stopped
2025-03-14T16:12:11.536Z,1741968731.536 [keepstation:PowerOnly:B] Stopped
2025-03-14T16:12:11.536Z,1741968731.536 [keepstation:PowerOnly:A] Stopped
2025-03-14T16:12:11.536Z,1741968731.536 [keepstation:BackseatDriver] Running Loop=1
2025-03-14T16:12:11.537Z,1741968731.537 [keepstation:StandardEnvelopes] Running Loop=1
2025-03-14T16:12:11.537Z,1741968731.537 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-03-14T16:12:11.537Z,1741968731.537 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-03-14T16:12:11.537Z,1741968731.537 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-03-14T16:12:11.538Z,1741968731.538 [keepstation:B] Stopped
2025-03-14T16:12:11.538Z,1741968731.538 [keepstation:D] Running Loop=1
2025-03-14T16:12:11.846Z,1741968731.846 [keepstation:D] Stopped
2025-03-14T16:12:11.846Z,1741968731.846 [keepstation:E] Running Loop=1
2025-03-14T16:12:12.265Z,1741968732.265 [keepstation:E] Stopped
2025-03-14T16:12:12.265Z,1741968732.265 [keepstation:StartingMission] Running Loop=1
2025-03-14T16:12:12.266Z,1741968732.266 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-03-14T16:12:12.645Z,1741968732.645 [keepstation:NeedComms] Running Loop=1
2025-03-14T16:12:12.646Z,1741968732.646 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T16:12:12.646Z,1741968732.646 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T16:12:12.646Z,1741968732.646 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T16:12:12.646Z,1741968732.646 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T16:12:12.646Z,1741968732.646 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T16:12:12.647Z,1741968732.647 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T16:12:12.647Z,1741968732.647 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T16:12:12.647Z,1741968732.647 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T16:12:12.648Z,1741968732.648 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T16:12:12.648Z,1741968732.648 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T16:12:12.650Z,1741968732.650 [keepstation:NeedComms:A](INFO): last time_fix was: 1741968662.000000 second since 1970/01/01T00:00:00Z
2025-03-14T16:12:12.650Z,1741968732.650 [keepstation:NeedComms:A] Stopped
2025-03-14T16:12:13.064Z,1741968733.064 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T16:12:13.480Z,1741968733.480 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-14T16:12:13.539Z,1741968733.539 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250314T133305/Express0025.lzma
2025-03-14T16:12:14.542Z,1741968734.542 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0025.lzma.bak
2025-03-14T16:12:14.542Z,1741968734.542 [DataOverHttps](INFO): SBD MOMSN=24228976
2025-03-14T16:12:15.060Z,1741968735.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161209.00,A,3647.99316,N,12151.00066,W,0.175,328.63,140325,,,A*7B
2025-03-14T16:12:15.063Z,1741968735.063 [NAL9602](INFO): GPS fix at 20250314T161209: (36.799886, -121.850011)
2025-03-14T16:12:15.106Z,1741968735.106 [keepstation:NeedComms:C] Stopped
2025-03-14T16:12:15.106Z,1741968735.106 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T16:12:15.518Z,1741968735.518 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-14T16:12:32.176Z,1741968752.176 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250314T133305/Courier0027.lzma
2025-03-14T16:12:33.178Z,1741968753.178 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0027.lzma.bak
2025-03-14T16:12:33.178Z,1741968753.178 [DataOverHttps](INFO): SBD MOMSN=24228978
2025-03-14T16:12:35.454Z,1741968755.454 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-03-14T16:12:47.577Z,1741968767.577 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T16:12:49.917Z,1741968769.917 [CommandExec](IMPORTANT): got command show stack
2025-03-14T16:12:49.917Z,1741968769.917 [CommandExec](IMPORTANT): Behavior Stack:
2025-03-14T16:12:49.917Z,1741968769.917 [keepstation:NeedComms](IMPORTANT): Priority 0: keepstation:NeedComms:B.GoToSurface
2025-03-14T16:12:49.918Z,1741968769.918 [keepstation:NeedComms](IMPORTANT): Priority 1: keepstation:NeedComms:D
2025-03-14T16:12:49.918Z,1741968769.918 [keepstation:StandardEnvelopes](IMPORTANT): Priority 2: keepstation:StandardEnvelopes:A.AltitudeEnvelope
2025-03-14T16:12:49.918Z,1741968769.918 [keepstation:StandardEnvelopes](IMPORTANT): Priority 3: keepstation:StandardEnvelopes:B.DepthEnvelope
2025-03-14T16:12:49.918Z,1741968769.918 [keepstation:StandardEnvelopes](IMPORTANT): Priority 4: keepstation:StandardEnvelopes:C.OffshoreEnvelope
2025-03-14T16:12:49.919Z,1741968769.919 [keepstation:BackseatDriver](IMPORTANT): Priority 5: keepstation:BackseatDriver:A.BackseatDriver
2025-03-14T16:12:49.919Z,1741968769.919 [keepstation:PowerOnly](IMPORTANT): Priority 6: keepstation:PowerOnly:E.Wait
2025-03-14T16:12:49.920Z,1741968769.920 [keepstation](IMPORTANT): Priority 7: keepstation:H.Pitch
2025-03-14T16:12:52.561Z,1741968772.561 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20250314T133305/Express0028.lzma
2025-03-14T16:12:53.562Z,1741968773.562 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0028.lzma.bak
2025-03-14T16:12:53.562Z,1741968773.562 [DataOverHttps](INFO): SBD MOMSN=24228981
2025-03-14T16:12:55.274Z,1741968775.274 [keepstation:NeedComms:D] Stopped
2025-03-14T16:12:55.274Z,1741968775.274 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T16:12:55.682Z,1741968775.682 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-14T16:12:57.276Z,1741968777.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161251.00,A,3647.99794,N,12151.00615,W,1.400,277.95,140325,,,A*7E
2025-03-14T16:12:57.278Z,1741968777.278 [NAL9602](INFO): GPS fix at 20250314T161251: (36.799966, -121.850103)
2025-03-14T16:12:57.294Z,1741968777.294 [keepstation:NeedComms:E] Stopped
2025-03-14T16:12:57.295Z,1741968777.295 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T16:12:57.295Z,1741968777.295 [keepstation:NeedComms] Stopped
2025-03-14T16:12:57.320Z,1741968777.320 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T16:12:57.320Z,1741968777.320 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T16:12:57.320Z,1741968777.320 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T16:12:57.761Z,1741968777.761 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-03-14T16:12:57.761Z,1741968777.761 [keepstation:StartingMission] Stopped
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:TransitToStation] Running Loop=1
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-03-14T16:12:57.762Z,1741968777.762 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-03-14T16:12:57.763Z,1741968777.763 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-03-14T16:12:57.763Z,1741968777.763 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-03-14T16:12:57.763Z,1741968777.763 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-03-14T16:12:57.763Z,1741968777.763 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-03-14T16:12:57.805Z,1741968777.805 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.815601,-121.806000
2025-03-14T16:12:58.120Z,1741968778.120 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.815601,-121.806000
2025-03-14T16:12:58.120Z,1741968778.120 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-03-14T16:12:58.121Z,1741968778.121 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-03-14T16:12:58.121Z,1741968778.121 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-03-14T16:13:16.692Z,1741968796.692 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-03-14T16:13:16.692Z,1741968796.692 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-03-14T16:13:16.692Z,1741968796.692 [ThrusterHE] Communications Fault, FailCount= 1
2025-03-14T16:13:16.692Z,1741968796.692 [ThrusterHE](ERROR): Communications Fault
2025-03-14T16:13:16.696Z,1741968796.696 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-03-14T16:13:17.181Z,1741968797.181 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-14T16:13:17.181Z,1741968797.181 [ThrusterHE](INFO): Powering down
2025-03-14T16:13:18.005Z,1741968798.005 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-14T16:13:18.125Z,1741968798.125 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-14T16:13:18.128Z,1741968798.128 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-03-14T16:13:18.128Z,1741968798.128 [ThrusterHE] No Fault, FailCount= 1
2025-03-14T16:13:26.256Z,1741968806.256 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-03-14T16:13:26.352Z,1741968806.352 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-03-14T16:13:26.352Z,1741968806.352 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-03-14T16:13:26.352Z,1741968806.352 [ThrusterHE] Communications Fault, FailCount= 2
2025-03-14T16:13:26.352Z,1741968806.352 [ThrusterHE](ERROR): Communications Fault
2025-03-14T16:13:26.356Z,1741968806.356 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-03-14T16:13:26.637Z,1741968806.637 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-14T16:13:26.638Z,1741968806.638 [ThrusterHE](INFO): Powering down
2025-03-14T16:13:27.321Z,1741968807.321 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-14T16:13:27.442Z,1741968807.442 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-14T16:13:27.448Z,1741968807.448 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-03-14T16:13:27.448Z,1741968807.448 [ThrusterHE] No Fault, FailCount= 2
2025-03-14T16:13:34.932Z,1741968814.932 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-03-14T16:13:35.028Z,1741968815.028 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-03-14T16:13:35.028Z,1741968815.028 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-03-14T16:13:35.028Z,1741968815.028 [ThrusterHE] Communications Fault, FailCount= 3
2025-03-14T16:13:35.028Z,1741968815.028 [ThrusterHE](ERROR): Communications Fault
2025-03-14T16:13:35.032Z,1741968815.032 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-03-14T16:13:35.312Z,1741968815.312 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-14T16:13:35.313Z,1741968815.313 [ThrusterHE](INFO): Powering down
2025-03-14T16:13:36.305Z,1741968816.305 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-14T16:13:36.425Z,1741968816.425 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-14T16:13:36.429Z,1741968816.429 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-03-14T16:13:36.429Z,1741968816.429 [ThrusterHE] No Fault, FailCount= 3
2025-03-14T16:13:41.901Z,1741968821.901 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-03-14T16:14:10.119Z,1741968850.119 [NAL9602](INFO): SBD MO Status=2, MOMSN=19183, MT Status=2, MTMSN=0
2025-03-14T16:14:10.119Z,1741968850.119 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T16:14:56.770Z,1741968896.770 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:15:27.893Z,1741968927.893 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:15:32.620Z,1741968932.620 [Radio_Surface](INFO): Powering down
2025-03-14T16:15:59.024Z,1741968959.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:16:00.028Z,1741968960.028 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T16:17:18.348Z,1741969038.348 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.734070
2025-03-14T16:17:59.511Z,1741969079.511 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-14T16:18:30.238Z,1741969110.238 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T16:18:31.046Z,1741969111.046 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T16:23:28.509Z,1741969408.509 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-03-14T16:23:28.510Z,1741969408.510 [BPC1](ERROR): Battery stick #25 (s/n: 0480) reported OVER_TEMP_ALARM. Status code: 0x1717.
2025-03-14T16:23:28.510Z,1741969408.510 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-03-14T16:23:28.513Z,1741969408.513 [BPC1](INFO): Received data from all battery sticks.
2025-03-14T16:28:31.422Z,1741969711.422 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T16:33:00.741Z,1741969980.741 [keepstation:NeedComms] Running Loop=1
2025-03-14T16:33:00.741Z,1741969980.741 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T16:33:00.741Z,1741969980.741 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T16:33:00.741Z,1741969980.741 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T16:33:00.742Z,1741969980.742 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T16:33:00.743Z,1741969980.743 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T16:33:00.743Z,1741969980.743 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T16:33:00.744Z,1741969980.744 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T16:33:00.745Z,1741969980.745 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T16:33:00.746Z,1741969980.746 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T16:33:00.746Z,1741969980.746 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T16:33:00.749Z,1741969980.749 [keepstation:NeedComms:A](INFO): last time_fix was: 1741968771.000000 second since 1970/01/01T00:00:00Z
2025-03-14T16:33:00.750Z,1741969980.750 [keepstation:NeedComms:A] Stopped
2025-03-14T16:33:03.528Z,1741969983.528 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-03-14T16:33:03.620Z,1741969983.620 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-03-14T16:33:03.620Z,1741969983.620 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-03-14T16:33:03.621Z,1741969983.621 [ThrusterHE] Communications Fault, FailCount= 1
2025-03-14T16:33:03.621Z,1741969983.621 [ThrusterHE](ERROR): Communications Fault
2025-03-14T16:33:03.634Z,1741969983.634 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-03-14T16:33:03.805Z,1741969983.805 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-14T16:33:03.805Z,1741969983.805 [ThrusterHE](INFO): Powering down
2025-03-14T16:33:04.676Z,1741969984.676 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-14T16:33:04.795Z,1741969984.795 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-14T16:33:04.799Z,1741969984.799 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-03-14T16:33:04.799Z,1741969984.799 [ThrusterHE] No Fault, FailCount= 1
2025-03-14T16:33:30.833Z,1741970010.833 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T16:33:31.469Z,1741970011.469 [Radio_Surface](INFO): Powering up
2025-03-14T16:33:37.409Z,1741970017.409 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T16:34:03.555Z,1741970043.555 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-03-14T16:34:03.628Z,1741970043.628 [NAL9602](ERROR): received:
+CSQ:0
OK183, 2, 0, 0, 0
OK
2025-03-14T16:34:07.528Z,1741970047.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:34:14.043Z,1741970054.043 [NAL9602](INFO): SBD MO Status=0, MOMSN=19183, MT Status=0, MTMSN=0
2025-03-14T16:34:14.043Z,1741970054.043 [NAL9602](INFO): No messages in MT queue
2025-03-14T16:34:15.258Z,1741970055.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163409.00,A,3648.34975,N,12150.08187,W,0.972,323.08,140325,,,A*76
2025-03-14T16:34:15.261Z,1741970055.261 [NAL9602](INFO): GPS fix at 20250314T163409: (36.805829, -121.834698)
2025-03-14T16:34:15.273Z,1741970055.273 [UniversalFixResidualReporter](INFO): Fix residual: 1.5 %DT, over the last 1603.1 m. Residual distance 24.4 m at bearing -141.9 degrees. Fix at (36.8058, -121.8347) with 1518.6 m made good.
2025-03-14T16:34:15.297Z,1741970055.297 [keepstation:NeedComms:C] Stopped
2025-03-14T16:34:15.297Z,1741970055.297 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T16:34:38.652Z,1741970078.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:34:45.282Z,1741970085.282 [NAL9602](INFO): SBD MO Status=1, MOMSN=19184, MT Status=0, MTMSN=0
2025-03-14T16:34:45.332Z,1741970085.332 [NAL9602](INFO): Sent 101 bytes from file Logs/20250314T133305/Courier0030.lzma
2025-03-14T16:34:45.332Z,1741970085.332 [NAL9602](INFO): Packets left to send: 0
2025-03-14T16:35:03.576Z,1741970103.576 [NAL9602](INFO): SBD MO Status=2, MOMSN=19185, MT Status=2, MTMSN=0
2025-03-14T16:35:03.576Z,1741970103.576 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T16:35:09.776Z,1741970109.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:35:31.977Z,1741970131.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=19185, MT Status=2, MTMSN=0
2025-03-14T16:35:31.977Z,1741970131.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T16:35:41.132Z,1741970141.132 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003125
2025-03-14T16:35:48.884Z,1741970148.884 [DataOverHttps](INFO): Sending 813 bytes from file Logs/20250314T133305/Express0031.lzma
2025-03-14T16:35:49.883Z,1741970149.883 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0031.lzma.bak
2025-03-14T16:35:49.883Z,1741970149.883 [DataOverHttps](INFO): SBD MOMSN=24229023
2025-03-14T16:35:51.522Z,1741970151.522 [keepstation:NeedComms:D] Stopped
2025-03-14T16:35:51.522Z,1741970151.522 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T16:36:04.843Z,1741970164.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=19185, MT Status=2, MTMSN=0
2025-03-14T16:36:04.843Z,1741970164.843 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T16:36:06.047Z,1741970166.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163600.00,A,3648.35845,N,12150.09042,W,0.894,302.02,140325,,,A*77
2025-03-14T16:36:06.062Z,1741970166.062 [NAL9602](INFO): GPS fix at 20250314T163600: (36.805974, -121.834840)
2025-03-14T16:36:06.076Z,1741970166.076 [keepstation:NeedComms:E] Stopped
2025-03-14T16:36:06.077Z,1741970166.077 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T16:36:06.077Z,1741970166.077 [keepstation:NeedComms] Stopped
2025-03-14T16:36:06.077Z,1741970166.077 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T16:36:06.078Z,1741970166.078 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T16:36:06.078Z,1741970166.078 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T16:36:38.447Z,1741970198.447 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T16:37:11.824Z,1741970231.824 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-03-14T16:37:11.824Z,1741970231.824 [DVL_micro] Communications Fault, FailCount= 1
2025-03-14T16:37:11.824Z,1741970231.824 [DVL_micro](ERROR): Communications Fault
2025-03-14T16:37:11.825Z,1741970231.825 [DVL_micro](ERROR): Failed to parse:
2025-03-14T16:37:11.923Z,1741970231.923 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-03-14T16:37:12.248Z,1741970232.248 [DVL_micro](INFO): Powering down
2025-03-14T16:37:13.083Z,1741970233.083 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-03-14T16:37:13.083Z,1741970233.083 [DVL_micro] No Fault, FailCount= 1
2025-03-14T16:37:23.364Z,1741970243.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:37:29.892Z,1741970249.892 [Radio_Surface](INFO): Powering down
2025-03-14T16:37:54.488Z,1741970274.488 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:37:55.492Z,1741970275.492 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T16:38:31.502Z,1741970311.502 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-14T16:38:58.749Z,1741970338.749 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.115417
2025-03-14T16:40:00.533Z,1741970400.533 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0040.00,000.00
2025-03-14T16:49:00.763Z,1741970940.763 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.6,0000.0,1489.WI,-00060,+00932,+00019,+00000,A
2025-03-14T16:49:00.763Z,1741970940.763 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.6,0000.0,1489.WI,-00060,+00932,+00019,+00000,A
2025-03-14T16:56:08.453Z,1741971368.453 [keepstation:NeedComms] Running Loop=1
2025-03-14T16:56:08.453Z,1741971368.453 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T16:56:08.453Z,1741971368.453 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T16:56:08.453Z,1741971368.453 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T16:56:08.454Z,1741971368.454 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T16:56:08.455Z,1741971368.455 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T16:56:08.455Z,1741971368.455 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T16:56:08.456Z,1741971368.456 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T16:56:08.457Z,1741971368.457 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T16:56:08.457Z,1741971368.457 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T16:56:08.458Z,1741971368.458 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T16:56:08.461Z,1741971368.461 [keepstation:NeedComms:A](INFO): last time_fix was: 1741970160.000000 second since 1970/01/01T00:00:00Z
2025-03-14T16:56:08.462Z,1741971368.462 [keepstation:NeedComms:A] Stopped
2025-03-14T16:56:17.676Z,1741971377.676 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-03-14T16:56:17.764Z,1741971377.764 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-03-14T16:56:17.764Z,1741971377.764 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-03-14T16:56:17.764Z,1741971377.764 [ThrusterHE] Communications Fault, FailCount= 1
2025-03-14T16:56:17.764Z,1741971377.764 [ThrusterHE](ERROR): Communications Fault
2025-03-14T16:56:17.768Z,1741971377.768 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-03-14T16:56:17.976Z,1741971377.976 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-14T16:56:17.976Z,1741971377.976 [ThrusterHE](INFO): Powering down
2025-03-14T16:56:18.937Z,1741971378.937 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-14T16:56:19.057Z,1741971379.057 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-14T16:56:19.061Z,1741971379.061 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-03-14T16:56:19.061Z,1741971379.061 [ThrusterHE] No Fault, FailCount= 1
2025-03-14T16:56:36.173Z,1741971396.173 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T16:56:36.568Z,1741971396.568 [Radio_Surface](INFO): Powering up
2025-03-14T16:56:42.140Z,1741971402.140 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T16:56:52.316Z,1741971412.316 [NAL9602](INFO): SBD MO Status=1, MOMSN=19185, MT Status=0, MTMSN=0
2025-03-14T16:56:52.316Z,1741971412.316 [NAL9602](INFO): No messages in MT queue
2025-03-14T16:56:53.526Z,1741971413.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165647.00,A,3648.65958,N,12149.11523,W,1.458,33.69,140325,,,A*48
2025-03-14T16:56:53.537Z,1741971413.537 [NAL9602](INFO): GPS fix at 20250314T165647: (36.810993, -121.818587)
2025-03-14T16:56:53.574Z,1741971413.574 [UniversalFixResidualReporter](INFO): Fix residual: 3.3 %DT, over the last 1573.1 m. Residual distance 52.4 m at bearing -67.4 degrees. Fix at (36.8110, -121.8186) with 1550.9 m made good.
2025-03-14T16:56:53.586Z,1741971413.586 [keepstation:NeedComms:C] Stopped
2025-03-14T16:56:53.586Z,1741971413.586 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T16:57:12.263Z,1741971432.263 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:57:16.265Z,1741971436.265 [NAL9602](INFO): SBD MO Status=1, MOMSN=19186, MT Status=0, MTMSN=0
2025-03-14T16:57:16.348Z,1741971436.348 [NAL9602](INFO): Sent 86 bytes from file Logs/20250314T133305/Courier0033.lzma
2025-03-14T16:57:16.348Z,1741971436.348 [NAL9602](INFO): Packets left to send: 0
2025-03-14T16:57:40.942Z,1741971460.942 [NAL9602](INFO): SBD MO Status=1, MOMSN=19187, MT Status=0, MTMSN=0
2025-03-14T16:57:41.000Z,1741971461.000 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0034.lzma
2025-03-14T16:57:41.001Z,1741971461.001 [NAL9602](INFO): Packets left to send: 1
2025-03-14T16:57:43.384Z,1741971463.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:57:57.230Z,1741971477.230 [NAL9602](INFO): SBD MO Status=1, MOMSN=19188, MT Status=0, MTMSN=0
2025-03-14T16:57:57.292Z,1741971477.292 [NAL9602](INFO): Sent 27 bytes from file Logs/20250314T133305/Express0034.lzma
2025-03-14T16:57:57.292Z,1741971477.292 [NAL9602](INFO): Packets left to send: 0
2025-03-14T16:58:09.749Z,1741971489.749 [NAL9602](INFO): SBD MO Status=0, MOMSN=19189, MT Status=0, MTMSN=0
2025-03-14T16:58:09.837Z,1741971489.837 [keepstation:NeedComms:D] Stopped
2025-03-14T16:58:09.838Z,1741971489.838 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T16:58:12.196Z,1741971492.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165806.00,A,3648.66578,N,12149.12109,W,1.322,305.17,140325,,,A*74
2025-03-14T16:58:12.198Z,1741971492.198 [NAL9602](INFO): GPS fix at 20250314T165806: (36.811096, -121.818685)
2025-03-14T16:58:12.237Z,1741971492.237 [keepstation:NeedComms:E] Stopped
2025-03-14T16:58:12.238Z,1741971492.238 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T16:58:12.239Z,1741971492.239 [keepstation:NeedComms] Stopped
2025-03-14T16:58:12.239Z,1741971492.239 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T16:58:12.239Z,1741971492.239 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T16:58:12.239Z,1741971492.239 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T16:58:14.508Z,1741971494.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:58:44.331Z,1741971524.331 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T16:58:45.633Z,1741971525.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:59:16.756Z,1741971556.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:59:31.564Z,1741971571.564 [Radio_Surface](INFO): Powering down
2025-03-14T16:59:47.924Z,1741971587.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T16:59:48.928Z,1741971588.928 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-14T17:00:47.187Z,1741971647.187 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.6,0000.0,1477
2025-03-14T17:00:47.187Z,1741971647.187 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+11.6,0000.0,1477
2025-03-14T17:13:29.469Z,1741972409.469 [DVL_micro](ERROR): Failed to parse:
:SA,+01.29,+00.03,045.7
2025-03-14T17:15:57.290Z,1741972557.290 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.815601,-121.806000
2025-03-14T17:15:57.290Z,1741972557.290 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-03-14T17:15:57.290Z,1741972557.290 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-03-14T17:15:57.291Z,1741972557.291 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation] Stopped
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation:B.Pitch] Stopped
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:OnStation] Running Loop=1
2025-03-14T17:15:57.292Z,1741972557.292 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-03-14T17:15:57.709Z,1741972557.709 [keepstation:NeedComms] Running Loop=1
2025-03-14T17:15:57.709Z,1741972557.709 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-03-14T17:15:57.710Z,1741972557.710 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-03-14T17:15:57.710Z,1741972557.710 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T17:15:57.710Z,1741972557.710 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-03-14T17:15:57.710Z,1741972557.710 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-03-14T17:15:57.711Z,1741972557.711 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-03-14T17:15:57.711Z,1741972557.711 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-03-14T17:15:57.711Z,1741972557.711 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T17:15:57.712Z,1741972557.712 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T17:15:57.713Z,1741972557.713 [keepstation:NeedComms:A] Running Loop=1
2025-03-14T17:15:57.714Z,1741972557.714 [keepstation:NeedComms:A](INFO): last time_fix was: 1741971486.000000 second since 1970/01/01T00:00:00Z
2025-03-14T17:15:57.714Z,1741972557.714 [keepstation:NeedComms:A] Stopped
2025-03-14T17:16:01.712Z,1741972561.712 [ThrusterHE](FAULT): Could not get status:serial timeout
2025-03-14T17:16:01.808Z,1741972561.808 [ThrusterHE](FAULT): getSpeed error serial timeout
2025-03-14T17:16:01.808Z,1741972561.808 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2025-03-14T17:16:01.808Z,1741972561.808 [ThrusterHE] Communications Fault, FailCount= 1
2025-03-14T17:16:01.808Z,1741972561.808 [ThrusterHE](ERROR): Communications Fault
2025-03-14T17:16:01.812Z,1741972561.812 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2025-03-14T17:16:01.997Z,1741972561.997 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-14T17:16:01.997Z,1741972561.997 [ThrusterHE](INFO): Powering down
2025-03-14T17:16:02.973Z,1741972562.973 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-14T17:16:03.093Z,1741972563.093 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-14T17:16:03.097Z,1741972563.097 [CBIT](INFO): Clearing failed state for component ThrusterHE
2025-03-14T17:16:03.097Z,1741972563.097 [ThrusterHE] No Fault, FailCount= 1
2025-03-14T17:16:13.657Z,1741972573.657 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE
2025-03-14T17:16:27.477Z,1741972587.477 [keepstation:NeedComms:C] Running Loop=1
2025-03-14T17:16:28.140Z,1741972588.140 [Radio_Surface](INFO): Powering up
2025-03-14T17:16:34.076Z,1741972594.076 [DataOverHttps](INFO): Radio surface powered ON.
2025-03-14T17:17:04.217Z,1741972624.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T17:17:35.341Z,1741972655.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T17:17:45.030Z,1741972665.030 [NAL9602](INFO): SBD MO Status=0, MOMSN=19190, MT Status=0, MTMSN=0
2025-03-14T17:17:45.031Z,1741972665.031 [NAL9602](INFO): No messages in MT queue
2025-03-14T17:17:46.231Z,1741972666.231 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171740.00,A,3648.97513,N,12148.25540,W,0.447,335.67,140325,,,A*72
2025-03-14T17:17:46.234Z,1741972666.234 [NAL9602](INFO): GPS fix at 20250314T171740: (36.816252, -121.804257)
2025-03-14T17:17:46.246Z,1741972666.246 [UniversalFixResidualReporter](INFO): Fix residual: 10.9 %DT, over the last 1323.8 m. Residual distance 144.6 m at bearing -105.2 degrees. Fix at (36.8163, -121.8043) with 1406.6 m made good.
2025-03-14T17:17:46.248Z,1741972666.248 [keepstation:NeedComms:C] Stopped
2025-03-14T17:17:46.249Z,1741972666.249 [keepstation:NeedComms:D] Running Loop=1
2025-03-14T17:17:57.693Z,1741972677.693 [NAL9602](INFO): SBD MO Status=1, MOMSN=19191, MT Status=0, MTMSN=0
2025-03-14T17:17:57.740Z,1741972677.740 [NAL9602](INFO): Sent 85 bytes from file Logs/20250314T133305/Courier0036.lzma
2025-03-14T17:17:57.740Z,1741972677.740 [NAL9602](INFO): Packets left to send: 0
2025-03-14T17:18:06.464Z,1741972686.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T17:18:12.819Z,1741972692.819 [NAL9602](INFO): SBD MO Status=1, MOMSN=19192, MT Status=0, MTMSN=0
2025-03-14T17:18:12.868Z,1741972692.868 [NAL9602](INFO): Sent 332 bytes from file Logs/20250314T133305/Express0037.lzma
2025-03-14T17:18:12.868Z,1741972692.868 [NAL9602](INFO): Packets left to send: 1
2025-03-14T17:18:27.485Z,1741972707.485 [NAL9602](INFO): SBD MO Status=1, MOMSN=19193, MT Status=0, MTMSN=0
2025-03-14T17:18:27.532Z,1741972707.532 [NAL9602](INFO): Sent 153 bytes from file Logs/20250314T133305/Express0037.lzma
2025-03-14T17:18:27.532Z,1741972707.532 [NAL9602](INFO): Packets left to send: 0
2025-03-14T17:18:37.588Z,1741972717.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-03-14T17:18:45.282Z,1741972725.282 [NAL9602](INFO): SBD MO Status=2, MOMSN=19194, MT Status=2, MTMSN=0
2025-03-14T17:18:45.282Z,1741972725.282 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-14T17:18:46.785Z,1741972726.785 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003089
2025-03-14T17:18:48.134Z,1741972728.134 [keepstation:NeedComms:D] Stopped
2025-03-14T17:18:48.134Z,1741972728.134 [keepstation:NeedComms:E] Running Loop=1
2025-03-14T17:19:03.777Z,1741972743.777 [NAL9602](INFO): SBD MO Status=0, MOMSN=19194, MT Status=0, MTMSN=0
2025-03-14T17:19:04.987Z,1741972744.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171859.00,A,3648.97924,N,12148.26317,W,1.225,268.11,140325,,,A*71
2025-03-14T17:19:04.990Z,1741972744.990 [NAL9602](INFO): GPS fix at 20250314T171859: (36.816321, -121.804386)
2025-03-14T17:19:05.002Z,1741972745.002 [keepstation:NeedComms:E] Stopped
2025-03-14T17:19:05.003Z,1741972745.003 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-03-14T17:19:05.003Z,1741972745.003 [keepstation:NeedComms] Stopped
2025-03-14T17:19:05.003Z,1741972745.003 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-03-14T17:19:05.003Z,1741972745.003 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-03-14T17:19:05.003Z,1741972745.003 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-14T17:19:05.430Z,1741972745.430 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-03-14T17:19:05.430Z,1741972745.430 [keepstation:OnStation] Stopped
2025-03-14T17:19:05.430Z,1741972745.430 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-03-14T17:19:05.431Z,1741972745.431 [keepstation:KeepStation] Running Loop=1
2025-03-14T17:19:05.431Z,1741972745.431 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-03-14T17:19:05.431Z,1741972745.431 [keepstation:KeepStation:A] Running Loop=1
2025-03-14T17:19:05.431Z,1741972745.431 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-03-14T17:19:05.431Z,1741972745.431 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-03-14T17:19:05.432Z,1741972745.432 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-03-14T17:19:05.432Z,1741972745.432 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-03-14T17:19:05.433Z,1741972745.433 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-03-14T17:19:05.433Z,1741972745.433 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-03-14T17:19:05.842Z,1741972745.842 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-03-14T17:19:05.842Z,1741972745.842 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-03-14T17:19:05.843Z,1741972745.843 [keepstation:KeepStation:A] Running Loop=1
2025-03-14T17:19:37.493Z,1741972777.493 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T17:25:04.453Z,1741973104.453 [DataOverHttps](IMPORTANT): SBD MTMSN=20250314T172458
2025-03-14T17:25:12.096Z,1741973112.096 [DataOverHttps](INFO): Received command: stop
2025-03-14T17:25:12.125Z,1741973112.125 [CommandExec](IMPORTANT): got command stop
2025-03-14T17:25:12.125Z,1741973112.125 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-03-14T17:25:12.439Z,1741973112.439 [MissionManager](INFO): MissionManager is completed.
2025-03-14T17:25:12.439Z,1741973112.439 [MissionManager](INFO): Uninitializing Mission keepstation
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation] Stopped
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes] Stopped
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-03-14T17:25:12.440Z,1741973112.440 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:BackseatDriver] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:PowerOnly] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:PowerOnly:E.Wait] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:H.Pitch] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:KeepStation] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:KeepStation:A] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:KeepStation:B.Pitch] Stopped
2025-03-14T17:25:12.441Z,1741973112.441 [keepstation:KeepStation:C.KeepStation] Stopped
2025-03-14T17:25:12.442Z,1741973112.442 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-03-14T17:25:12.442Z,1741973112.442 [keepstation:KeepStation:D.Wait] Stopped
2025-03-14T17:25:12.442Z,1741973112.442 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T17:25:12.787Z,1741973112.787 [MissionManager](IMPORTANT): Started mission Default
2025-03-14T17:25:12.787Z,1741973112.787 [Default] Running Loop=1
2025-03-14T17:25:12.787Z,1741973112.787 [Default](DEBUG): Aggregate::initialize Default
2025-03-14T17:25:12.787Z,1741973112.787 [Default:B.GoToSurface] Running Loop=1
2025-03-14T17:25:12.787Z,1741973112.787 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-14T17:25:12.787Z,1741973112.787 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-14T17:25:12.788Z,1741973112.788 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-14T17:25:12.788Z,1741973112.788 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-14T17:25:12.789Z,1741973112.789 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-14T17:25:12.789Z,1741973112.789 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-14T17:25:12.789Z,1741973112.789 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-14T17:25:12.790Z,1741973112.790 [Default:A.Wait] Running Loop=1
2025-03-14T17:25:12.790Z,1741973112.790 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-14T17:25:26.150Z,1741973126.150 [Default:A.Wait](INFO): Done Waiting.
2025-03-14T17:25:26.150Z,1741973126.150 [Default:A.Wait] Stopped
2025-03-14T17:25:26.150Z,1741973126.150 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T17:25:26.515Z,1741973126.515 [Default:CheckIn] Running Loop=1
2025-03-14T17:25:26.515Z,1741973126.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-14T17:25:26.516Z,1741973126.516 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-14T17:25:28.531Z,1741973128.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172522.00,A,3648.98798,N,12148.29295,W,0.214,0.12,140325,,,A*7D
2025-03-14T17:25:28.534Z,1741973128.534 [NAL9602](INFO): GPS fix at 20250314T172522: (36.816466, -121.804883)
2025-03-14T17:25:28.545Z,1741973128.545 [Default:CheckIn:Read_GPS] Stopped
2025-03-14T17:25:28.545Z,1741973128.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-14T17:25:36.216Z,1741973136.216 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T133305/Courier0039.lzma
2025-03-14T17:25:37.218Z,1741973137.218 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0039.lzma.bak
2025-03-14T17:25:37.218Z,1741973137.218 [DataOverHttps](INFO): SBD MOMSN=24229252
2025-03-14T17:25:47.986Z,1741973147.986 [NAL9602](INFO): SBD MO Status=0, MOMSN=19195, MT Status=0, MTMSN=0
2025-03-14T17:25:47.986Z,1741973147.986 [NAL9602](INFO): No messages in MT queue
2025-03-14T17:25:54.029Z,1741973154.029 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250314T133305/Express0040.lzma
2025-03-14T17:25:55.030Z,1741973155.030 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0040.lzma.bak
2025-03-14T17:25:55.030Z,1741973155.030 [DataOverHttps](INFO): SBD MOMSN=24229258
2025-03-14T17:25:56.485Z,1741973156.485 [Default:CheckIn:Read_Iridium] Stopped
2025-03-14T17:25:56.485Z,1741973156.485 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-14T17:25:56.485Z,1741973156.485 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-14T17:26:18.668Z,1741973178.668 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T17:30:57.115Z,1741973457.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-14T17:30:57.115Z,1741973457.115 [Default:CheckIn:C.Wait] Stopped
2025-03-14T17:30:57.115Z,1741973457.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-14T17:30:57.120Z,1741973457.120 [Default:CheckIn:D] Running Loop=1
2025-03-14T17:30:57.507Z,1741973457.507 [Default:CheckIn:D] Stopped
2025-03-14T17:30:57.528Z,1741973457.528 [Default:CheckIn:E] Running Loop=1
2025-03-14T17:30:57.912Z,1741973457.912 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.745341 min
2025-03-14T17:30:57.912Z,1741973457.912 [Default:CheckIn:E] Stopped
2025-03-14T17:30:57.912Z,1741973457.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-14T17:30:57.912Z,1741973457.912 [Default:CheckIn] Stopped
2025-03-14T17:30:57.913Z,1741973457.913 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-14T17:30:57.913Z,1741973457.913 [Default:CheckIn](INFO): Running loop #2
2025-03-14T17:30:57.913Z,1741973457.913 [Default:CheckIn] Running Loop=2
2025-03-14T17:30:57.913Z,1741973457.913 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-14T17:30:57.913Z,1741973457.913 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-14T17:30:59.899Z,1741973459.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173054.00,A,3648.99693,N,12148.32453,W,1.186,272.72,140325,,,D*78
2025-03-14T17:30:59.910Z,1741973459.910 [NAL9602](INFO): GPS fix at 20250314T173054: (36.816615, -121.805409)
2025-03-14T17:30:59.921Z,1741973459.921 [Default:CheckIn:Read_GPS] Stopped
2025-03-14T17:30:59.921Z,1741973459.921 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-14T17:31:03.190Z,1741973463.190 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-03-14T17:31:03.191Z,1741973463.191 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-03-14T17:31:03.198Z,1741973463.198 [BPC1](INFO): Received data from all battery sticks.
2025-03-14T17:31:07.640Z,1741973467.640 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250314T133305/Courier0044.lzma
2025-03-14T17:31:08.642Z,1741973468.642 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Courier0044.lzma.bak
2025-03-14T17:31:08.642Z,1741973468.642 [DataOverHttps](INFO): SBD MOMSN=24229273
2025-03-14T17:31:16.897Z,1741973476.897 [NAL9602](INFO): SBD MO Status=0, MOMSN=19196, MT Status=0, MTMSN=0
2025-03-14T17:31:16.897Z,1741973476.897 [NAL9602](INFO): No messages in MT queue
2025-03-14T17:31:25.641Z,1741973485.641 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250314T133305/Express0046.lzma
2025-03-14T17:31:26.642Z,1741973486.642 [DataOverHttps](INFO): Moved sent file to Logs/20250314T133305/Express0046.lzma.bak
2025-03-14T17:31:26.642Z,1741973486.642 [DataOverHttps](INFO): SBD MOMSN=24229278
2025-03-14T17:31:28.227Z,1741973488.227 [Default:CheckIn:Read_Iridium] Stopped
2025-03-14T17:31:28.227Z,1741973488.227 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-14T17:31:28.227Z,1741973488.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-14T17:31:47.587Z,1741973507.587 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-14T17:31:54.865Z,1741973514.865 [DVL_micro](ERROR): Failed to parse:
:WI,-00230,-00170,-00258,+00000,A
2025-03-14T17:32:11.045Z,1741973531.045 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,+00261,-00663,+0010016.60,000.00
2025-03-14T17:32:11.045Z,1741973531.045 [DVL_micro](ERROR): Failed to parse:
:BI,+00261,-00663,+0010016.60,000.00
2025-03-14T17:35:35.109Z,1741973735.109 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00000,+00000,+00000,I
2025-03-14T17:35:36.273Z,1741973736.273 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00
2025-03-14T17:35:46.525Z,1741973746.525 [DataOverHttps](IMPORTANT): SBD MTMSN=20250314T173540
2025-03-14T17:35:54.182Z,1741973754.182 [DataOverHttps](INFO): Received command: restart logs