2025-07-23T17:01:41.820Z,1753290101.820 [DataOverHttps](INFO): Received command: restart logs 2025-07-23T17:01:41.823Z,1753290101.823 [CommandExec](IMPORTANT): got command restart logs 2025-07-23T17:01:52.550Z,1753290112.550 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:01:53.763Z,1753290113.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170150.00,A,3647.40063,N,12150.64236,W,6.745,77.33,230725,,,D*4F 2025-07-23T17:01:53.778Z,1753290113.778 [NAL9602](INFO): GPS fix at 20250723T170150: (36.790011, -121.844039) 2025-07-23T17:01:53.805Z,1753290113.805 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:01:53.806Z,1753290113.806 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:02:01.792Z,1753290121.792 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T153601/Courier0022.lzma 2025-07-23T17:02:02.796Z,1753290122.796 [DataOverHttps](INFO): Moved sent file to Logs/20250723T153601/Courier0022.lzma.bak 2025-07-23T17:02:02.796Z,1753290122.796 [DataOverHttps](INFO): SBD MOMSN=25577266 2025-07-23T17:02:24.184Z,1753290144.184 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T17:02:24.261Z,1753290144.261 [NAL9602](ERROR): received: +CSQ:0 OK900, 2, 0, 0, 0 OK 2025-07-23T17:02:26.265Z,1753290146.265 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250723T170141/Courier0000.lzma 2025-07-23T17:02:27.267Z,1753290147.267 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0000.lzma.bak 2025-07-23T17:02:27.267Z,1753290147.267 [DataOverHttps](INFO): SBD MOMSN=25577268 2025-07-23T17:02:43.513Z,1753290163.513 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20250723T153601/Express0023.lzma 2025-07-23T17:02:44.515Z,1753290164.515 [DataOverHttps](INFO): Moved sent file to Logs/20250723T153601/Express0023.lzma.bak 2025-07-23T17:02:44.515Z,1753290164.515 [DataOverHttps](INFO): SBD MOMSN=25577274 2025-07-23T17:03:01.189Z,1753290181.189 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20250723T170141/Express0001.lzma 2025-07-23T17:03:02.191Z,1753290182.191 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0001.lzma.bak 2025-07-23T17:03:02.192Z,1753290182.192 [DataOverHttps](INFO): SBD MOMSN=25577279 2025-07-23T17:03:23.085Z,1753290203.085 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250723T170141/Express0004.lzma 2025-07-23T17:03:24.090Z,1753290204.090 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0004.lzma.bak 2025-07-23T17:03:24.091Z,1753290204.091 [DataOverHttps](INFO): SBD MOMSN=25577285 2025-07-23T17:03:25.538Z,1753290205.538 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:03:25.538Z,1753290205.538 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:03:25.538Z,1753290205.538 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:03:56.198Z,1753290236.198 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:04:26.934Z,1753290266.934 [NAL9602](INFO): Powering down 2025-07-23T17:08:26.153Z,1753290506.153 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:08:26.153Z,1753290506.153 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:08:26.153Z,1753290506.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:08:26.153Z,1753290506.153 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:08:26.520Z,1753290506.520 [Default:CheckIn:D] Stopped 2025-07-23T17:08:26.520Z,1753290506.520 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:08:26.922Z,1753290506.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.827909 min 2025-07-23T17:08:26.922Z,1753290506.922 [Default:CheckIn:E] Stopped 2025-07-23T17:08:26.922Z,1753290506.922 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:08:26.922Z,1753290506.922 [Default:CheckIn] Stopped 2025-07-23T17:08:26.922Z,1753290506.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:08:26.922Z,1753290506.922 [Default:CheckIn](INFO): Running loop #4 2025-07-23T17:08:26.923Z,1753290506.923 [Default:CheckIn] Running Loop=4 2025-07-23T17:08:26.923Z,1753290506.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:08:26.923Z,1753290506.923 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:08:27.731Z,1753290507.731 [NAL9602](INFO): Powering up 2025-07-23T17:08:38.639Z,1753290518.639 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:08:39.842Z,1753290519.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170839.00,A,3648.45743,N,12147.30509,W,10.749,54.10,230725,,,D*71 2025-07-23T17:08:39.844Z,1753290519.844 [NAL9602](INFO): GPS fix at 20250723T170839: (36.807624, -121.788418) 2025-07-23T17:08:39.856Z,1753290519.856 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:08:39.856Z,1753290519.856 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:08:47.560Z,1753290527.560 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T170141/Courier0006.lzma 2025-07-23T17:08:48.563Z,1753290528.563 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0006.lzma.bak 2025-07-23T17:08:48.563Z,1753290528.563 [DataOverHttps](INFO): SBD MOMSN=25577310 2025-07-23T17:09:05.026Z,1753290545.026 [DataOverHttps](INFO): Sending 385 bytes from file Logs/20250723T170141/Express0007.lzma 2025-07-23T17:09:06.029Z,1753290546.029 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0007.lzma.bak 2025-07-23T17:09:06.030Z,1753290546.030 [DataOverHttps](INFO): SBD MOMSN=25577317 2025-07-23T17:09:07.333Z,1753290547.333 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:09:07.333Z,1753290547.333 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:09:07.334Z,1753290547.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:09:10.542Z,1753290550.542 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T17:09:10.625Z,1753290550.625 [NAL9602](ERROR): received: +CSQ:1 OK900, 2, 0, 0, 0 OK 2025-07-23T17:10:42.663Z,1753290642.663 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:11:13.366Z,1753290673.366 [NAL9602](INFO): Powering down 2025-07-23T17:14:07.933Z,1753290847.933 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:14:07.933Z,1753290847.933 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:14:07.934Z,1753290847.934 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:14:07.934Z,1753290847.934 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:14:08.380Z,1753290848.380 [Default:CheckIn:D] Stopped 2025-07-23T17:14:08.380Z,1753290848.380 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.525576 min 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn:E] Stopped 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn] Stopped 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn](INFO): Running loop #5 2025-07-23T17:14:08.743Z,1753290848.743 [Default:CheckIn] Running Loop=5 2025-07-23T17:14:08.744Z,1753290848.744 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:14:08.744Z,1753290848.744 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:14:09.542Z,1753290849.542 [NAL9602](INFO): Powering up 2025-07-23T17:14:20.459Z,1753290860.459 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:14:21.661Z,1753290861.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171420.00,A,3648.16628,N,12147.20785,W,2.430,227.35,230725,,,A*73 2025-07-23T17:14:21.663Z,1753290861.663 [NAL9602](INFO): GPS fix at 20250723T171420: (36.802771, -121.786798) 2025-07-23T17:14:21.675Z,1753290861.675 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:14:21.675Z,1753290861.675 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:14:29.573Z,1753290869.573 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250723T170141/Courier0009.lzma 2025-07-23T17:14:30.575Z,1753290870.575 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0009.lzma.bak 2025-07-23T17:14:30.575Z,1753290870.575 [DataOverHttps](INFO): SBD MOMSN=25577358 2025-07-23T17:14:46.750Z,1753290886.750 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20250723T170141/Express0010.lzma 2025-07-23T17:14:47.743Z,1753290887.743 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0010.lzma.bak 2025-07-23T17:14:47.743Z,1753290887.743 [DataOverHttps](INFO): SBD MOMSN=25577361 2025-07-23T17:14:49.152Z,1753290889.152 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:14:49.152Z,1753290889.152 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:14:49.152Z,1753290889.152 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:14:52.370Z,1753290892.370 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T17:14:52.445Z,1753290892.445 [NAL9602](ERROR): received: +CSQ:0 OK900, 2, 0, 0, 0 OK 2025-07-23T17:16:24.121Z,1753290984.121 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:16:54.806Z,1753291014.806 [NAL9602](INFO): Powering down 2025-07-23T17:19:49.771Z,1753291189.771 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:19:49.771Z,1753291189.771 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:19:49.771Z,1753291189.771 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:19:49.772Z,1753291189.772 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:19:50.194Z,1753291190.194 [Default:CheckIn:D] Stopped 2025-07-23T17:19:50.194Z,1753291190.194 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:19:50.558Z,1753291190.558 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.222477 min 2025-07-23T17:19:50.558Z,1753291190.558 [Default:CheckIn:E] Stopped 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn] Stopped 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn](INFO): Running loop #6 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn] Running Loop=6 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:19:50.559Z,1753291190.559 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:19:51.358Z,1753291191.358 [NAL9602](INFO): Powering up 2025-07-23T17:20:02.280Z,1753291202.280 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:20:18.436Z,1753291218.436 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for water velocity. Device response is::WS-32768,-32768,V 2025-07-23T17:20:28.131Z,1753291228.131 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2025-07-23T17:20:34.178Z,1753291234.178 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T17:20:34.257Z,1753291234.257 [NAL9602](ERROR): received: +CSQ:0 OK900, 2, 0, 0, 0 OK 2025-07-23T17:20:58.446Z,1753291258.446 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +154.47044.85, -22.45, 0.00,671.99 2025-07-23T17:22:13.979Z,1753291333.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=12901, MT Status=2, MTMSN=0 2025-07-23T17:22:13.979Z,1753291333.979 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T17:22:14.374Z,1753291334.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:22:15.607Z,1753291335.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172214.00,A,3648.16783,N,12147.25820,W,2.916,293.13,230725,,,A*76 2025-07-23T17:22:15.610Z,1753291335.610 [NAL9602](INFO): GPS fix at 20250723T172214: (36.802797, -121.787637) 2025-07-23T17:22:15.621Z,1753291335.621 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:22:15.622Z,1753291335.622 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:22:23.316Z,1753291343.316 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T170141/Courier0012.lzma 2025-07-23T17:22:24.319Z,1753291344.319 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0012.lzma.bak 2025-07-23T17:22:24.319Z,1753291344.319 [DataOverHttps](INFO): SBD MOMSN=25577404 2025-07-23T17:22:43.993Z,1753291363.993 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20250723T170141/Express0013.lzma 2025-07-23T17:22:45.999Z,1753291365.999 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0013.lzma.bak 2025-07-23T17:22:45.999Z,1753291365.999 [DataOverHttps](INFO): SBD MOMSN=25577407 2025-07-23T17:22:47.523Z,1753291367.523 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:22:47.523Z,1753291367.523 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:22:47.523Z,1753291367.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:22:48.314Z,1753291368.314 [NAL9602](INFO): Powering down 2025-07-23T17:27:48.149Z,1753291668.149 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:27:48.149Z,1753291668.149 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:27:48.149Z,1753291668.149 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:27:48.149Z,1753291668.149 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:27:48.513Z,1753291668.513 [Default:CheckIn:D] Stopped 2025-07-23T17:27:48.513Z,1753291668.513 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.194450 min 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn:E] Stopped 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn] Stopped 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn](INFO): Running loop #7 2025-07-23T17:27:48.919Z,1753291668.919 [Default:CheckIn] Running Loop=7 2025-07-23T17:27:48.920Z,1753291668.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:27:48.920Z,1753291668.920 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:27:49.708Z,1753291669.708 [NAL9602](INFO): Powering up 2025-07-23T17:28:00.623Z,1753291680.623 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:30:04.242Z,1753291804.242 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:32:49.127Z,1753291969.127 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T17:27:48.9Z 2025-07-23T17:32:49.127Z,1753291969.127 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:32:49.128Z,1753291969.128 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:33:02.096Z,1753291982.096 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0015.lzma 2025-07-23T17:33:03.091Z,1753291983.091 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0015.lzma.bak 2025-07-23T17:33:03.091Z,1753291983.091 [DataOverHttps](INFO): SBD MOMSN=25577467 2025-07-23T17:33:20.310Z,1753292000.310 [NAL9602](INFO): Powering down 2025-07-23T17:33:28.861Z,1753292008.861 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250723T170141/Express0016.lzma 2025-07-23T17:33:29.851Z,1753292009.851 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0016.lzma.bak 2025-07-23T17:33:29.851Z,1753292009.851 [DataOverHttps](INFO): SBD MOMSN=25577469 2025-07-23T17:33:31.646Z,1753292011.646 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:33:31.646Z,1753292011.646 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:33:31.647Z,1753292011.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:35:05.366Z,1753292105.366 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:35:17.618Z,1753292117.618 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:35:29.333Z,1753292129.333 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:35:41.449Z,1753292141.449 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:35:53.166Z,1753292153.166 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:36:05.307Z,1753292165.307 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:36:17.022Z,1753292177.022 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:36:29.153Z,1753292189.153 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:36:40.844Z,1753292200.844 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:36:53.021Z,1753292213.021 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:37:04.702Z,1753292224.702 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:37:16.803Z,1753292236.803 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:37:28.520Z,1753292248.520 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:37:40.651Z,1753292260.651 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:37:52.367Z,1753292272.367 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:38:04.199Z,1753292284.199 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:38:16.269Z,1753292296.269 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:38:27.926Z,1753292307.926 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:38:32.293Z,1753292312.293 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:38:32.294Z,1753292312.294 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:38:32.294Z,1753292312.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:38:32.294Z,1753292312.294 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:38:32.640Z,1753292312.640 [Default:CheckIn:D] Stopped 2025-07-23T17:38:32.640Z,1753292312.640 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:38:33.047Z,1753292313.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.929907 min 2025-07-23T17:38:33.047Z,1753292313.047 [Default:CheckIn:E] Stopped 2025-07-23T17:38:33.047Z,1753292313.047 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:38:33.047Z,1753292313.047 [Default:CheckIn] Stopped 2025-07-23T17:38:33.047Z,1753292313.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:38:33.047Z,1753292313.047 [Default:CheckIn](INFO): Running loop #8 2025-07-23T17:38:33.048Z,1753292313.048 [Default:CheckIn] Running Loop=8 2025-07-23T17:38:33.048Z,1753292313.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:38:33.048Z,1753292313.048 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:38:33.837Z,1753292313.837 [NAL9602](INFO): Powering up 2025-07-23T17:38:40.047Z,1753292320.047 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:38:44.762Z,1753292324.762 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:38:45.550Z,1753292325.550 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T17:38:45.550Z,1753292325.550 [NAL9602] Data Fault, FailCount= 1 2025-07-23T17:38:45.550Z,1753292325.550 [NAL9602](ERROR): Data Fault 2025-07-23T17:38:45.607Z,1753292325.607 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T17:38:45.964Z,1753292325.964 [NAL9602](INFO): Powering down 2025-07-23T17:38:46.835Z,1753292326.835 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T17:38:46.835Z,1753292326.835 [NAL9602] No Fault, FailCount= 1 2025-07-23T17:38:51.762Z,1753292331.762 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:39:03.862Z,1753292343.862 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:39:14.254Z,1753292354.254 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for water velocity. Device response is::WS,-32768,-32768,-327WE,-32768,-32768,-32768,V 2025-07-23T17:39:15.611Z,1753292355.611 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:39:16.269Z,1753292356.269 [NAL9602](INFO): Powering up NAL9602 2025-07-23T17:39:27.162Z,1753292367.162 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:39:27.698Z,1753292367.698 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:39:39.444Z,1753292379.444 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:39:51.121Z,1753292391.121 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:40:00.285Z,1753292400.285 [BPC1](ERROR): Failed to receive data from all battery packs. 2025-07-23T17:40:00.285Z,1753292400.285 [BPC1] Communications Fault, FailCount= 2 2025-07-23T17:40:00.285Z,1753292400.285 [BPC1](ERROR): Communications Fault 2025-07-23T17:40:00.420Z,1753292400.420 [CBIT](ERROR): Communications Fault in component: BPC1 2025-07-23T17:40:24.177Z,1753292424.177 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BS,-32768-32768,V 2025-07-23T17:41:30.378Z,1753292490.378 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:43:33.240Z,1753292613.240 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T17:38:33.0Z 2025-07-23T17:43:33.240Z,1753292613.240 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:43:33.240Z,1753292613.240 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:43:45.632Z,1753292625.632 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0018.lzma 2025-07-23T17:43:46.635Z,1753292626.635 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0018.lzma.bak 2025-07-23T17:43:46.635Z,1753292626.635 [DataOverHttps](INFO): SBD MOMSN=25577583 2025-07-23T17:44:06.150Z,1753292646.150 [NAL9602](INFO): Powering down 2025-07-23T17:44:11.973Z,1753292651.973 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250723T170141/Express0019.lzma 2025-07-23T17:44:12.975Z,1753292652.975 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0019.lzma.bak 2025-07-23T17:44:12.975Z,1753292652.975 [DataOverHttps](INFO): SBD MOMSN=25577585 2025-07-23T17:44:14.656Z,1753292654.656 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:44:14.656Z,1753292654.656 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:44:14.656Z,1753292654.656 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:49:15.277Z,1753292955.277 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:49:15.277Z,1753292955.277 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:49:15.277Z,1753292955.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:49:15.277Z,1753292955.277 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:49:15.662Z,1753292955.662 [Default:CheckIn:D] Stopped 2025-07-23T17:49:15.662Z,1753292955.662 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:49:16.172Z,1753292956.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.646940 min 2025-07-23T17:49:16.172Z,1753292956.172 [Default:CheckIn:E] Stopped 2025-07-23T17:49:16.172Z,1753292956.172 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:49:16.172Z,1753292956.172 [Default:CheckIn] Stopped 2025-07-23T17:49:16.181Z,1753292956.181 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:49:16.181Z,1753292956.181 [Default:CheckIn](INFO): Running loop #9 2025-07-23T17:49:16.181Z,1753292956.181 [Default:CheckIn] Running Loop=9 2025-07-23T17:49:16.181Z,1753292956.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:49:16.181Z,1753292956.181 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:49:16.841Z,1753292956.841 [NAL9602](INFO): Powering up 2025-07-23T17:49:27.766Z,1753292967.766 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:49:28.574Z,1753292968.574 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T17:49:28.574Z,1753292968.574 [NAL9602] Data Fault, FailCount= 2 2025-07-23T17:49:28.574Z,1753292968.574 [NAL9602](ERROR): Data Fault 2025-07-23T17:49:28.647Z,1753292968.647 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T17:49:28.974Z,1753292968.974 [NAL9602](INFO): Powering down 2025-07-23T17:49:29.826Z,1753292969.826 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T17:49:29.826Z,1753292969.826 [NAL9602] No Fault, FailCount= 2 2025-07-23T17:49:59.274Z,1753292999.274 [NAL9602](INFO): Powering up NAL9602 2025-07-23T17:50:01.353Z,1753293001.353 [CBIT](INFO): Clearing failed state for component BPC1 2025-07-23T17:50:01.353Z,1753293001.353 [BPC1] No Fault, FailCount= 2 2025-07-23T17:50:10.174Z,1753293010.174 [NAL9602](INFO): NAL9602 initialized 2025-07-23T17:51:50.834Z,1753293110.834 [CommandExec](IMPORTANT): got command strobe off 2025-07-23T17:51:50.834Z,1753293110.834 [CommandExec](IMPORTANT): Deactivating strobe 2025-07-23T17:52:13.115Z,1753293133.115 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T17:54:16.412Z,1753293256.412 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T17:49:16.2Z 2025-07-23T17:54:16.412Z,1753293256.412 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:54:16.421Z,1753293256.421 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:54:28.444Z,1753293268.444 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0021.lzma 2025-07-23T17:54:29.447Z,1753293269.447 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0021.lzma.bak 2025-07-23T17:54:29.447Z,1753293269.447 [DataOverHttps](INFO): SBD MOMSN=25577636 2025-07-23T17:54:47.670Z,1753293287.670 [NAL9602](INFO): Powering down 2025-07-23T17:54:55.641Z,1753293295.641 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20250723T170141/Express0022.lzma 2025-07-23T17:54:55.863Z,1753293295.863 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:54:56.643Z,1753293296.643 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0022.lzma.bak 2025-07-23T17:54:56.643Z,1753293296.643 [DataOverHttps](INFO): SBD MOMSN=25577638 2025-07-23T17:54:58.335Z,1753293298.335 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:54:58.336Z,1753293298.336 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:54:58.336Z,1753293298.336 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:55:07.651Z,1753293307.651 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:55:19.340Z,1753293319.340 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:55:31.440Z,1753293331.440 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:55:43.230Z,1753293343.230 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:55:55.294Z,1753293355.294 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:56:07.009Z,1753293367.009 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:56:19.151Z,1753293379.151 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:56:30.846Z,1753293390.846 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:56:42.934Z,1753293402.934 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:56:54.725Z,1753293414.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:57:06.381Z,1753293426.381 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:57:18.554Z,1753293438.554 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:57:30.310Z,1753293450.310 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:57:42.379Z,1753293462.379 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:57:54.139Z,1753293474.139 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:58:06.348Z,1753293486.348 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:58:16.068Z,1753293496.068 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T17:58:18.099Z,1753293498.099 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:58:30.411Z,1753293510.411 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:58:42.480Z,1753293522.480 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:58:54.247Z,1753293534.247 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:06.279Z,1753293546.279 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:17.627Z,1753293557.627 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:29.706Z,1753293569.706 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:41.449Z,1753293581.449 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:53.186Z,1753293593.186 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:53.562Z,1753293593.562 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T17:59:58.707Z,1753293598.707 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:59:58.707Z,1753293598.707 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:59:58.707Z,1753293598.707 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:59:58.707Z,1753293598.707 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:59:59.131Z,1753293599.131 [Default:CheckIn:D] Stopped 2025-07-23T17:59:59.131Z,1753293599.131 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:59:59.529Z,1753293599.529 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.371411 min 2025-07-23T17:59:59.529Z,1753293599.529 [Default:CheckIn:E] Stopped 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn] Stopped 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn](INFO): Running loop #10 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn] Running Loop=10 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:59:59.530Z,1753293599.530 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:00:00.298Z,1753293600.298 [NAL9602](INFO): Powering up 2025-07-23T18:00:11.191Z,1753293611.191 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:00:12.007Z,1753293612.007 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T18:00:12.007Z,1753293612.007 [NAL9602] Data Fault, FailCount= 3 2025-07-23T18:00:12.007Z,1753293612.007 [NAL9602](ERROR): Data Fault 2025-07-23T18:00:12.116Z,1753293612.116 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T18:00:12.414Z,1753293612.414 [NAL9602](INFO): Powering down 2025-07-23T18:00:13.275Z,1753293613.275 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T18:00:13.275Z,1753293613.275 [NAL9602] No Fault, FailCount= 3 2025-07-23T18:00:42.710Z,1753293642.710 [NAL9602](INFO): Powering up NAL9602 2025-07-23T18:00:46.744Z,1753293646.744 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-07-23T18:00:53.619Z,1753293653.619 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:01:18.363Z,1753293678.363 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:02:56.838Z,1753293776.838 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T18:04:52.469Z,1753293892.469 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:04:59.687Z,1753293899.687 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T17:59:59.5Z 2025-07-23T18:04:59.687Z,1753293899.687 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:04:59.690Z,1753293899.690 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:05:04.167Z,1753293904.167 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:05:12.328Z,1753293912.328 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0024.lzma 2025-07-23T18:05:13.339Z,1753293913.339 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0024.lzma.bak 2025-07-23T18:05:13.339Z,1753293913.339 [DataOverHttps](INFO): SBD MOMSN=25577706 2025-07-23T18:05:16.236Z,1753293916.236 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:05:27.957Z,1753293927.957 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:05:32.658Z,1753293932.658 [NAL9602](INFO): Powering down 2025-07-23T18:05:37.653Z,1753293937.653 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20250723T170141/Express0025.lzma 2025-07-23T18:05:38.652Z,1753293938.652 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0025.lzma.bak 2025-07-23T18:05:38.652Z,1753293938.652 [DataOverHttps](INFO): SBD MOMSN=25577708 2025-07-23T18:05:40.148Z,1753293940.148 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:05:40.257Z,1753293940.257 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:05:40.257Z,1753293940.257 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:05:40.257Z,1753293940.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:05:51.819Z,1753293951.819 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:06:03.530Z,1753293963.530 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:06:15.658Z,1753293975.658 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:06:27.366Z,1753293987.366 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:06:30.956Z,1753293990.956 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:06:39.471Z,1753293999.471 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:06:51.183Z,1753294011.183 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:07:03.377Z,1753294023.377 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:07:15.055Z,1753294035.055 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:07:27.160Z,1753294047.160 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:07:38.874Z,1753294058.874 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:07:51.016Z,1753294071.016 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:02.699Z,1753294082.699 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:14.838Z,1753294094.838 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:26.179Z,1753294106.179 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:37.873Z,1753294117.873 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:41.514Z,1753294121.514 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:49.566Z,1753294129.566 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:08:54.382Z,1753294134.382 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:01.614Z,1753294141.614 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:13.422Z,1753294153.422 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:25.458Z,1753294165.458 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:37.163Z,1753294177.163 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:39.174Z,1753294179.174 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:49.292Z,1753294189.292 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:09:50.119Z,1753294190.119 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:10:02.610Z,1753294202.610 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:10:20.784Z,1753294220.784 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:10:40.540Z,1753294240.540 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:10:40.557Z,1753294240.557 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:10:40.557Z,1753294240.557 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:10:40.557Z,1753294240.557 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:10:40.948Z,1753294240.948 [Default:CheckIn:D] Stopped 2025-07-23T18:10:40.949Z,1753294240.949 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:10:41.347Z,1753294241.347 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.068376 min 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn:E] Stopped 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn] Stopped 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn](INFO): Running loop #11 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn] Running Loop=11 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:10:41.348Z,1753294241.348 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:10:42.142Z,1753294242.142 [NAL9602](INFO): Powering up 2025-07-23T18:10:53.071Z,1753294253.071 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:11:24.982Z,1753294284.982 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T18:11:25.058Z,1753294285.058 [NAL9602](ERROR): received: +CSQ:0 OK901, 2, 0, 0, 0 OK 2025-07-23T18:12:56.283Z,1753294376.283 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T18:12:57.090Z,1753294377.090 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T18:12:57.091Z,1753294377.091 [NAL9602] Data Fault, FailCount= 4 2025-07-23T18:12:57.091Z,1753294377.091 [NAL9602](ERROR): Data Fault 2025-07-23T18:12:57.143Z,1753294377.143 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T18:12:57.502Z,1753294377.502 [NAL9602](INFO): Powering down 2025-07-23T18:12:58.373Z,1753294378.373 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T18:12:58.373Z,1753294378.373 [NAL9602] No Fault, FailCount= 4 2025-07-23T18:13:27.806Z,1753294407.806 [NAL9602](INFO): Powering up NAL9602 2025-07-23T18:13:38.704Z,1753294418.704 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:14:49.456Z,1753294489.456 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:15:01.187Z,1753294501.187 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:15:13.385Z,1753294513.385 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:15:25.057Z,1753294525.057 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:15:37.146Z,1753294537.146 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:15:41.538Z,1753294541.538 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T18:10:41.3Z 2025-07-23T18:15:41.538Z,1753294541.538 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:15:41.538Z,1753294541.538 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:15:48.720Z,1753294548.720 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0027.lzma 2025-07-23T18:15:48.860Z,1753294548.860 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:15:49.723Z,1753294549.723 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0027.lzma.bak 2025-07-23T18:15:49.723Z,1753294549.723 [DataOverHttps](INFO): SBD MOMSN=25577793 2025-07-23T18:16:01.009Z,1753294561.009 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:16:06.044Z,1753294566.044 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20250723T170141/Express0028.lzma 2025-07-23T18:16:07.042Z,1753294567.042 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0028.lzma.bak 2025-07-23T18:16:07.042Z,1753294567.042 [DataOverHttps](INFO): SBD MOMSN=25577795 2025-07-23T18:16:08.604Z,1753294568.604 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:16:08.621Z,1753294568.621 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:16:08.621Z,1753294568.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:16:12.319Z,1753294572.319 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:16:13.048Z,1753294573.048 [NAL9602](INFO): Powering down 2025-07-23T18:16:24.415Z,1753294584.415 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:16:35.740Z,1753294595.740 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:16:36.155Z,1753294596.155 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:16:48.314Z,1753294608.314 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:16:59.967Z,1753294619.967 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:17:12.137Z,1753294632.137 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:17:23.854Z,1753294643.854 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:17:35.520Z,1753294655.520 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:17:47.238Z,1753294667.238 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:17:59.415Z,1753294679.415 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:18:11.091Z,1753294691.091 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:18:22.781Z,1753294702.781 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:18:34.573Z,1753294714.573 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:18:45.396Z,1753294725.396 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:18:46.623Z,1753294726.623 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:18:58.387Z,1753294738.387 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:19:10.531Z,1753294750.531 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:19:22.172Z,1753294762.172 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:19:34.296Z,1753294774.296 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:19:46.145Z,1753294786.145 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:19:51.240Z,1753294791.240 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:20:30.886Z,1753294830.886 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T18:20:57.140Z,1753294857.140 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T18:21:01.891Z,1753294861.891 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T18:21:01.891Z,1753294861.891 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T18:21:01.892Z,1753294861.892 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T18:21:01.894Z,1753294861.894 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T18:21:01.895Z,1753294861.895 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T18:21:01.895Z,1753294861.895 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T18:21:01.896Z,1753294861.896 [BPC1](INFO): Calculating totals. Valid battery stick count: 16. Valid reserve battery stick count: 1. 2025-07-23T18:21:01.899Z,1753294861.899 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-07-23T18:21:09.176Z,1753294869.176 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:21:09.176Z,1753294869.176 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:21:09.176Z,1753294869.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:21:09.176Z,1753294869.176 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:21:09.612Z,1753294869.612 [Default:CheckIn:D] Stopped 2025-07-23T18:21:09.612Z,1753294869.612 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.546102 min 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn:E] Stopped 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn] Stopped 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn](INFO): Running loop #12 2025-07-23T18:21:09.987Z,1753294869.987 [Default:CheckIn] Running Loop=12 2025-07-23T18:21:09.988Z,1753294869.988 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:21:09.988Z,1753294869.988 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:21:10.778Z,1753294870.778 [NAL9602](INFO): Powering up 2025-07-23T18:21:21.686Z,1753294881.686 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:23:24.910Z,1753295004.910 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T18:23:41.875Z,1753295021.875 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T18:23:41.875Z,1753295021.875 [NAL9602] Data Fault, FailCount= 5 2025-07-23T18:23:41.876Z,1753295021.876 [NAL9602](ERROR): Data Fault 2025-07-23T18:23:41.896Z,1753295021.896 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T18:23:41.896Z,1753295021.896 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-07-23T18:23:42.286Z,1753295022.286 [NAL9602](INFO): Powering down 2025-07-23T18:23:42.349Z,1753295022.349 [CommandExec](FAULT): Scheduling is paused 2025-07-23T18:23:42.350Z,1753295022.350 [CBIT](INFO): Critical error at 20250723T182341 2025-07-23T18:25:41.890Z,1753295141.890 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T18:25:41.890Z,1753295141.890 [NAL9602] No Fault, FailCount= 5 2025-07-23T18:25:42.272Z,1753295142.272 [NAL9602](INFO): Powering up NAL9602 2025-07-23T18:25:53.174Z,1753295153.174 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:26:10.190Z,1753295170.190 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T18:21:09.0Z 2025-07-23T18:26:10.190Z,1753295170.190 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:26:10.191Z,1753295170.191 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:26:17.429Z,1753295177.429 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20250723T170141/Courier0030.lzma 2025-07-23T18:26:18.431Z,1753295178.431 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0030.lzma.bak 2025-07-23T18:26:18.431Z,1753295178.431 [DataOverHttps](INFO): SBD MOMSN=25577886 2025-07-23T18:26:34.553Z,1753295194.553 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20250723T170141/Express0031.lzma 2025-07-23T18:26:35.555Z,1753295195.555 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0031.lzma.bak 2025-07-23T18:26:35.555Z,1753295195.555 [DataOverHttps](INFO): SBD MOMSN=25577889 2025-07-23T18:26:36.892Z,1753295196.892 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:26:36.892Z,1753295196.892 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:26:36.892Z,1753295196.892 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:26:43.343Z,1753295203.343 [NAL9602](INFO): Powering down 2025-07-23T18:31:37.492Z,1753295497.492 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:31:37.492Z,1753295497.492 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:31:37.492Z,1753295497.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:31:37.493Z,1753295497.493 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:31:37.897Z,1753295497.897 [Default:CheckIn:D] Stopped 2025-07-23T18:31:37.897Z,1753295497.897 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.017521 min 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn:E] Stopped 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn] Stopped 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn](INFO): Running loop #13 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn] Running Loop=13 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:31:38.318Z,1753295498.318 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:31:39.093Z,1753295499.093 [NAL9602](INFO): Powering up 2025-07-23T18:31:50.047Z,1753295510.047 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:33:53.222Z,1753295633.222 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T18:35:55.634Z,1753295755.634 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T18:35:55.634Z,1753295755.634 [NAL9602] Data Fault, FailCount= 1 2025-07-23T18:35:55.634Z,1753295755.634 [NAL9602](ERROR): Data Fault 2025-07-23T18:35:55.707Z,1753295755.707 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T18:35:56.051Z,1753295756.051 [NAL9602](INFO): Powering down 2025-07-23T18:35:56.870Z,1753295756.870 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T18:35:56.870Z,1753295756.870 [NAL9602] No Fault, FailCount= 1 2025-07-23T18:36:26.343Z,1753295786.343 [NAL9602](INFO): Powering up NAL9602 2025-07-23T18:36:37.250Z,1753295797.250 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:36:38.493Z,1753295798.493 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T18:31:38.3Z 2025-07-23T18:36:38.493Z,1753295798.493 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:36:38.493Z,1753295798.493 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:36:46.400Z,1753295806.400 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0033.lzma 2025-07-23T18:36:47.403Z,1753295807.403 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0033.lzma.bak 2025-07-23T18:36:47.403Z,1753295807.403 [DataOverHttps](INFO): SBD MOMSN=25577937 2025-07-23T18:37:03.593Z,1753295823.593 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250723T170141/Express0034.lzma 2025-07-23T18:37:04.595Z,1753295824.595 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0034.lzma.bak 2025-07-23T18:37:04.595Z,1753295824.595 [DataOverHttps](INFO): SBD MOMSN=25577940 2025-07-23T18:37:05.945Z,1753295825.945 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:37:05.945Z,1753295825.945 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:37:05.946Z,1753295825.946 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:37:10.378Z,1753295830.378 [NAL9602](INFO): Powering down 2025-07-23T18:37:12.067Z,1753295832.067 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.0 +0.00, +0.00, 20.00,5854.27 2025-07-23T18:42:06.517Z,1753296126.517 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:42:06.517Z,1753296126.517 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:42:06.518Z,1753296126.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:42:06.518Z,1753296126.518 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:42:06.922Z,1753296126.922 [Default:CheckIn:D] Stopped 2025-07-23T18:42:06.922Z,1753296126.922 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:42:07.326Z,1753296127.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.501278 min 2025-07-23T18:42:07.326Z,1753296127.326 [Default:CheckIn:E] Stopped 2025-07-23T18:42:07.326Z,1753296127.326 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:42:07.326Z,1753296127.326 [Default:CheckIn] Stopped 2025-07-23T18:42:07.326Z,1753296127.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:42:07.327Z,1753296127.327 [Default:CheckIn](INFO): Running loop #14 2025-07-23T18:42:07.327Z,1753296127.327 [Default:CheckIn] Running Loop=14 2025-07-23T18:42:07.328Z,1753296127.328 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:42:07.328Z,1753296127.328 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:42:08.122Z,1753296128.122 [NAL9602](INFO): Powering up 2025-07-23T18:42:19.030Z,1753296139.030 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:44:22.251Z,1753296262.251 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T18:46:38.402Z,1753296398.402 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T18:46:38.402Z,1753296398.402 [NAL9602] Data Fault, FailCount= 2 2025-07-23T18:46:38.402Z,1753296398.402 [NAL9602](ERROR): Data Fault 2025-07-23T18:46:38.422Z,1753296398.422 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T18:46:38.803Z,1753296398.803 [NAL9602](INFO): Powering down 2025-07-23T18:46:39.690Z,1753296399.690 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T18:46:39.690Z,1753296399.690 [NAL9602] No Fault, FailCount= 2 2025-07-23T18:47:07.530Z,1753296427.530 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T18:42:07.3Z 2025-07-23T18:47:07.530Z,1753296427.530 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:47:07.530Z,1753296427.530 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:47:09.110Z,1753296429.110 [NAL9602](INFO): Powering up NAL9602 2025-07-23T18:47:15.200Z,1753296435.200 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0036.lzma 2025-07-23T18:47:16.203Z,1753296436.203 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0036.lzma.bak 2025-07-23T18:47:16.203Z,1753296436.203 [DataOverHttps](INFO): SBD MOMSN=25578006 2025-07-23T18:47:20.044Z,1753296440.044 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:47:32.517Z,1753296452.517 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250723T170141/Express0037.lzma 2025-07-23T18:47:33.519Z,1753296453.519 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0037.lzma.bak 2025-07-23T18:47:33.519Z,1753296453.519 [DataOverHttps](INFO): SBD MOMSN=25578008 2025-07-23T18:47:34.984Z,1753296454.984 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:47:34.985Z,1753296454.985 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:47:34.985Z,1753296454.985 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:47:51.126Z,1753296471.126 [NAL9602](INFO): Powering down 2025-07-23T18:52:35.557Z,1753296755.557 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:52:35.558Z,1753296755.558 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:52:35.558Z,1753296755.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:52:35.558Z,1753296755.558 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:52:35.975Z,1753296755.975 [Default:CheckIn:D] Stopped 2025-07-23T18:52:35.975Z,1753296755.975 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.985474 min 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn:E] Stopped 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn] Stopped 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn](INFO): Running loop #15 2025-07-23T18:52:36.389Z,1753296756.389 [Default:CheckIn] Running Loop=15 2025-07-23T18:52:36.390Z,1753296756.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:52:36.390Z,1753296756.390 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:52:37.158Z,1753296757.158 [NAL9602](INFO): Powering up 2025-07-23T18:52:48.070Z,1753296768.070 [NAL9602](INFO): NAL9602 initialized 2025-07-23T18:54:51.296Z,1753296891.296 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T18:57:36.565Z,1753297056.565 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T18:52:36.4Z 2025-07-23T18:57:36.565Z,1753297056.565 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:57:36.565Z,1753297056.565 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:57:43.784Z,1753297063.784 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0039.lzma 2025-07-23T18:57:44.787Z,1753297064.787 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0039.lzma.bak 2025-07-23T18:57:44.787Z,1753297064.787 [DataOverHttps](INFO): SBD MOMSN=25578074 2025-07-23T18:58:01.161Z,1753297081.161 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250723T170141/Express0040.lzma 2025-07-23T18:58:02.163Z,1753297082.163 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0040.lzma.bak 2025-07-23T18:58:02.163Z,1753297082.163 [DataOverHttps](INFO): SBD MOMSN=25578076 2025-07-23T18:58:03.616Z,1753297083.616 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:58:03.617Z,1753297083.617 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:58:03.617Z,1753297083.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:58:08.050Z,1753297088.050 [NAL9602](INFO): Powering down 2025-07-23T19:03:04.217Z,1753297384.217 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:03:04.217Z,1753297384.217 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:03:04.217Z,1753297384.217 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:03:04.218Z,1753297384.218 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:03:04.617Z,1753297384.617 [Default:CheckIn:D] Stopped 2025-07-23T19:03:04.617Z,1753297384.617 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:03:04.996Z,1753297384.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.462581 min 2025-07-23T19:03:04.996Z,1753297384.996 [Default:CheckIn:E] Stopped 2025-07-23T19:03:04.996Z,1753297384.996 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:03:04.996Z,1753297384.996 [Default:CheckIn] Stopped 2025-07-23T19:03:04.998Z,1753297384.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:03:04.998Z,1753297384.998 [Default:CheckIn](INFO): Running loop #16 2025-07-23T19:03:04.998Z,1753297384.998 [Default:CheckIn] Running Loop=16 2025-07-23T19:03:04.998Z,1753297384.998 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:03:04.998Z,1753297384.998 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:03:05.797Z,1753297385.797 [NAL9602](INFO): Powering up 2025-07-23T19:03:16.703Z,1753297396.703 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:03:17.511Z,1753297397.511 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T19:03:17.512Z,1753297397.512 [NAL9602] Data Fault, FailCount= 1 2025-07-23T19:03:17.512Z,1753297397.512 [NAL9602](ERROR): Data Fault 2025-07-23T19:03:17.532Z,1753297397.532 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T19:03:17.914Z,1753297397.914 [NAL9602](INFO): Powering down 2025-07-23T19:03:18.756Z,1753297398.756 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T19:03:18.756Z,1753297398.756 [NAL9602] No Fault, FailCount= 1 2025-07-23T19:03:48.222Z,1753297428.222 [NAL9602](INFO): Powering up NAL9602 2025-07-23T19:03:59.124Z,1753297439.124 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:06:02.344Z,1753297562.344 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:08:05.178Z,1753297685.178 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T19:03:04.0Z 2025-07-23T19:08:05.178Z,1753297685.178 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:08:05.179Z,1753297685.179 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:08:12.316Z,1753297692.316 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0042.lzma 2025-07-23T19:08:13.319Z,1753297693.319 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0042.lzma.bak 2025-07-23T19:08:13.319Z,1753297693.319 [DataOverHttps](INFO): SBD MOMSN=25578177 2025-07-23T19:08:30.133Z,1753297710.133 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20250723T170141/Express0043.lzma 2025-07-23T19:08:31.135Z,1753297711.135 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0043.lzma.bak 2025-07-23T19:08:31.135Z,1753297711.135 [DataOverHttps](INFO): SBD MOMSN=25578179 2025-07-23T19:08:32.648Z,1753297712.648 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:08:32.648Z,1753297712.648 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:08:32.665Z,1753297712.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:08:38.294Z,1753297718.294 [NAL9602](INFO): Powering down 2025-07-23T19:10:46.030Z,1753297846.030 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.0090 2025-07-23T19:13:33.256Z,1753298013.256 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:13:33.256Z,1753298013.256 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:13:33.257Z,1753298013.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:13:33.257Z,1753298013.257 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:13:33.670Z,1753298013.670 [Default:CheckIn:D] Stopped 2025-07-23T19:13:33.671Z,1753298013.671 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.947087 min 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn:E] Stopped 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn] Stopped 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn](INFO): Running loop #17 2025-07-23T19:13:34.096Z,1753298014.096 [Default:CheckIn] Running Loop=17 2025-07-23T19:13:34.097Z,1753298014.097 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:13:34.097Z,1753298014.097 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:13:34.852Z,1753298014.852 [NAL9602](INFO): Powering up 2025-07-23T19:13:45.755Z,1753298025.755 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:14:17.670Z,1753298057.670 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T19:14:17.753Z,1753298057.753 [NAL9602](ERROR): received: +CSQ:0 OK901, 2, 0, 0, 0 OK 2025-07-23T19:15:48.990Z,1753298148.990 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:15:49.783Z,1753298149.783 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T19:15:49.784Z,1753298149.784 [NAL9602] Data Fault, FailCount= 2 2025-07-23T19:15:49.784Z,1753298149.784 [NAL9602](ERROR): Data Fault 2025-07-23T19:15:49.805Z,1753298149.805 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T19:15:50.191Z,1753298150.191 [NAL9602](INFO): Powering down 2025-07-23T19:15:51.013Z,1753298151.013 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T19:15:51.013Z,1753298151.013 [NAL9602] No Fault, FailCount= 2 2025-07-23T19:16:20.558Z,1753298180.558 [NAL9602](INFO): Powering up NAL9602 2025-07-23T19:16:24.207Z,1753298184.207 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, , +0.00, +0.00, 0.00,7594.81 2025-07-23T19:16:31.462Z,1753298191.462 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:18:34.314Z,1753298314.314 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T19:13:34.1Z 2025-07-23T19:18:34.314Z,1753298314.314 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:18:34.315Z,1753298314.315 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:18:42.320Z,1753298322.320 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0045.lzma 2025-07-23T19:18:43.323Z,1753298323.323 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0045.lzma.bak 2025-07-23T19:18:43.323Z,1753298323.323 [DataOverHttps](INFO): SBD MOMSN=25578220 2025-07-23T19:19:00.029Z,1753298340.029 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250723T170141/Express0046.lzma 2025-07-23T19:19:01.031Z,1753298341.031 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0046.lzma.bak 2025-07-23T19:19:01.031Z,1753298341.031 [DataOverHttps](INFO): SBD MOMSN=25578227 2025-07-23T19:19:02.577Z,1753298342.577 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:19:02.577Z,1753298342.577 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:19:02.578Z,1753298342.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:19:05.794Z,1753298345.794 [NAL9602](INFO): Powering down 2025-07-23T19:20:46.213Z,1753298446.213 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.939285 2025-07-23T19:21:19.345Z,1753298479.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:21:54.497Z,1753298514.497 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:22:29.641Z,1753298549.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:23:04.781Z,1753298584.781 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:23:39.925Z,1753298619.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:24:03.159Z,1753298643.159 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:24:03.160Z,1753298643.160 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:24:03.160Z,1753298643.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:24:03.160Z,1753298643.160 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:24:03.567Z,1753298643.567 [Default:CheckIn:D] Stopped 2025-07-23T19:24:03.567Z,1753298643.567 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:24:04.006Z,1753298644.006 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.445361 min 2025-07-23T19:24:04.006Z,1753298644.006 [Default:CheckIn:E] Stopped 2025-07-23T19:24:04.006Z,1753298644.006 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:24:04.006Z,1753298644.006 [Default:CheckIn] Stopped 2025-07-23T19:24:04.007Z,1753298644.007 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:24:04.007Z,1753298644.007 [Default:CheckIn](INFO): Running loop #18 2025-07-23T19:24:04.007Z,1753298644.007 [Default:CheckIn] Running Loop=18 2025-07-23T19:24:04.007Z,1753298644.007 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:24:04.007Z,1753298644.007 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:24:04.750Z,1753298644.750 [NAL9602](INFO): Powering up 2025-07-23T19:24:15.078Z,1753298655.078 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:24:15.665Z,1753298655.665 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:24:21.323Z,1753298661.323 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:24:50.221Z,1753298690.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:25:25.365Z,1753298725.365 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:26:00.505Z,1753298760.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:26:18.539Z,1753298778.539 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:26:33.088Z,1753298793.088 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T19:26:33.089Z,1753298793.089 [NAL9602] Data Fault, FailCount= 3 2025-07-23T19:26:33.089Z,1753298793.089 [NAL9602](ERROR): Data Fault 2025-07-23T19:26:33.144Z,1753298793.144 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T19:26:33.436Z,1753298793.436 [NAL9602](INFO): Powering down 2025-07-23T19:26:34.314Z,1753298794.314 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T19:26:34.314Z,1753298794.314 [NAL9602] No Fault, FailCount= 3 2025-07-23T19:26:35.657Z,1753298795.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:27:03.730Z,1753298823.730 [NAL9602](INFO): Powering up NAL9602 2025-07-23T19:27:10.797Z,1753298830.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:27:14.638Z,1753298834.638 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:27:45.938Z,1753298865.938 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:28:21.077Z,1753298901.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:28:56.217Z,1753298936.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:29:04.165Z,1753298944.165 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T19:24:04.0Z 2025-07-23T19:29:04.165Z,1753298944.165 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:29:04.165Z,1753298944.165 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:29:17.350Z,1753298957.350 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003809 2025-07-23T19:29:24.832Z,1753298964.832 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0048.lzma 2025-07-23T19:29:25.831Z,1753298965.831 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0048.lzma.bak 2025-07-23T19:29:25.831Z,1753298965.831 [DataOverHttps](INFO): SBD MOMSN=25578270 2025-07-23T19:29:42.441Z,1753298982.441 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250723T170141/Express0049.lzma 2025-07-23T19:29:43.443Z,1753298983.443 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0049.lzma.bak 2025-07-23T19:29:43.443Z,1753298983.443 [DataOverHttps](INFO): SBD MOMSN=25578275 2025-07-23T19:29:44.937Z,1753298984.937 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:29:44.937Z,1753298984.937 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:29:44.937Z,1753298984.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:29:47.750Z,1753298987.750 [NAL9602](INFO): Powering down 2025-07-23T19:30:31.429Z,1753299031.429 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:34:20.101Z,1753299260.101 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:34:24.935Z,1753299264.935 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:34:36.677Z,1753299276.677 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:34:45.525Z,1753299285.525 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:34:45.525Z,1753299285.525 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:34:45.525Z,1753299285.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:34:45.526Z,1753299285.526 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:34:45.957Z,1753299285.957 [Default:CheckIn:D] Stopped 2025-07-23T19:34:45.957Z,1753299285.957 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.151774 min 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn:E] Stopped 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn] Stopped 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn](INFO): Running loop #19 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn] Running Loop=19 2025-07-23T19:34:46.366Z,1753299286.366 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:34:46.367Z,1753299286.367 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:34:47.130Z,1753299287.130 [NAL9602](INFO): Powering up 2025-07-23T19:34:48.399Z,1753299288.399 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:34:58.034Z,1753299298.034 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:35:00.058Z,1753299300.058 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000128232153 6,35.0 0.0,1524.4, 0 2025-07-23T19:35:00.525Z,1753299300.525 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:35:12.234Z,1753299312.234 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:35:24.355Z,1753299324.355 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:35:36.073Z,1753299336.073 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:35:47.855Z,1753299347.855 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:35:59.505Z,1753299359.505 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:00.296Z,1753299360.296 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:00.347Z,1753299360.347 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for bottom velocity. Device response is::BS,-32768-32768,V 2025-07-23T19:36:11.624Z,1753299371.624 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:23.398Z,1753299383.398 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:27.771Z,1753299387.771 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:34.648Z,1753299394.648 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:46.367Z,1753299406.367 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:36:58.540Z,1753299418.540 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:01.248Z,1753299421.248 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:37:01.703Z,1753299421.703 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:10.202Z,1753299430.202 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:17.408Z,1753299437.408 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T19:37:17.408Z,1753299437.408 [NAL9602] Data Fault, FailCount= 4 2025-07-23T19:37:17.408Z,1753299437.408 [NAL9602](ERROR): Data Fault 2025-07-23T19:37:17.433Z,1753299437.433 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T19:37:17.814Z,1753299437.814 [NAL9602](INFO): Powering down 2025-07-23T19:37:18.644Z,1753299438.644 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T19:37:18.644Z,1753299438.644 [NAL9602] No Fault, FailCount= 4 2025-07-23T19:37:22.323Z,1753299442.323 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:34.143Z,1753299454.143 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:40.886Z,1753299460.886 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:46.165Z,1753299466.165 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:48.111Z,1753299468.111 [NAL9602](INFO): Powering up NAL9602 2025-07-23T19:37:57.878Z,1753299477.878 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:37:59.018Z,1753299479.018 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:38:09.639Z,1753299489.639 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:38:20.109Z,1753299500.109 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:38:21.713Z,1753299501.713 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:38:33.448Z,1753299513.448 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:38:45.613Z,1753299525.613 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:38:57.272Z,1753299537.272 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:38:59.668Z,1753299539.668 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:39:08.986Z,1753299548.986 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:39:21.163Z,1753299561.163 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:39:46.503Z,1753299586.503 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T19:34:46.4Z 2025-07-23T19:39:46.503Z,1753299586.503 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:39:46.503Z,1753299586.503 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:39:53.050Z,1753299593.050 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:39:53.688Z,1753299593.688 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0051.lzma 2025-07-23T19:39:54.691Z,1753299594.691 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0051.lzma.bak 2025-07-23T19:39:54.691Z,1753299594.691 [DataOverHttps](INFO): SBD MOMSN=25578314 2025-07-23T19:40:09.989Z,1753299609.989 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20250723T170141/Express0052.lzma 2025-07-23T19:40:10.991Z,1753299610.991 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0052.lzma.bak 2025-07-23T19:40:10.991Z,1753299610.991 [DataOverHttps](INFO): SBD MOMSN=25578316 2025-07-23T19:40:12.413Z,1753299612.413 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:40:12.413Z,1753299612.413 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:40:12.414Z,1753299612.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:40:17.248Z,1753299617.248 [NAL9602](INFO): Powering down 2025-07-23T19:44:21.711Z,1753299861.711 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:44:33.445Z,1753299873.445 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:44:45.561Z,1753299885.561 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:44:51.618Z,1753299891.618 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:44:57.282Z,1753299897.282 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:45:09.398Z,1753299909.398 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:45:12.976Z,1753299912.976 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:45:12.976Z,1753299912.976 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:45:12.976Z,1753299912.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:45:12.977Z,1753299912.977 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:45:13.381Z,1753299913.381 [Default:CheckIn:D] Stopped 2025-07-23T19:45:13.381Z,1753299913.381 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:45:13.786Z,1753299913.786 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.608919 min 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn:E] Stopped 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn] Stopped 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn](INFO): Running loop #20 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn] Running Loop=20 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:45:13.787Z,1753299913.787 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:45:14.585Z,1753299914.585 [NAL9602](INFO): Powering up 2025-07-23T19:45:21.126Z,1753299921.126 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:45:25.496Z,1753299925.496 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:45:33.235Z,1753299933.235 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:45:44.951Z,1753299944.951 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:45:57.070Z,1753299957.070 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:46:08.787Z,1753299968.787 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:46:20.917Z,1753299980.917 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:46:32.207Z,1753299992.207 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:46:32.634Z,1753299992.634 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:46:44.743Z,1753300004.743 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:46:56.460Z,1753300016.460 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:47:07.770Z,1753300027.770 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:47:19.520Z,1753300039.520 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:47:28.712Z,1753300048.712 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:47:31.204Z,1753300051.204 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:47:36.836Z,1753300056.836 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T19:47:42.918Z,1753300062.918 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:47:55.043Z,1753300075.043 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:48:00.638Z,1753300080.638 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T19:48:00.639Z,1753300080.639 [NAL9602] Data Fault, FailCount= 5 2025-07-23T19:48:00.639Z,1753300080.639 [NAL9602](ERROR): Data Fault 2025-07-23T19:48:00.660Z,1753300080.660 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T19:48:00.662Z,1753300080.662 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-07-23T19:48:01.036Z,1753300081.036 [NAL9602](INFO): Powering down 2025-07-23T19:48:01.068Z,1753300081.068 [CBIT](INFO): Critical error at 20250723T194800 2025-07-23T19:48:06.754Z,1753300086.754 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:48:18.875Z,1753300098.875 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:48:30.622Z,1753300110.622 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:48:42.310Z,1753300122.310 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:48:54.052Z,1753300134.052 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:49:05.740Z,1753300145.740 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:49:17.859Z,1753300157.859 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:50:01.039Z,1753300201.039 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T19:50:01.039Z,1753300201.039 [NAL9602] No Fault, FailCount= 5 2025-07-23T19:50:01.442Z,1753300201.442 [NAL9602](INFO): Powering up NAL9602 2025-07-23T19:50:12.332Z,1753300212.332 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:50:14.032Z,1753300214.032 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T19:45:13.8Z 2025-07-23T19:50:14.032Z,1753300214.032 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:50:14.032Z,1753300214.032 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:50:21.316Z,1753300221.316 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250723T170141/Courier0054.lzma 2025-07-23T19:50:22.320Z,1753300222.320 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0054.lzma.bak 2025-07-23T19:50:22.320Z,1753300222.320 [DataOverHttps](INFO): SBD MOMSN=25578347 2025-07-23T19:50:38.654Z,1753300238.654 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250723T170141/Express0055.lzma 2025-07-23T19:50:39.655Z,1753300239.655 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0055.lzma.bak 2025-07-23T19:50:39.655Z,1753300239.655 [DataOverHttps](INFO): SBD MOMSN=25578377 2025-07-23T19:50:41.037Z,1753300241.037 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:50:41.037Z,1753300241.037 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:50:41.037Z,1753300241.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:50:45.462Z,1753300245.462 [NAL9602](INFO): Powering down 2025-07-23T19:50:56.419Z,1753300256.419 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T19:52:03.027Z,1753300323.027 [BPC1](FAULT): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T19:52:03.028Z,1753300323.028 [BPC1](FAULT): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T19:52:03.029Z,1753300323.029 [BPC1](FAULT): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T19:52:03.030Z,1753300323.030 [BPC1](FAULT): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T19:52:03.030Z,1753300323.030 [BPC1](FAULT): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T19:52:03.031Z,1753300323.031 [BPC1](FAULT): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T19:52:03.032Z,1753300323.032 [BPC1](INFO): Calculating totals. Valid battery stick count: 29. Valid reserve battery stick count: 3. 2025-07-23T19:52:03.035Z,1753300323.035 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-07-23T19:54:46.257Z,1753300486.257 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00, :WI,-32768,-32768,-32768,-32768,V 2025-07-23T19:55:06.047Z,1753300506.047 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012823415926,35.0, +21.4, 0.0,1525 2025-07-23T19:55:16.152Z,1753300516.152 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0. +0.00, +0.00, 20.00,10536.55 2025-07-23T19:55:41.604Z,1753300541.604 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:55:41.605Z,1753300541.605 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:55:41.605Z,1753300541.605 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:55:41.606Z,1753300541.606 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:55:42.037Z,1753300542.037 [Default:CheckIn:D] Stopped 2025-07-23T19:55:42.038Z,1753300542.038 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.086523 min 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn:E] Stopped 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn] Stopped 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn](INFO): Running loop #21 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn] Running Loop=21 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:55:42.424Z,1753300542.424 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:55:43.211Z,1753300543.211 [NAL9602](INFO): Powering up 2025-07-23T19:55:54.118Z,1753300554.118 [NAL9602](INFO): NAL9602 initialized 2025-07-23T19:57:56.979Z,1753300676.979 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:58:19.737Z,1753300699.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T19:58:21.746Z,1753300701.746 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247887 2025-07-23T19:58:52.417Z,1753300732.417 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003179 2025-07-23T20:00:15.554Z,1753300815.554 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T20:00:15.554Z,1753300815.554 [NAL9602] Data Fault, FailCount= 1 2025-07-23T20:00:15.554Z,1753300815.554 [NAL9602](ERROR): Data Fault 2025-07-23T20:00:15.699Z,1753300815.699 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T20:00:15.992Z,1753300815.992 [NAL9602](INFO): Powering down 2025-07-23T20:00:16.879Z,1753300816.879 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T20:00:16.879Z,1753300816.879 [NAL9602] No Fault, FailCount= 1 2025-07-23T20:00:42.648Z,1753300842.648 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T19:55:42.4Z 2025-07-23T20:00:42.648Z,1753300842.648 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T20:00:42.665Z,1753300842.665 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T20:00:46.274Z,1753300846.274 [NAL9602](INFO): Powering up NAL9602 2025-07-23T20:00:50.052Z,1753300850.052 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250723T170141/Courier0057.lzma 2025-07-23T20:00:51.055Z,1753300851.055 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0057.lzma.bak 2025-07-23T20:00:51.055Z,1753300851.055 [DataOverHttps](INFO): SBD MOMSN=25578461 2025-07-23T20:00:57.178Z,1753300857.178 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:01:07.345Z,1753300867.345 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20250723T170141/Express0058.lzma 2025-07-23T20:01:08.347Z,1753300868.347 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0058.lzma.bak 2025-07-23T20:01:08.347Z,1753300868.347 [DataOverHttps](INFO): SBD MOMSN=25578463 2025-07-23T20:01:09.717Z,1753300869.717 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T20:01:09.717Z,1753300869.717 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T20:01:09.718Z,1753300869.718 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T20:01:28.285Z,1753300888.285 [NAL9602](INFO): Powering down 2025-07-23T20:06:10.340Z,1753301170.340 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T20:06:10.340Z,1753301170.340 [Default:CheckIn:C.Wait] Stopped 2025-07-23T20:06:10.340Z,1753301170.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T20:06:10.340Z,1753301170.340 [Default:CheckIn:D] Running Loop=1 2025-07-23T20:06:10.706Z,1753301170.706 [Default:CheckIn:D] Stopped 2025-07-23T20:06:10.706Z,1753301170.706 [Default:CheckIn:E] Running Loop=1 2025-07-23T20:06:11.120Z,1753301171.120 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 196.564323 min 2025-07-23T20:06:11.120Z,1753301171.120 [Default:CheckIn:E] Stopped 2025-07-23T20:06:11.120Z,1753301171.120 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T20:06:11.120Z,1753301171.120 [Default:CheckIn] Stopped 2025-07-23T20:06:11.120Z,1753301171.120 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T20:06:11.120Z,1753301171.120 [Default:CheckIn](INFO): Running loop #22 2025-07-23T20:06:11.121Z,1753301171.121 [Default:CheckIn] Running Loop=22 2025-07-23T20:06:11.121Z,1753301171.121 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T20:06:11.121Z,1753301171.121 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T20:06:11.922Z,1753301171.922 [NAL9602](INFO): Powering up 2025-07-23T20:06:22.815Z,1753301182.815 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:08:26.039Z,1753301306.039 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T20:11:11.316Z,1753301471.316 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T20:06:11.1Z 2025-07-23T20:11:11.316Z,1753301471.316 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T20:11:11.316Z,1753301471.316 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T20:11:18.540Z,1753301478.540 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0060.lzma 2025-07-23T20:11:19.543Z,1753301479.543 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0060.lzma.bak 2025-07-23T20:11:19.543Z,1753301479.543 [DataOverHttps](INFO): SBD MOMSN=25578663 2025-07-23T20:11:35.869Z,1753301495.869 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250723T170141/Express0061.lzma 2025-07-23T20:11:36.871Z,1753301496.871 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0061.lzma.bak 2025-07-23T20:11:36.871Z,1753301496.871 [DataOverHttps](INFO): SBD MOMSN=25578665 2025-07-23T20:11:38.384Z,1753301498.384 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T20:11:38.384Z,1753301498.384 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T20:11:38.384Z,1753301498.384 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T20:11:44.019Z,1753301504.019 [NAL9602](INFO): Powering down 2025-07-23T20:14:32.088Z,1753301672.088 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,32768,V 2025-07-23T20:16:38.961Z,1753301798.961 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T20:16:38.961Z,1753301798.961 [Default:CheckIn:C.Wait] Stopped 2025-07-23T20:16:38.961Z,1753301798.961 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T20:16:38.961Z,1753301798.961 [Default:CheckIn:D] Running Loop=1 2025-07-23T20:16:39.384Z,1753301799.384 [Default:CheckIn:D] Stopped 2025-07-23T20:16:39.384Z,1753301799.384 [Default:CheckIn:E] Running Loop=1 2025-07-23T20:16:39.770Z,1753301799.770 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 207.042301 min 2025-07-23T20:16:39.770Z,1753301799.770 [Default:CheckIn:E] Stopped 2025-07-23T20:16:39.770Z,1753301799.770 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T20:16:39.771Z,1753301799.771 [Default:CheckIn] Stopped 2025-07-23T20:16:39.771Z,1753301799.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T20:16:39.771Z,1753301799.771 [Default:CheckIn](INFO): Running loop #23 2025-07-23T20:16:39.771Z,1753301799.771 [Default:CheckIn] Running Loop=23 2025-07-23T20:16:39.771Z,1753301799.771 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T20:16:39.771Z,1753301799.771 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T20:16:40.550Z,1753301800.550 [NAL9602](INFO): Powering up 2025-07-23T20:16:51.459Z,1753301811.459 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:16:52.270Z,1753301812.270 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T20:16:52.271Z,1753301812.271 [NAL9602] Data Fault, FailCount= 1 2025-07-23T20:16:52.271Z,1753301812.271 [NAL9602](ERROR): Data Fault 2025-07-23T20:16:52.311Z,1753301812.311 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T20:16:52.670Z,1753301812.670 [NAL9602](INFO): Powering down 2025-07-23T20:16:53.499Z,1753301813.499 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T20:16:53.499Z,1753301813.499 [NAL9602] No Fault, FailCount= 1 2025-07-23T20:16:54.289Z,1753301814.289 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: -32768 Device respsone is::TS,00012900034726,35.0, +21.6,8,-32768,-32768,V 2025-07-23T20:17:22.970Z,1753301842.970 [NAL9602](INFO): Powering up NAL9602 2025-07-23T20:17:33.887Z,1753301853.887 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:19:37.104Z,1753301977.104 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T20:21:40.018Z,1753302100.018 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T20:16:39.8Z 2025-07-23T20:21:40.019Z,1753302100.019 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T20:21:40.019Z,1753302100.019 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T20:21:47.532Z,1753302107.532 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0063.lzma 2025-07-23T20:21:48.535Z,1753302108.535 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0063.lzma.bak 2025-07-23T20:21:48.535Z,1753302108.535 [DataOverHttps](INFO): SBD MOMSN=25578695 2025-07-23T20:22:07.121Z,1753302127.121 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250723T170141/Express0064.lzma 2025-07-23T20:22:08.123Z,1753302128.123 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0064.lzma.bak 2025-07-23T20:22:08.123Z,1753302128.123 [DataOverHttps](INFO): SBD MOMSN=25578700 2025-07-23T20:22:09.476Z,1753302129.476 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T20:22:09.476Z,1753302129.476 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T20:22:09.476Z,1753302129.476 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T20:22:13.090Z,1753302133.090 [NAL9602](INFO): Powering down 2025-07-23T20:27:10.076Z,1753302430.076 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T20:27:10.076Z,1753302430.076 [Default:CheckIn:C.Wait] Stopped 2025-07-23T20:27:10.076Z,1753302430.076 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T20:27:10.076Z,1753302430.076 [Default:CheckIn:D] Running Loop=1 2025-07-23T20:27:10.468Z,1753302430.468 [Default:CheckIn:D] Stopped 2025-07-23T20:27:10.469Z,1753302430.469 [Default:CheckIn:E] Running Loop=1 2025-07-23T20:27:10.857Z,1753302430.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.560384 min 2025-07-23T20:27:10.857Z,1753302430.857 [Default:CheckIn:E] Stopped 2025-07-23T20:27:10.857Z,1753302430.857 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T20:27:10.857Z,1753302430.857 [Default:CheckIn] Stopped 2025-07-23T20:27:10.857Z,1753302430.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T20:27:10.858Z,1753302430.858 [Default:CheckIn](INFO): Running loop #24 2025-07-23T20:27:10.858Z,1753302430.858 [Default:CheckIn] Running Loop=24 2025-07-23T20:27:10.858Z,1753302430.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T20:27:10.858Z,1753302430.858 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T20:27:11.658Z,1753302431.658 [NAL9602](INFO): Powering up 2025-07-23T20:27:22.579Z,1753302442.579 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:27:54.482Z,1753302474.482 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T20:27:54.561Z,1753302474.561 [NAL9602](ERROR): received: +CSQ:0 OK901, 2, 0, 0, 0 OK 2025-07-23T20:29:25.782Z,1753302565.782 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T20:29:26.603Z,1753302566.603 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T20:29:26.603Z,1753302566.603 [NAL9602] Data Fault, FailCount= 2 2025-07-23T20:29:26.603Z,1753302566.603 [NAL9602](ERROR): Data Fault 2025-07-23T20:29:26.624Z,1753302566.624 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T20:29:26.994Z,1753302566.994 [NAL9602](INFO): Powering down 2025-07-23T20:29:27.823Z,1753302567.823 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T20:29:27.823Z,1753302567.823 [NAL9602] No Fault, FailCount= 2 2025-07-23T20:29:57.306Z,1753302597.306 [NAL9602](INFO): Powering up NAL9602 2025-07-23T20:30:08.234Z,1753302608.234 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:32:11.111Z,1753302731.111 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T20:27:10.9Z 2025-07-23T20:32:11.111Z,1753302731.111 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T20:32:11.111Z,1753302731.111 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T20:32:20.408Z,1753302740.408 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0066.lzma 2025-07-23T20:32:21.411Z,1753302741.411 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0066.lzma.bak 2025-07-23T20:32:21.411Z,1753302741.411 [DataOverHttps](INFO): SBD MOMSN=25578745 2025-07-23T20:32:41.417Z,1753302761.417 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250723T170141/Express0067.lzma 2025-07-23T20:32:42.198Z,1753302762.198 [NAL9602](INFO): Powering down 2025-07-23T20:32:42.419Z,1753302762.419 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0067.lzma.bak 2025-07-23T20:32:42.419Z,1753302762.419 [DataOverHttps](INFO): SBD MOMSN=25578755 2025-07-23T20:32:43.822Z,1753302763.822 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T20:32:43.822Z,1753302763.822 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T20:32:43.822Z,1753302763.822 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T20:34:57.993Z,1753302897.993 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3278,V 2025-07-23T20:37:44.497Z,1753303064.497 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T20:37:44.497Z,1753303064.497 [Default:CheckIn:C.Wait] Stopped 2025-07-23T20:37:44.497Z,1753303064.497 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T20:37:44.497Z,1753303064.497 [Default:CheckIn:D] Running Loop=1 2025-07-23T20:37:44.894Z,1753303064.894 [Default:CheckIn:D] Stopped 2025-07-23T20:37:44.894Z,1753303064.894 [Default:CheckIn:E] Running Loop=1 2025-07-23T20:37:45.329Z,1753303065.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.134131 min 2025-07-23T20:37:45.329Z,1753303065.329 [Default:CheckIn:E] Stopped 2025-07-23T20:37:45.329Z,1753303065.329 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T20:37:45.329Z,1753303065.329 [Default:CheckIn] Stopped 2025-07-23T20:37:45.330Z,1753303065.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T20:37:45.330Z,1753303065.330 [Default:CheckIn](INFO): Running loop #25 2025-07-23T20:37:45.330Z,1753303065.330 [Default:CheckIn] Running Loop=25 2025-07-23T20:37:45.330Z,1753303065.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T20:37:45.330Z,1753303065.330 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T20:37:46.090Z,1753303066.090 [NAL9602](INFO): Powering up 2025-07-23T20:37:56.999Z,1753303076.999 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:39:59.878Z,1753303199.878 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T20:40:09.162Z,1753303209.162 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T20:40:09.162Z,1753303209.162 [NAL9602] Data Fault, FailCount= 3 2025-07-23T20:40:09.162Z,1753303209.162 [NAL9602](ERROR): Data Fault 2025-07-23T20:40:09.203Z,1753303209.203 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T20:40:09.578Z,1753303209.578 [NAL9602](INFO): Powering down 2025-07-23T20:40:10.486Z,1753303210.486 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T20:40:10.486Z,1753303210.486 [NAL9602] No Fault, FailCount= 3 2025-07-23T20:40:39.922Z,1753303239.922 [NAL9602](INFO): Powering up NAL9602 2025-07-23T20:40:50.802Z,1753303250.802 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:42:45.610Z,1753303365.610 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T20:37:45.3Z 2025-07-23T20:42:45.610Z,1753303365.610 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T20:42:45.611Z,1753303365.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T20:42:53.536Z,1753303373.536 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0069.lzma 2025-07-23T20:42:54.539Z,1753303374.539 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0069.lzma.bak 2025-07-23T20:42:54.539Z,1753303374.539 [DataOverHttps](INFO): SBD MOMSN=25578779 2025-07-23T20:43:13.817Z,1753303393.817 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20250723T170141/Express0070.lzma 2025-07-23T20:43:14.819Z,1753303394.819 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0070.lzma.bak 2025-07-23T20:43:14.819Z,1753303394.819 [DataOverHttps](INFO): SBD MOMSN=25578787 2025-07-23T20:43:16.725Z,1753303396.725 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T20:43:16.725Z,1753303396.725 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T20:43:16.725Z,1753303396.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T20:43:17.948Z,1753303397.948 [NAL9602](INFO): Powering down 2025-07-23T20:48:17.359Z,1753303697.359 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T20:48:17.359Z,1753303697.359 [Default:CheckIn:C.Wait] Stopped 2025-07-23T20:48:17.359Z,1753303697.359 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T20:48:17.360Z,1753303697.360 [Default:CheckIn:D] Running Loop=1 2025-07-23T20:48:17.771Z,1753303697.771 [Default:CheckIn:D] Stopped 2025-07-23T20:48:17.771Z,1753303697.771 [Default:CheckIn:E] Running Loop=1 2025-07-23T20:48:18.201Z,1753303698.201 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 238.682096 min 2025-07-23T20:48:18.201Z,1753303698.201 [Default:CheckIn:E] Stopped 2025-07-23T20:48:18.201Z,1753303698.201 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T20:48:18.201Z,1753303698.201 [Default:CheckIn] Stopped 2025-07-23T20:48:18.201Z,1753303698.201 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T20:48:18.201Z,1753303698.201 [Default:CheckIn](INFO): Running loop #26 2025-07-23T20:48:18.202Z,1753303698.202 [Default:CheckIn] Running Loop=26 2025-07-23T20:48:18.202Z,1753303698.202 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T20:48:18.202Z,1753303698.202 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T20:48:18.957Z,1753303698.957 [NAL9602](INFO): Powering up 2025-07-23T20:48:29.867Z,1753303709.867 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:50:33.086Z,1753303833.086 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T20:50:51.668Z,1753303851.668 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T20:50:51.668Z,1753303851.668 [NAL9602] Data Fault, FailCount= 4 2025-07-23T20:50:51.668Z,1753303851.668 [NAL9602](ERROR): Data Fault 2025-07-23T20:50:51.689Z,1753303851.689 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T20:50:52.078Z,1753303852.078 [NAL9602](INFO): Powering down 2025-07-23T20:50:52.897Z,1753303852.897 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T20:50:52.897Z,1753303852.897 [NAL9602] No Fault, FailCount= 4 2025-07-23T20:51:22.371Z,1753303882.371 [NAL9602](INFO): Powering up NAL9602 2025-07-23T20:51:33.289Z,1753303893.289 [NAL9602](INFO): NAL9602 initialized 2025-07-23T20:53:18.340Z,1753303998.340 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T20:48:18.2Z 2025-07-23T20:53:18.340Z,1753303998.340 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T20:53:18.340Z,1753303998.340 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T20:53:26.385Z,1753304006.385 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0072.lzma 2025-07-23T20:53:27.387Z,1753304007.387 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0072.lzma.bak 2025-07-23T20:53:27.387Z,1753304007.387 [DataOverHttps](INFO): SBD MOMSN=25578818 2025-07-23T20:53:44.585Z,1753304024.585 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250723T170141/Express0073.lzma 2025-07-23T20:53:45.587Z,1753304025.587 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0073.lzma.bak 2025-07-23T20:53:45.587Z,1753304025.587 [DataOverHttps](INFO): SBD MOMSN=25578823 2025-07-23T20:53:47.025Z,1753304027.025 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T20:53:47.025Z,1753304027.025 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T20:53:47.026Z,1753304027.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T20:53:49.434Z,1753304029.434 [NAL9602](INFO): Powering down 2025-07-23T20:53:59.952Z,1753304039.952 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-07-23T20:58:47.630Z,1753304327.630 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T20:58:47.630Z,1753304327.630 [Default:CheckIn:C.Wait] Stopped 2025-07-23T20:58:47.630Z,1753304327.630 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T20:58:47.630Z,1753304327.630 [Default:CheckIn:D] Running Loop=1 2025-07-23T20:58:48.210Z,1753304328.210 [Default:CheckIn:D] Stopped 2025-07-23T20:58:48.210Z,1753304328.210 [Default:CheckIn:E] Running Loop=1 2025-07-23T20:58:48.449Z,1753304328.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 249.189404 min 2025-07-23T20:58:48.449Z,1753304328.449 [Default:CheckIn:E] Stopped 2025-07-23T20:58:48.449Z,1753304328.449 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T20:58:48.449Z,1753304328.449 [Default:CheckIn] Stopped 2025-07-23T20:58:48.450Z,1753304328.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T20:58:48.450Z,1753304328.450 [Default:CheckIn](INFO): Running loop #27 2025-07-23T20:58:48.450Z,1753304328.450 [Default:CheckIn] Running Loop=27 2025-07-23T20:58:48.450Z,1753304328.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T20:58:48.450Z,1753304328.450 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T20:58:49.230Z,1753304329.230 [NAL9602](INFO): Powering up 2025-07-23T20:59:00.123Z,1753304340.123 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:01:03.350Z,1753304463.350 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T21:01:35.270Z,1753304495.270 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T21:01:35.270Z,1753304495.270 [NAL9602] Data Fault, FailCount= 5 2025-07-23T21:01:35.270Z,1753304495.270 [NAL9602](ERROR): Data Fault 2025-07-23T21:01:35.339Z,1753304495.339 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T21:01:35.339Z,1753304495.339 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-07-23T21:01:35.674Z,1753304495.674 [NAL9602](INFO): Powering down 2025-07-23T21:01:35.739Z,1753304495.739 [CBIT](INFO): Critical error at 20250723T210135 2025-07-23T21:02:02.384Z,1753304522.384 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T21:03:35.305Z,1753304615.305 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T21:03:35.305Z,1753304615.305 [NAL9602] No Fault, FailCount= 5 2025-07-23T21:03:35.664Z,1753304615.664 [NAL9602](INFO): Powering up NAL9602 2025-07-23T21:03:46.578Z,1753304626.578 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:03:48.611Z,1753304628.611 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T20:58:48.4Z 2025-07-23T21:03:48.611Z,1753304628.611 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:03:48.611Z,1753304628.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:03:56.404Z,1753304636.404 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250723T170141/Courier0075.lzma 2025-07-23T21:03:57.407Z,1753304637.407 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0075.lzma.bak 2025-07-23T21:03:57.407Z,1753304637.407 [DataOverHttps](INFO): SBD MOMSN=25578853 2025-07-23T21:04:15.637Z,1753304655.637 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20250723T170141/Express0076.lzma 2025-07-23T21:04:16.639Z,1753304656.639 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0076.lzma.bak 2025-07-23T21:04:16.639Z,1753304656.639 [DataOverHttps](INFO): SBD MOMSN=25578856 2025-07-23T21:04:18.145Z,1753304658.145 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:04:18.145Z,1753304658.145 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:04:18.145Z,1753304658.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:04:19.726Z,1753304659.726 [NAL9602](INFO): Powering down 2025-07-23T21:09:18.712Z,1753304958.712 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:09:18.729Z,1753304958.729 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:09:18.729Z,1753304958.729 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:09:18.729Z,1753304958.729 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:09:19.120Z,1753304959.120 [Default:CheckIn:D] Stopped 2025-07-23T21:09:19.121Z,1753304959.121 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:09:19.511Z,1753304959.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 259.704574 min 2025-07-23T21:09:19.511Z,1753304959.511 [Default:CheckIn:E] Stopped 2025-07-23T21:09:19.511Z,1753304959.511 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:09:19.511Z,1753304959.511 [Default:CheckIn] Stopped 2025-07-23T21:09:19.512Z,1753304959.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:09:19.512Z,1753304959.512 [Default:CheckIn](INFO): Running loop #28 2025-07-23T21:09:19.512Z,1753304959.512 [Default:CheckIn] Running Loop=28 2025-07-23T21:09:19.512Z,1753304959.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:09:19.512Z,1753304959.512 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:09:20.306Z,1753304960.306 [NAL9602](INFO): Powering up 2025-07-23T21:09:31.270Z,1753304971.270 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:11:34.434Z,1753305094.434 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T21:13:48.570Z,1753305228.570 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T21:13:48.570Z,1753305228.570 [NAL9602] Data Fault, FailCount= 1 2025-07-23T21:13:48.570Z,1753305228.570 [NAL9602](ERROR): Data Fault 2025-07-23T21:13:48.590Z,1753305228.590 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T21:13:48.970Z,1753305228.970 [NAL9602](INFO): Powering down 2025-07-23T21:13:49.792Z,1753305229.792 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T21:13:49.792Z,1753305229.792 [NAL9602] No Fault, FailCount= 1 2025-07-23T21:13:58.266Z,1753305238.266 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012901000, +0.00, +0.00, 20.00,15256.25 2025-07-23T21:14:19.379Z,1753305259.379 [NAL9602](INFO): Powering up NAL9602 2025-07-23T21:14:19.708Z,1753305259.708 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T21:09:19.5Z 2025-07-23T21:14:19.708Z,1753305259.708 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:14:19.713Z,1753305259.713 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:14:20.093Z,1753305260.093 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2025-07-23T21:14:27.296Z,1753305267.296 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0078.lzma 2025-07-23T21:14:28.299Z,1753305268.299 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0078.lzma.bak 2025-07-23T21:14:28.299Z,1753305268.299 [DataOverHttps](INFO): SBD MOMSN=25578886 2025-07-23T21:14:30.582Z,1753305270.582 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:14:39.969Z,1753305279.969 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2025-07-23T21:14:39.969Z,1753305279.969 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-07-23T21:14:39.969Z,1753305279.969 [RDI_Pathfinder](ERROR): Communications Fault 2025-07-23T21:14:40.061Z,1753305280.061 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-07-23T21:14:40.354Z,1753305280.354 [RDI_Pathfinder](INFO): Powering down 2025-07-23T21:14:41.134Z,1753305281.134 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-07-23T21:14:41.134Z,1753305281.134 [RDI_Pathfinder] No Fault, FailCount= 1 2025-07-23T21:14:41.518Z,1753305281.518 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-07-23T21:14:46.049Z,1753305286.049 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20250723T170141/Express0079.lzma 2025-07-23T21:14:47.053Z,1753305287.053 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0079.lzma.bak 2025-07-23T21:14:47.053Z,1753305287.053 [DataOverHttps](INFO): SBD MOMSN=25578888 2025-07-23T21:14:48.408Z,1753305288.408 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:14:48.421Z,1753305288.421 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:14:48.421Z,1753305288.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:15:01.715Z,1753305301.715 [NAL9602](INFO): Powering down 2025-07-23T21:19:48.977Z,1753305588.977 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:19:48.977Z,1753305588.977 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:19:48.977Z,1753305588.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:19:48.977Z,1753305588.977 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:19:49.383Z,1753305589.383 [Default:CheckIn:D] Stopped 2025-07-23T21:19:49.383Z,1753305589.383 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:19:49.813Z,1753305589.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 270.208952 min 2025-07-23T21:19:49.813Z,1753305589.813 [Default:CheckIn:E] Stopped 2025-07-23T21:19:49.813Z,1753305589.813 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:19:49.813Z,1753305589.813 [Default:CheckIn] Stopped 2025-07-23T21:19:49.813Z,1753305589.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:19:49.814Z,1753305589.814 [Default:CheckIn](INFO): Running loop #29 2025-07-23T21:19:49.814Z,1753305589.814 [Default:CheckIn] Running Loop=29 2025-07-23T21:19:49.814Z,1753305589.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:19:49.814Z,1753305589.814 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:19:50.582Z,1753305590.582 [NAL9602](INFO): Powering up 2025-07-23T21:20:01.499Z,1753305601.499 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:22:04.718Z,1753305724.718 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T21:23:04.105Z,1753305784.105 [BPC1](ERROR): Battery stick #5 (s/n: 00C2) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T21:23:04.106Z,1753305784.106 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T21:23:04.107Z,1753305784.107 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T21:23:04.107Z,1753305784.107 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T21:23:04.108Z,1753305784.108 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T21:23:04.109Z,1753305784.109 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T21:23:04.110Z,1753305784.110 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T21:23:04.110Z,1753305784.110 [BPC1](INFO): Calculating totals. Valid battery stick count: 30. Valid reserve battery stick count: 3. 2025-07-23T21:23:04.126Z,1753305784.126 [BPC1](FAULT): Failed to receive data from 6 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 17, 18, 26, 27, 44, 58. 2025-07-23T21:24:49.991Z,1753305889.991 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T21:19:49.8Z 2025-07-23T21:24:49.991Z,1753305889.991 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:24:49.992Z,1753305889.992 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:24:57.640Z,1753305897.640 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250723T170141/Courier0081.lzma 2025-07-23T21:24:58.643Z,1753305898.643 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0081.lzma.bak 2025-07-23T21:24:58.643Z,1753305898.643 [DataOverHttps](INFO): SBD MOMSN=25578918 2025-07-23T21:25:14.726Z,1753305914.726 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20250723T170141/Express0082.lzma 2025-07-23T21:25:15.731Z,1753305915.731 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0082.lzma.bak 2025-07-23T21:25:15.731Z,1753305915.731 [DataOverHttps](INFO): SBD MOMSN=25578920 2025-07-23T21:25:17.073Z,1753305917.073 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:25:17.073Z,1753305917.073 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:25:17.074Z,1753305917.074 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:25:20.682Z,1753305920.682 [NAL9602](INFO): Powering down 2025-07-23T21:30:10.658Z,1753306210.658 [DataOverHttps](IMPORTANT): SBD MTMSN=20250723T213009 2025-07-23T21:30:17.646Z,1753306217.646 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:30:17.646Z,1753306217.646 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:30:17.646Z,1753306217.646 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:30:17.647Z,1753306217.647 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:30:18.073Z,1753306218.073 [Default:CheckIn:D] Stopped 2025-07-23T21:30:18.073Z,1753306218.073 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:30:18.176Z,1753306218.176 [DataOverHttps](INFO): Received command: ibit 2025-07-23T21:30:18.274Z,1753306218.274 [CommandExec](IMPORTANT): got command ibit 2025-07-23T21:30:18.448Z,1753306218.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 280.687109 min 2025-07-23T21:30:18.448Z,1753306218.448 [Default:CheckIn:E] Stopped 2025-07-23T21:30:18.448Z,1753306218.448 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:30:18.449Z,1753306218.449 [Default:CheckIn] Stopped 2025-07-23T21:30:18.449Z,1753306218.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:30:18.449Z,1753306218.449 [Default:CheckIn](INFO): Running loop #30 2025-07-23T21:30:18.449Z,1753306218.449 [Default:CheckIn] Running Loop=30 2025-07-23T21:30:18.449Z,1753306218.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:30:18.449Z,1753306218.449 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:30:18.470Z,1753306218.470 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-23T21:30:18.470Z,1753306218.470 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-23T21:30:18.474Z,1753306218.474 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T21:30:18.830Z,1753306218.830 [NAL9602](INFO): Powering up 2025-07-23T21:30:23.105Z,1753306223.105 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002729 CHAN A1 (24V): 0.001181 CHAN A2 (12V): 0.001502 CHAN A3 (5V): 0.000701 CHAN B0 (3.3V): 0.000560 CHAN B1 (3.15aV): 0.000615 CHAN B2 (3.15bV): 0.001054 CHAN B3 (GND): -0.000012 OPEN: 0.005209 Full Scale: +/- 1 mA 2025-07-23T21:30:23.803Z,1753306223.803 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for altitude. Device response is::BD, -14.91, -8.30, +6.41, 2025-07-23T21:30:29.783Z,1753306229.783 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:30:30.586Z,1753306230.586 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-23T21:30:30.586Z,1753306230.586 [NAL9602] Data Fault, FailCount= 1 2025-07-23T21:30:30.586Z,1753306230.586 [NAL9602](ERROR): Data Fault 2025-07-23T21:30:30.734Z,1753306230.734 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-23T21:30:30.987Z,1753306230.987 [NAL9602](INFO): Powering down 2025-07-23T21:30:31.841Z,1753306231.841 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-23T21:30:31.841Z,1753306231.841 [NAL9602] No Fault, FailCount= 1 2025-07-23T21:31:01.287Z,1753306261.287 [NAL9602](INFO): Powering up NAL9602 2025-07-23T21:31:12.194Z,1753306272.194 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:31:25.947Z,1753306285.947 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS1180, +22.4, 0.0,1528.0, 0 2025-07-23T21:31:34.424Z,1753306294.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=12901, MT Status=2, MTMSN=0 2025-07-23T21:31:34.424Z,1753306294.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T21:32:09.608Z,1753306329.608 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2025-07-23T21:32:10.033Z,1753306330.033 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 14.708000 2025-07-23T21:32:10.034Z,1753306330.034 [IBIT](IMPORTANT): batteryCapacityThreshold: 0.100000 Ah 2025-07-23T21:32:10.034Z,1753306330.034 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V 2025-07-23T21:32:10.411Z,1753306330.411 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-23T21:32:10.411Z,1753306330.411 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-23T21:32:10.412Z,1753306330.412 [IBIT](IMPORTANT): Pressure:10.301258 PSI 2025-07-23T21:32:10.412Z,1753306330.412 [IBIT](IMPORTANT): Humidity:18.508120 % 2025-07-23T21:32:10.822Z,1753306330.822 [IBIT](IMPORTANT): Vehicle Pitch:-1.022579 degrees 2025-07-23T21:32:10.823Z,1753306330.823 [IBIT](IMPORTANT): Vehicle Roll:9.480286 degrees 2025-07-23T21:32:10.823Z,1753306330.823 [IBIT](IMPORTANT): Vehicle Heading:55.230064 degrees 2025-07-23T21:32:11.229Z,1753306331.229 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-23T21:32:11.230Z,1753306331.230 [IBIT](IMPORTANT): buoyancyNeutral: 159.857101 cc 2025-07-23T21:32:11.230Z,1753306331.230 [IBIT](IMPORTANT): massDefault: 1.841843 cm 2025-07-23T21:32:11.230Z,1753306331.230 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-07-23T21:32:11.231Z,1753306331.231 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-23T21:32:11.231Z,1753306331.231 [IBIT](IMPORTANT): IBIT FAILED 2025-07-23T21:33:15.040Z,1753306395.040 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T21:33:21.903Z,1753306401.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213318.00,A,3648.17260,N,12147.28338,W,0.078,0.00,230725,,,A*70 2025-07-23T21:33:21.915Z,1753306401.915 [NAL9602](INFO): GPS fix at 20250723T213318: (36.802877, -121.788056) 2025-07-23T21:33:21.946Z,1753306401.946 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:33:21.947Z,1753306401.947 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:33:29.937Z,1753306409.937 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T170141/Courier0084.lzma 2025-07-23T21:33:30.939Z,1753306410.939 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0084.lzma.bak 2025-07-23T21:33:30.939Z,1753306410.939 [DataOverHttps](INFO): SBD MOMSN=25578950 2025-07-23T21:33:47.627Z,1753306427.627 [DataOverHttps](INFO): Sending 886 bytes from file Logs/20250723T170141/Express0085.lzma 2025-07-23T21:33:48.627Z,1753306428.627 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0085.lzma.bak 2025-07-23T21:33:48.627Z,1753306428.627 [DataOverHttps](INFO): SBD MOMSN=25578957 2025-07-23T21:33:54.222Z,1753306434.222 [NAL9602](INFO): Powering down 2025-07-23T21:34:07.088Z,1753306447.088 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250723T170141/Express0088.lzma 2025-07-23T21:34:08.091Z,1753306448.091 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0088.lzma.bak 2025-07-23T21:34:08.091Z,1753306448.091 [DataOverHttps](INFO): SBD MOMSN=25578985 2025-07-23T21:34:09.630Z,1753306449.630 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:34:09.630Z,1753306449.630 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:34:09.630Z,1753306449.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:36:04.682Z,1753306564.682 [DataOverHttps](IMPORTANT): SBD MTMSN=20250723T213603 2025-07-23T21:36:12.316Z,1753306572.316 [DataOverHttps](INFO): Received command: ibit 2025-07-23T21:36:12.327Z,1753306572.327 [CommandExec](IMPORTANT): got command ibit 2025-07-23T21:36:12.449Z,1753306572.449 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-23T21:36:12.449Z,1753306572.449 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-23T21:36:12.452Z,1753306572.452 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T21:36:12.834Z,1753306572.834 [NAL9602](INFO): Powering up 2025-07-23T21:36:17.110Z,1753306577.110 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003250 CHAN A1 (24V): 0.001290 CHAN A2 (12V): 0.000956 CHAN A3 (5V): 0.000090 CHAN B0 (3.3V): 0.000913 CHAN B1 (3.15aV): 0.000622 CHAN B2 (3.15bV): 0.000904 CHAN B3 (GND): 0.000014 OPEN: 0.005524 Full Scale: +/- 1 mA 2025-07-23T21:36:23.776Z,1753306583.776 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:36:55.687Z,1753306615.687 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T21:36:55.761Z,1753306615.761 [NAL9602](ERROR): received: +CSQ:0 OK901, 2, 0, 0, 0 OK 2025-07-23T21:37:09.823Z,1753306629.823 [NAL9602](INFO): SBD MO Status=0, MOMSN=12901, MT Status=0, MTMSN=0 2025-07-23T21:37:09.824Z,1753306629.824 [NAL9602](INFO): No messages in MT queue 2025-07-23T21:37:11.059Z,1753306631.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213707.00,A,3648.17679,N,12147.28093,W,0.078,0.00,230725,,,A*74 2025-07-23T21:37:11.070Z,1753306631.070 [NAL9602](INFO): GPS fix at 20250723T213707: (36.802946, -121.788016) 2025-07-23T21:37:11.095Z,1753306631.095 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 4 Latitude: 36.802948 Longitude: -121.788017 2025-07-23T21:37:11.503Z,1753306631.503 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 109.320999 Voltage: 14.708000 2025-07-23T21:37:11.504Z,1753306631.504 [IBIT](IMPORTANT): batteryCapacityThreshold: 0.100000 Ah 2025-07-23T21:37:11.504Z,1753306631.504 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V 2025-07-23T21:37:11.910Z,1753306631.910 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-23T21:37:11.910Z,1753306631.910 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-23T21:37:11.910Z,1753306631.910 [IBIT](IMPORTANT): Pressure:10.220723 PSI 2025-07-23T21:37:11.910Z,1753306631.910 [IBIT](IMPORTANT): Humidity:18.422659 % 2025-07-23T21:37:12.320Z,1753306632.320 [IBIT](IMPORTANT): Vehicle Pitch:-1.053790 degrees 2025-07-23T21:37:12.325Z,1753306632.325 [IBIT](IMPORTANT): Vehicle Roll:9.457441 degrees 2025-07-23T21:37:12.325Z,1753306632.325 [IBIT](IMPORTANT): Vehicle Heading:55.234486 degrees 2025-07-23T21:37:12.688Z,1753306632.688 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-23T21:37:12.688Z,1753306632.688 [IBIT](IMPORTANT): buoyancyNeutral: 159.857101 cc 2025-07-23T21:37:12.689Z,1753306632.689 [IBIT](IMPORTANT): massDefault: 1.841843 cm 2025-07-23T21:37:12.689Z,1753306632.689 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-07-23T21:37:12.690Z,1753306632.690 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-23T21:37:12.690Z,1753306632.690 [IBIT](IMPORTANT): IBIT PASSED 2025-07-23T21:37:43.356Z,1753306663.356 [NAL9602](INFO): Powering down 2025-07-23T21:39:10.226Z,1753306750.226 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:39:10.226Z,1753306750.226 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:39:10.226Z,1753306750.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:39:10.226Z,1753306750.226 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:39:10.632Z,1753306750.632 [Default:CheckIn:D] Stopped 2025-07-23T21:39:10.632Z,1753306750.632 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 289.563118 min 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn:E] Stopped 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn] Stopped 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn](INFO): Running loop #31 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn] Running Loop=31 2025-07-23T21:39:11.046Z,1753306751.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:39:11.047Z,1753306751.047 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:39:11.838Z,1753306751.838 [NAL9602](INFO): Powering up 2025-07-23T21:39:22.746Z,1753306762.746 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:39:54.674Z,1753306794.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=12902, MT Status=2, MTMSN=0 2025-07-23T21:39:54.674Z,1753306794.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T21:40:04.351Z,1753306804.351 [NAL9602](INFO): SBD MO Status=0, MOMSN=12902, MT Status=0, MTMSN=0 2025-07-23T21:40:04.351Z,1753306804.351 [NAL9602](INFO): No messages in MT queue 2025-07-23T21:40:04.351Z,1753306804.351 [NAL9602](INFO): Failure count cleared after critical for NAL9602 2025-07-23T21:40:05.582Z,1753306805.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214001.00,A,3648.17365,N,12147.28649,W,0.019,0.00,230725,,,A*7C 2025-07-23T21:40:05.584Z,1753306805.584 [NAL9602](INFO): GPS fix at 20250723T214001: (36.802894, -121.788108) 2025-07-23T21:40:05.595Z,1753306805.595 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:40:05.596Z,1753306805.596 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:40:13.600Z,1753306813.600 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T170141/Courier0090.lzma 2025-07-23T21:40:14.603Z,1753306814.603 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0090.lzma.bak 2025-07-23T21:40:14.603Z,1753306814.603 [DataOverHttps](INFO): SBD MOMSN=25578995 2025-07-23T21:40:31.072Z,1753306831.072 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250723T170141/Courier0093.lzma 2025-07-23T21:40:32.075Z,1753306832.075 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0093.lzma.bak 2025-07-23T21:40:32.075Z,1753306832.075 [DataOverHttps](INFO): SBD MOMSN=25578998 2025-07-23T21:40:38.290Z,1753306838.290 [NAL9602](INFO): Powering down 2025-07-23T21:40:48.642Z,1753306848.642 [DataOverHttps](INFO): Sending 824 bytes from file Logs/20250723T170141/Express0091.lzma 2025-07-23T21:40:49.651Z,1753306849.651 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0091.lzma.bak 2025-07-23T21:40:49.651Z,1753306849.651 [DataOverHttps](INFO): SBD MOMSN=25579000 2025-07-23T21:41:06.464Z,1753306866.464 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250723T170141/Express0094.lzma 2025-07-23T21:41:07.467Z,1753306867.467 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0094.lzma.bak 2025-07-23T21:41:07.467Z,1753306867.467 [DataOverHttps](INFO): SBD MOMSN=25579028 2025-07-23T21:41:09.014Z,1753306869.014 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:41:09.014Z,1753306869.014 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:41:09.014Z,1753306869.014 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:43:21.502Z,1753307001.502 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T21:46:09.584Z,1753307169.584 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:46:09.584Z,1753307169.584 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:46:09.584Z,1753307169.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:46:09.584Z,1753307169.584 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:46:10.014Z,1753307170.014 [Default:CheckIn:D] Stopped 2025-07-23T21:46:10.014Z,1753307170.014 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 296.552799 min 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn:E] Stopped 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn] Stopped 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn](INFO): Running loop #32 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn] Running Loop=32 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:46:10.400Z,1753307170.400 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:46:11.183Z,1753307171.183 [NAL9602](INFO): Powering up 2025-07-23T21:46:22.094Z,1753307182.094 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:46:24.185Z,1753307184.185 [CommandExec](IMPORTANT): got command report mod NAL9602.sigQuality 2025-07-23T21:46:24.541Z,1753307184.541 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T21:46:33.077Z,1753307193.077 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-07-23T21:46:36.235Z,1753307196.235 [NAL9602](INFO): SBD MO Status=0, MOMSN=12903, MT Status=0, MTMSN=0 2025-07-23T21:46:36.235Z,1753307196.235 [NAL9602](INFO): No messages in MT queue 2025-07-23T21:47:03.741Z,1753307223.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214700.00,A,3648.17333,N,12147.28374,W,0.039,0.00,230725,,,A*70 2025-07-23T21:47:03.749Z,1753307223.749 [NAL9602](INFO): GPS fix at 20250723T214700: (36.802889, -121.788062) 2025-07-23T21:47:03.790Z,1753307223.790 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:47:03.790Z,1753307223.790 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:47:07.826Z,1753307227.826 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD0.00, +0.00, +0.00, 0.00,1927.92 2025-07-23T21:47:11.393Z,1753307231.393 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250723T170141/Courier0096.lzma 2025-07-23T21:47:12.395Z,1753307232.395 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Courier0096.lzma.bak 2025-07-23T21:47:12.395Z,1753307232.395 [DataOverHttps](INFO): SBD MOMSN=25579048 2025-07-23T21:47:30.381Z,1753307250.381 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2025-07-23T21:47:31.597Z,1753307251.597 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20250723T170141/Express0097.lzma 2025-07-23T21:47:32.599Z,1753307252.599 [DataOverHttps](INFO): Moved sent file to Logs/20250723T170141/Express0097.lzma.bak 2025-07-23T21:47:32.599Z,1753307252.599 [DataOverHttps](INFO): SBD MOMSN=25579051 2025-07-23T21:47:34.058Z,1753307254.058 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:47:34.058Z,1753307254.058 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:47:34.058Z,1753307254.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:47:36.430Z,1753307256.430 [NAL9602](INFO): Powering down 2025-07-23T21:47:36.507Z,1753307256.507 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T21:48:53.126Z,1753307333.126 [DataOverHttps](IMPORTANT): SBD MTMSN=20250723T214852 2025-07-23T21:49:00.592Z,1753307340.592 [DataOverHttps](INFO): Received command: ! echo 0 > /dev/loadB6 2025-07-23T21:49:00.650Z,1753307340.650 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadB6 2025-07-23T21:49:59.829Z,1753307399.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:50:01.837Z,1753307401.837 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.242828 2025-07-23T21:50:22.090Z,1753307422.090 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2025-07-23T21:50:34.973Z,1753307434.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:51:10.113Z,1753307470.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:51:45.257Z,1753307505.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:52:20.409Z,1753307540.409 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:52:34.617Z,1753307554.617 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:52:34.617Z,1753307554.617 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:52:34.617Z,1753307554.617 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:52:34.618Z,1753307554.618 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:52:35.077Z,1753307555.077 [Default:CheckIn:D] Stopped 2025-07-23T21:52:35.078Z,1753307555.078 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:52:35.447Z,1753307555.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 302.970540 min 2025-07-23T21:52:35.447Z,1753307555.447 [Default:CheckIn:E] Stopped 2025-07-23T21:52:35.447Z,1753307555.447 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:52:35.448Z,1753307555.448 [Default:CheckIn] Stopped 2025-07-23T21:52:35.448Z,1753307555.448 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:52:35.448Z,1753307555.448 [Default:CheckIn](INFO): Running loop #33 2025-07-23T21:52:35.448Z,1753307555.448 [Default:CheckIn] Running Loop=33 2025-07-23T21:52:35.448Z,1753307555.448 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:52:35.448Z,1753307555.448 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:52:36.229Z,1753307556.229 [NAL9602](INFO): Powering up 2025-07-23T21:52:47.126Z,1753307567.126 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:52:55.549Z,1753307575.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:52:58.107Z,1753307578.107 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T21:53:02.534Z,1753307582.534 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-07-23T21:53:02.931Z,1753307582.931 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T21:53:08.588Z,1753307588.588 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T21:53:22.684Z,1753307602.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=12904, MT Status=2, MTMSN=0 2025-07-23T21:53:22.684Z,1753307602.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T21:53:26.811Z,1753307606.811 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-07-23T21:53:30.689Z,1753307610.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:53:41.263Z,1753307621.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=12904, MT Status=2, MTMSN=0 2025-07-23T21:53:41.263Z,1753307621.263 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T21:53:45.789Z,1753307625.789 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T21:53:48.984Z,1753307628.984 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T21:53:57.467Z,1753307637.467 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T21:54:04.696Z,1753307644.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=12904, MT Status=0, MTMSN=0 2025-07-23T21:54:04.696Z,1753307644.696 [NAL9602](INFO): No messages in MT queue 2025-07-23T21:54:05.829Z,1753307645.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:54:05.902Z,1753307645.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215405.00,A,3648.17418,N,12147.28193,W,0.039,0.00,230725,,,A*72 2025-07-23T21:54:05.904Z,1753307645.904 [NAL9602](INFO): GPS fix at 20250723T215405: (36.802903, -121.788032) 2025-07-23T21:54:05.940Z,1753307645.940 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T21:54:05.941Z,1753307645.941 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T21:54:09.172Z,1753307649.172 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-07-23T21:54:20.927Z,1753307660.927 [NAL9602](INFO): SBD MO Status=1, MOMSN=12905, MT Status=0, MTMSN=0 2025-07-23T21:54:20.977Z,1753307660.977 [NAL9602](INFO): Sent 73 bytes from file Logs/20250723T170141/Courier0099.lzma 2025-07-23T21:54:20.977Z,1753307660.977 [NAL9602](INFO): Packets left to send: 0 2025-07-23T21:54:31.100Z,1753307671.100 [NAL9602](INFO): SBD MO Status=1, MOMSN=12906, MT Status=0, MTMSN=0 2025-07-23T21:54:31.157Z,1753307671.157 [NAL9602](INFO): Sent 260 bytes from file Logs/20250723T170141/Express0100.lzma 2025-07-23T21:54:31.157Z,1753307671.157 [NAL9602](INFO): Packets left to send: 0 2025-07-23T21:54:40.969Z,1753307680.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:54:41.200Z,1753307681.200 [NAL9602](INFO): SBD MO Status=0, MOMSN=12907, MT Status=0, MTMSN=0 2025-07-23T21:54:41.282Z,1753307681.282 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T21:54:41.282Z,1753307681.282 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T21:54:41.282Z,1753307681.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T21:55:11.895Z,1753307711.895 [NAL9602](INFO): Powering down 2025-07-23T21:55:12.006Z,1753307712.006 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T21:55:16.109Z,1753307716.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:55:51.249Z,1753307751.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:56:26.389Z,1753307786.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:57:01.529Z,1753307821.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:57:36.669Z,1753307856.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:58:11.809Z,1753307891.809 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:58:46.961Z,1753307926.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:59:22.109Z,1753307962.109 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T21:59:41.820Z,1753307981.820 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T21:59:41.820Z,1753307981.820 [Default:CheckIn:C.Wait] Stopped 2025-07-23T21:59:41.820Z,1753307981.820 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T21:59:41.820Z,1753307981.820 [Default:CheckIn:D] Running Loop=1 2025-07-23T21:59:42.201Z,1753307982.201 [Default:CheckIn:D] Stopped 2025-07-23T21:59:42.201Z,1753307982.201 [Default:CheckIn:E] Running Loop=1 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 310.089258 min 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn:E] Stopped 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn] Stopped 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn](INFO): Running loop #34 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn] Running Loop=34 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T21:59:42.592Z,1753307982.592 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T21:59:43.383Z,1753307983.383 [NAL9602](INFO): Powering up 2025-07-23T21:59:54.291Z,1753307994.291 [NAL9602](INFO): NAL9602 initialized 2025-07-23T21:59:57.257Z,1753307997.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:00:05.652Z,1753308005.652 [Reporter](INFO): NAL9602.sigQuality 5 count 2025-07-23T22:00:11.279Z,1753308011.279 [NAL9602](INFO): SBD MO Status=0, MOMSN=12908, MT Status=0, MTMSN=0 2025-07-23T22:00:11.280Z,1753308011.280 [NAL9602](INFO): No messages in MT queue 2025-07-23T22:00:21.012Z,1753308021.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220017.00,A,3648.17191,N,12147.28841,W,0.097,0.00,230725,,,A*75 2025-07-23T22:00:21.015Z,1753308021.015 [NAL9602](INFO): GPS fix at 20250723T220017: (36.802865, -121.788140) 2025-07-23T22:00:21.026Z,1753308021.026 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T22:00:21.026Z,1753308021.026 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T22:00:31.508Z,1753308031.508 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T22:00:32.397Z,1753308032.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:00:44.044Z,1753308044.044 [NAL9602](INFO): SBD MO Status=2, MOMSN=12909, MT Status=2, MTMSN=0 2025-07-23T22:00:44.044Z,1753308044.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:00:48.537Z,1753308048.537 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T22:01:07.537Z,1753308067.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:01:07.550Z,1753308067.550 [NAL9602](INFO): SBD MO Status=2, MOMSN=12909, MT Status=2, MTMSN=0 2025-07-23T22:01:07.550Z,1753308067.550 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:01:12.064Z,1753308072.064 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T22:01:22.151Z,1753308082.151 [NAL9602](INFO): SBD MO Status=2, MOMSN=12909, MT Status=2, MTMSN=0 2025-07-23T22:01:22.151Z,1753308082.151 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:01:26.228Z,1753308086.228 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T22:01:42.681Z,1753308102.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:01:46.465Z,1753308106.465 [NAL9602](INFO): SBD MO Status=2, MOMSN=12909, MT Status=2, MTMSN=0 2025-07-23T22:01:46.465Z,1753308106.465 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:01:50.963Z,1753308110.963 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:01:59.854Z,1753308119.854 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:02:04.280Z,1753308124.280 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:02:08.734Z,1753308128.734 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:02:12.771Z,1753308132.771 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:02:17.208Z,1753308137.208 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-07-23T22:02:17.821Z,1753308137.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:02:37.056Z,1753308157.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=12909, MT Status=2, MTMSN=0 2025-07-23T22:02:37.057Z,1753308157.057 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:02:41.132Z,1753308161.132 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:02:43.144Z,1753308163.144 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T22:02:52.973Z,1753308172.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:02:53.711Z,1753308173.711 [NAL9602](INFO): SBD MO Status=1, MOMSN=12909, MT Status=0, MTMSN=0 2025-07-23T22:02:53.775Z,1753308173.775 [NAL9602](INFO): Sent 73 bytes from file Logs/20250723T170141/Courier0102.lzma 2025-07-23T22:02:53.775Z,1753308173.775 [NAL9602](INFO): Packets left to send: 0 2025-07-23T22:03:02.651Z,1753308182.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=12910, MT Status=0, MTMSN=0 2025-07-23T22:03:02.721Z,1753308182.721 [NAL9602](INFO): Sent 153 bytes from file Logs/20250723T170141/Express0103.lzma 2025-07-23T22:03:02.721Z,1753308182.721 [NAL9602](INFO): Packets left to send: 0 2025-07-23T22:03:10.323Z,1753308190.323 [NAL9602](INFO): SBD MO Status=0, MOMSN=12911, MT Status=0, MTMSN=0 2025-07-23T22:03:10.425Z,1753308190.425 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T22:03:10.426Z,1753308190.426 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T22:03:10.426Z,1753308190.426 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T22:03:28.113Z,1753308208.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:03:41.022Z,1753308221.022 [NAL9602](INFO): Powering down 2025-07-23T22:03:41.046Z,1753308221.046 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:04:03.253Z,1753308243.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:04:05.782Z,1753308245.782 [BPC1](ERROR): Battery stick #5 (s/n: 00C2) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T22:04:05.783Z,1753308245.783 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T22:04:05.783Z,1753308245.783 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T22:04:05.784Z,1753308245.784 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T22:04:05.821Z,1753308245.821 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T22:04:05.822Z,1753308245.822 [BPC1](ERROR): Battery stick #37 (s/n: 00A3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T22:04:05.823Z,1753308245.823 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-23T22:04:05.823Z,1753308245.823 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-07-23T22:04:05.824Z,1753308245.824 [BPC1](INFO): Calculating totals. Valid battery stick count: 29. Valid reserve battery stick count: 3. 2025-07-23T22:04:05.830Z,1753308245.830 [BPC1](FAULT): Failed to receive data from 26 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 6, 7, 8, 9, 10, 11, 12, 13, 15, 16, 17, 18, 26, 27, 32, 33, 34, 35, 38, 39, 44, 58. 2025-07-23T22:04:11.787Z,1753308251.787 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for water velocity. Device response is::WS,-32768,-32768,-3276851.81 2025-07-23T22:04:38.395Z,1753308278.395 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:05:13.533Z,1753308313.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:05:48.677Z,1753308348.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:06:23.833Z,1753308383.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:06:58.977Z,1753308418.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:07:34.117Z,1753308454.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:08:09.257Z,1753308489.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:08:10.914Z,1753308490.914 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T22:08:10.914Z,1753308490.914 [Default:CheckIn:C.Wait] Stopped 2025-07-23T22:08:10.914Z,1753308490.914 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T22:08:10.915Z,1753308490.915 [Default:CheckIn:D] Running Loop=1 2025-07-23T22:08:11.309Z,1753308491.309 [Default:CheckIn:D] Stopped 2025-07-23T22:08:11.309Z,1753308491.309 [Default:CheckIn:E] Running Loop=1 2025-07-23T22:08:11.750Z,1753308491.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 318.574382 min 2025-07-23T22:08:11.751Z,1753308491.751 [Default:CheckIn:E] Stopped 2025-07-23T22:08:11.751Z,1753308491.751 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T22:08:11.751Z,1753308491.751 [Default:CheckIn] Stopped 2025-07-23T22:08:11.752Z,1753308491.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T22:08:11.752Z,1753308491.752 [Default:CheckIn](INFO): Running loop #35 2025-07-23T22:08:11.752Z,1753308491.752 [Default:CheckIn] Running Loop=35 2025-07-23T22:08:11.757Z,1753308491.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T22:08:11.758Z,1753308491.758 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T22:08:12.519Z,1753308492.519 [NAL9602](INFO): Powering up 2025-07-23T22:08:23.421Z,1753308503.421 [NAL9602](INFO): NAL9602 initialized 2025-07-23T22:08:33.981Z,1753308513.981 [Reporter](INFO): NAL9602.sigQuality 4 count 2025-07-23T22:08:40.388Z,1753308520.388 [NAL9602](INFO): SBD MO Status=0, MOMSN=12912, MT Status=0, MTMSN=0 2025-07-23T22:08:40.388Z,1753308520.388 [NAL9602](INFO): No messages in MT queue 2025-07-23T22:08:44.401Z,1753308524.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:08:52.911Z,1753308532.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220849.00,A,3648.17203,N,12147.28289,W,0.039,0.00,230725,,,A*74 2025-07-23T22:08:52.914Z,1753308532.914 [NAL9602](INFO): GPS fix at 20250723T220849: (36.802867, -121.788048) 2025-07-23T22:08:52.925Z,1753308532.925 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T22:08:52.925Z,1753308532.925 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T22:08:59.879Z,1753308539.879 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-07-23T22:09:06.327Z,1753308546.327 [NAL9602](INFO): SBD MO Status=1, MOMSN=12913, MT Status=0, MTMSN=0 2025-07-23T22:09:06.381Z,1753308546.381 [NAL9602](INFO): Sent 85 bytes from file Logs/20250723T170141/Courier0105.lzma 2025-07-23T22:09:06.381Z,1753308546.381 [NAL9602](INFO): Packets left to send: 0 2025-07-23T22:09:16.522Z,1753308556.522 [NAL9602](INFO): SBD MO Status=1, MOMSN=12914, MT Status=0, MTMSN=0 2025-07-23T22:09:16.581Z,1753308556.581 [NAL9602](INFO): Sent 303 bytes from file Logs/20250723T170141/Express0106.lzma 2025-07-23T22:09:16.581Z,1753308556.581 [NAL9602](INFO): Packets left to send: 0 2025-07-23T22:09:19.541Z,1753308559.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:09:34.703Z,1753308574.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=12915, MT Status=2, MTMSN=0 2025-07-23T22:09:34.703Z,1753308574.703 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:09:39.187Z,1753308579.187 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-07-23T22:09:51.677Z,1753308591.677 [NAL9602](INFO): SBD MO Status=2, MOMSN=12915, MT Status=2, MTMSN=0 2025-07-23T22:09:51.677Z,1753308591.677 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T22:09:54.681Z,1753308594.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:09:56.194Z,1753308596.194 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:09:56.702Z,1753308596.702 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-07-23T22:09:58.166Z,1753308598.166 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:09:58.726Z,1753308598.726 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-07-23T22:10:02.226Z,1753308602.226 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:10:02.787Z,1753308602.787 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-07-23T22:10:04.649Z,1753308604.649 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:10:05.187Z,1753308605.187 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-07-23T22:10:12.312Z,1753308612.312 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-07-23T22:10:15.907Z,1753308615.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=12915, MT Status=0, MTMSN=0 2025-07-23T22:10:15.999Z,1753308615.999 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T22:10:16.000Z,1753308616.000 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T22:10:16.000Z,1753308616.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T22:10:29.821Z,1753308629.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:10:43.871Z,1753308643.871 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012901573710,35.0, +23.9, 0.0,1531 2025-07-23T22:10:46.614Z,1753308646.614 [NAL9602](INFO): Powering down 2025-07-23T22:10:46.672Z,1753308646.672 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:11:04.961Z,1753308664.961 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:11:40.101Z,1753308700.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:12:15.249Z,1753308735.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:12:50.393Z,1753308770.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:13:25.533Z,1753308805.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:14:00.673Z,1753308840.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:14:35.813Z,1753308875.813 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:15:10.953Z,1753308910.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:15:16.500Z,1753308916.500 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T22:15:16.500Z,1753308916.500 [Default:CheckIn:C.Wait] Stopped 2025-07-23T22:15:16.500Z,1753308916.500 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T22:15:16.500Z,1753308916.500 [Default:CheckIn:D] Running Loop=1 2025-07-23T22:15:16.906Z,1753308916.906 [Default:CheckIn:D] Stopped 2025-07-23T22:15:16.906Z,1753308916.906 [Default:CheckIn:E] Running Loop=1 2025-07-23T22:15:17.317Z,1753308917.317 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 325.667643 min 2025-07-23T22:15:17.317Z,1753308917.317 [Default:CheckIn:E] Stopped 2025-07-23T22:15:17.317Z,1753308917.317 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T22:15:17.317Z,1753308917.317 [Default:CheckIn] Stopped 2025-07-23T22:15:17.317Z,1753308917.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T22:15:17.317Z,1753308917.317 [Default:CheckIn](INFO): Running loop #36 2025-07-23T22:15:17.318Z,1753308917.318 [Default:CheckIn] Running Loop=36 2025-07-23T22:15:17.318Z,1753308917.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T22:15:17.318Z,1753308917.318 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T22:15:18.106Z,1753308918.106 [NAL9602](INFO): Powering up 2025-07-23T22:15:29.006Z,1753308929.006 [NAL9602](INFO): NAL9602 initialized 2025-07-23T22:15:35.580Z,1753308935.580 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:40.002Z,1753308940.002 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:44.016Z,1753308944.016 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:44.804Z,1753308944.804 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:46.110Z,1753308946.110 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:15:48.851Z,1753308948.851 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:49.678Z,1753308949.678 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:53.705Z,1753308953.705 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:54.525Z,1753308954.525 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:58.964Z,1753308958.964 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:15:59.796Z,1753308959.796 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:04.225Z,1753308964.225 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:05.032Z,1753308965.032 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:09.452Z,1753308969.452 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:10.256Z,1753308970.256 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:14.711Z,1753308974.711 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:15.516Z,1753308975.516 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:19.101Z,1753308979.101 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-07-23T22:16:19.991Z,1753308979.991 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:20.767Z,1753308980.767 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:21.249Z,1753308981.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:16:25.212Z,1753308985.212 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:26.039Z,1753308986.039 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:26.391Z,1753308986.391 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T22:16:30.468Z,1753308990.468 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:31.286Z,1753308991.286 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:35.776Z,1753308995.776 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:36.506Z,1753308996.506 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:40.960Z,1753309000.960 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:41.833Z,1753309001.833 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:46.223Z,1753309006.223 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:47.031Z,1753309007.031 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:51.479Z,1753309011.479 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:52.272Z,1753309012.272 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:56.397Z,1753309016.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:16:56.726Z,1753309016.726 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:16:57.518Z,1753309017.518 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:02.049Z,1753309022.049 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:02.801Z,1753309022.801 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:07.228Z,1753309027.228 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:08.075Z,1753309028.075 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:12.472Z,1753309032.472 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:13.291Z,1753309033.291 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:17.784Z,1753309037.784 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:18.555Z,1753309038.555 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:22.983Z,1753309042.983 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:23.866Z,1753309043.866 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:28.247Z,1753309048.247 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:29.065Z,1753309049.065 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:17:31.549Z,1753309051.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:17:31.850Z,1753309051.850 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T22:18:05.385Z,1753309085.385 [CommandLine](INFO): End of History 2025-07-23T22:18:05.398Z,1753309085.398 [CommandLine](INFO): End of History 2025-07-23T22:18:06.689Z,1753309086.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:18:12.351Z,1753309092.351 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:18:41.829Z,1753309121.829 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:18:43.829Z,1753309123.829 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:19:16.985Z,1753309156.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:19:43.958Z,1753309183.958 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T22:19:52.153Z,1753309192.153 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:19:56.897Z,1753309196.897 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T22:20:16.314Z,1753309216.314 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:20:17.503Z,1753309217.503 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-23T22:15:17.3Z 2025-07-23T22:20:17.503Z,1753309217.503 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T22:20:17.503Z,1753309217.503 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T22:20:27.309Z,1753309227.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:20:47.370Z,1753309247.370 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2025-07-23T22:20:47.445Z,1753309247.445 [NAL9602](ERROR): received: +CSQ:0 OK915, 0, 0, 0, 0 OK 2025-07-23T22:20:49.038Z,1753309249.038 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:20:53.481Z,1753309253.481 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:20:55.507Z,1753309255.507 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:20:59.953Z,1753309259.953 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:01.974Z,1753309261.974 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:02.449Z,1753309262.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:21:06.404Z,1753309266.404 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:08.436Z,1753309268.436 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:12.876Z,1753309272.876 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:14.888Z,1753309274.888 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:19.340Z,1753309279.340 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:21.352Z,1753309281.352 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:25.844Z,1753309285.844 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:27.864Z,1753309287.864 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:32.277Z,1753309292.277 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:34.280Z,1753309294.280 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:37.589Z,1753309297.589 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:21:38.743Z,1753309298.743 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:40.740Z,1753309300.740 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:45.206Z,1753309305.206 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:47.204Z,1753309307.204 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:51.660Z,1753309311.660 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:53.687Z,1753309313.687 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:21:58.128Z,1753309318.128 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:00.174Z,1753309320.174 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:04.587Z,1753309324.587 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:06.616Z,1753309326.616 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:11.098Z,1753309331.098 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:12.729Z,1753309332.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:22:13.072Z,1753309333.072 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:17.547Z,1753309337.547 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:19.544Z,1753309339.544 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:23.989Z,1753309343.989 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:26.009Z,1753309346.009 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:30.435Z,1753309350.435 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:32.463Z,1753309352.463 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:36.895Z,1753309356.895 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:38.931Z,1753309358.931 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:43.374Z,1753309363.374 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:45.509Z,1753309365.509 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:47.889Z,1753309367.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:22:49.962Z,1753309369.962 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:51.987Z,1753309371.987 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:56.408Z,1753309376.408 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:22:58.439Z,1753309378.439 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:02.848Z,1753309382.848 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:04.862Z,1753309384.862 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:09.329Z,1753309389.329 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:11.394Z,1753309391.394 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:23:11.445Z,1753309391.445 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:15.826Z,1753309395.826 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:17.839Z,1753309397.839 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:22.240Z,1753309402.240 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:23.033Z,1753309403.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:23:23.085Z,1753309403.085 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:23:24.260Z,1753309404.260 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:28.732Z,1753309408.732 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:30.719Z,1753309410.719 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:34.792Z,1753309414.792 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:23:34.845Z,1753309414.845 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:36.801Z,1753309416.801 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:37.598Z,1753309417.598 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-07-23T22:23:41.244Z,1753309421.244 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:43.273Z,1753309423.273 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:46.922Z,1753309426.922 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:23:47.726Z,1753309427.726 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:49.785Z,1753309429.785 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:54.174Z,1753309434.174 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:56.198Z,1753309436.198 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:23:58.173Z,1753309438.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:23:58.625Z,1753309438.625 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:24:00.631Z,1753309440.631 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:02.664Z,1753309442.664 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:07.096Z,1753309447.096 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:09.124Z,1753309449.124 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:10.751Z,1753309450.751 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:24:13.560Z,1753309453.560 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:15.568Z,1753309455.568 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:20.033Z,1753309460.033 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:21.250Z,1753309461.250 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB6 2025-07-23T22:24:22.080Z,1753309462.080 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:22.465Z,1753309462.465 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:24:23.958Z,1753309463.958 [CommandExec](IMPORTANT): got command gfscan 2025-07-23T22:24:24.085Z,1753309464.085 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T22:24:26.478Z,1753309466.478 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:28.511Z,1753309468.511 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.029587 CHAN A1 (24V): 0.013229 CHAN A2 (12V): 0.005163 CHAN A3 (5V): 0.001600 CHAN B0 (3.3V): 0.001573 CHAN B1 (3.15aV): 0.001295 CHAN B2 (3.15bV): 0.001259 CHAN B3 (GND): -0.000360 OPEN: 0.005482 Full Scale: +/- 1 mA 2025-07-23T22:24:28.513Z,1753309468.513 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:32.940Z,1753309472.940 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:33.317Z,1753309473.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T22:24:34.626Z,1753309474.626 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:24:35.024Z,1753309475.024 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:39.447Z,1753309479.447 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:41.475Z,1753309481.475 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:45.214Z,1753309485.214 [CommandExec](IMPORTANT): got command gfscan 2025-07-23T22:24:45.490Z,1753309485.490 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T22:24:45.955Z,1753309485.955 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:46.339Z,1753309486.339 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:24:47.952Z,1753309487.952 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:49.964Z,1753309489.964 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.030419 CHAN A1 (24V): 0.012445 CHAN A2 (12V): 0.005688 CHAN A3 (5V): 0.001278 CHAN B0 (3.3V): 0.001823 CHAN B1 (3.15aV): 0.000881 CHAN B2 (3.15bV): 0.001283 CHAN B3 (GND): -0.000389 OPEN: 0.005839 Full Scale: +/- 1 mA 2025-07-23T22:24:52.384Z,1753309492.384 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:54.392Z,1753309494.392 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:24:58.492Z,1753309498.492 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-07-23T22:24:58.552Z,1753309498.552 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:25:00.465Z,1753309500.465 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:25:04.900Z,1753309504.900 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:25:06.934Z,1753309506.934 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-07-23T22:25:08.457Z,1753309508.457 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.