2025-09-25T15:11:31.072Z,1758813091.072 [DataOverHttps](INFO): Received command: restart logs 2025-09-25T15:11:31.089Z,1758813091.089 [CommandExec](IMPORTANT): got command restart logs 2025-09-25T15:13:26.264Z,1758813206.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:13:57.388Z,1758813237.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:14:15.354Z,1758813255.354 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:14:15.354Z,1758813255.354 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:14:15.354Z,1758813255.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:14:15.355Z,1758813255.355 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:14:15.764Z,1758813255.764 [Default:CheckIn:D] Stopped 2025-09-25T15:14:15.764Z,1758813255.764 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:14:16.163Z,1758813256.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.098270 min 2025-09-25T15:14:16.163Z,1758813256.163 [Default:CheckIn:E] Stopped 2025-09-25T15:14:16.184Z,1758813256.184 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:14:16.184Z,1758813256.184 [Default:CheckIn] Stopped 2025-09-25T15:14:16.184Z,1758813256.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:14:16.184Z,1758813256.184 [Default:CheckIn](INFO): Running loop #3 2025-09-25T15:14:16.184Z,1758813256.184 [Default:CheckIn] Running Loop=3 2025-09-25T15:14:16.184Z,1758813256.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:14:16.185Z,1758813256.185 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:14:18.171Z,1758813258.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151417.00,A,3647.98429,N,12150.63826,W,0.622,75.20,250925,,,D*47 2025-09-25T15:14:18.174Z,1758813258.174 [NAL9602](INFO): GPS fix at 20250925T151417: (36.799738, -121.843971) 2025-09-25T15:14:18.185Z,1758813258.185 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:14:18.185Z,1758813258.185 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:14:28.512Z,1758813268.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:14:55.764Z,1758813295.764 [NAL9602](INFO): SBD MO Status=2, MOMSN=64783, MT Status=2, MTMSN=0 2025-09-25T15:14:55.764Z,1758813295.764 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:14:59.636Z,1758813299.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:15:13.519Z,1758813313.519 [NAL9602](INFO): SBD MO Status=0, MOMSN=64783, MT Status=0, MTMSN=0 2025-09-25T15:15:13.519Z,1758813313.519 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:15:30.760Z,1758813330.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:15:33.773Z,1758813333.773 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.730698 2025-09-25T15:15:47.112Z,1758813347.112 [NAL9602](INFO): SBD MO Status=1, MOMSN=64784, MT Status=0, MTMSN=0 2025-09-25T15:15:47.172Z,1758813347.172 [NAL9602](INFO): Sent 39 bytes from file Logs/20250924T185728/Courier0129.lzma 2025-09-25T15:15:47.172Z,1758813347.172 [NAL9602](INFO): Packets left to send: 0 2025-09-25T15:15:58.095Z,1758813358.095 [NAL9602](INFO): SBD MO Status=1, MOMSN=64785, MT Status=0, MTMSN=0 2025-09-25T15:15:58.144Z,1758813358.144 [NAL9602](INFO): Sent 268 bytes from file Logs/20250925T151131/Courier0000.lzma 2025-09-25T15:15:58.144Z,1758813358.144 [NAL9602](INFO): Packets left to send: 0 2025-09-25T15:16:04.896Z,1758813364.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:16:06.219Z,1758813366.219 [NAL9602](INFO): SBD MO Status=1, MOMSN=64786, MT Status=0, MTMSN=0 2025-09-25T15:16:06.272Z,1758813366.272 [NAL9602](INFO): Sent 106 bytes from file Logs/20250924T185728/Express0130.lzma 2025-09-25T15:16:06.272Z,1758813366.272 [NAL9602](INFO): Packets left to send: 0 2025-09-25T15:16:17.185Z,1758813377.185 [NAL9602](INFO): SBD MO Status=1, MOMSN=64787, MT Status=0, MTMSN=0 2025-09-25T15:16:17.232Z,1758813377.232 [NAL9602](INFO): Sent 332 bytes from file Logs/20250925T151131/Express0001.lzma 2025-09-25T15:16:17.232Z,1758813377.232 [NAL9602](INFO): Packets left to send: 1 2025-09-25T15:16:26.127Z,1758813386.127 [NAL9602](INFO): SBD MO Status=1, MOMSN=64788, MT Status=0, MTMSN=0 2025-09-25T15:16:26.176Z,1758813386.176 [NAL9602](INFO): Sent 158 bytes from file Logs/20250925T151131/Express0001.lzma 2025-09-25T15:16:26.176Z,1758813386.176 [NAL9602](INFO): Packets left to send: 0 2025-09-25T15:16:34.663Z,1758813394.663 [NAL9602](INFO): SBD MO Status=1, MOMSN=64789, MT Status=0, MTMSN=0 2025-09-25T15:16:34.724Z,1758813394.724 [NAL9602](INFO): Sent 61 bytes from file Logs/20250925T151131/Express0004.lzma 2025-09-25T15:16:34.724Z,1758813394.724 [NAL9602](INFO): Packets left to send: 0 2025-09-25T15:16:36.020Z,1758813396.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:16:44.358Z,1758813404.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=64790, MT Status=0, MTMSN=0 2025-09-25T15:16:44.461Z,1758813404.461 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:16:44.461Z,1758813404.461 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:16:44.461Z,1758813404.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:17:07.148Z,1758813427.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:17:15.065Z,1758813435.065 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:17:38.272Z,1758813458.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:18:09.416Z,1758813489.416 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:18:40.540Z,1758813520.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:19:11.684Z,1758813551.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:19:42.824Z,1758813582.824 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:20:00.700Z,1758813600.700 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:20:06.436Z,1758813606.436 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002879 2025-09-25T15:20:14.028Z,1758813614.028 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:20:27.421Z,1758813627.421 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:20:41.156Z,1758813641.156 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:20:55.296Z,1758813655.296 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:21:08.628Z,1758813668.628 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:21:21.966Z,1758813681.966 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:21:35.294Z,1758813695.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:21:45.065Z,1758813705.065 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:21:45.065Z,1758813705.065 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:21:45.065Z,1758813705.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:21:45.066Z,1758813705.066 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:21:45.411Z,1758813705.411 [Default:CheckIn:D] Stopped 2025-09-25T15:21:45.411Z,1758813705.411 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:21:45.848Z,1758813705.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.592387 min 2025-09-25T15:21:45.848Z,1758813705.848 [Default:CheckIn:E] Stopped 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn] Stopped 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn](INFO): Running loop #4 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn] Running Loop=4 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:21:45.849Z,1758813705.849 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:21:47.822Z,1758813707.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152147.00,A,3647.94245,N,12149.37464,W,28.419,86.87,250925,,,D*76 2025-09-25T15:21:47.824Z,1758813707.824 [NAL9602](INFO): GPS fix at 20250925T152147: (36.799041, -121.822911) 2025-09-25T15:21:47.835Z,1758813707.835 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:21:47.835Z,1758813707.835 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:21:48.625Z,1758813708.625 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:22:01.956Z,1758813721.956 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:22:06.411Z,1758813726.411 [NAL9602](INFO): SBD MO Status=2, MOMSN=64791, MT Status=2, MTMSN=0 2025-09-25T15:22:06.411Z,1758813726.411 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:22:16.276Z,1758813736.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-25T15:22:16.499Z,1758813736.499 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:22:25.398Z,1758813745.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=64791, MT Status=0, MTMSN=0 2025-09-25T15:22:25.399Z,1758813745.399 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:22:32.856Z,1758813752.856 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151131/Courier0006.lzma 2025-09-25T15:22:33.858Z,1758813753.858 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0006.lzma.bak 2025-09-25T15:22:33.858Z,1758813753.858 [DataOverHttps](INFO): SBD MOMSN=26074667 2025-09-25T15:22:50.100Z,1758813770.100 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250925T151131/Express0007.lzma 2025-09-25T15:22:51.102Z,1758813771.102 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0007.lzma.bak 2025-09-25T15:22:51.102Z,1758813771.102 [DataOverHttps](INFO): SBD MOMSN=26074671 2025-09-25T15:22:52.524Z,1758813772.524 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:22:52.525Z,1758813772.525 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:22:52.525Z,1758813772.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:22:56.181Z,1758813776.181 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:27:53.116Z,1758814073.116 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:27:53.117Z,1758814073.117 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:27:53.117Z,1758814073.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:27:53.117Z,1758814073.117 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:27:53.529Z,1758814073.529 [Default:CheckIn:D] Stopped 2025-09-25T15:27:53.529Z,1758814073.529 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:27:53.944Z,1758814073.944 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.727694 min 2025-09-25T15:27:53.944Z,1758814073.944 [Default:CheckIn:E] Stopped 2025-09-25T15:27:53.944Z,1758814073.944 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:27:53.944Z,1758814073.944 [Default:CheckIn] Stopped 2025-09-25T15:27:53.944Z,1758814073.944 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:27:53.944Z,1758814073.944 [Default:CheckIn](INFO): Running loop #5 2025-09-25T15:27:53.945Z,1758814073.944 [Default:CheckIn] Running Loop=5 2025-09-25T15:27:53.945Z,1758814073.945 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:27:53.945Z,1758814073.945 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:27:55.972Z,1758814075.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152755.00,A,3648.47549,N,12147.12834,W,5.112,175.90,250925,,,D*73 2025-09-25T15:27:55.974Z,1758814075.974 [NAL9602](INFO): GPS fix at 20250925T152755: (36.807925, -121.785472) 2025-09-25T15:27:55.985Z,1758814075.985 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:27:55.985Z,1758814075.985 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:28:03.607Z,1758814083.607 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250925T151131/Courier0009.lzma 2025-09-25T15:28:04.610Z,1758814084.610 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0009.lzma.bak 2025-09-25T15:28:04.610Z,1758814084.610 [DataOverHttps](INFO): SBD MOMSN=26074701 2025-09-25T15:28:11.326Z,1758814091.326 [NAL9602](INFO): SBD MO Status=0, MOMSN=64792, MT Status=0, MTMSN=0 2025-09-25T15:28:11.327Z,1758814091.327 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:28:23.176Z,1758814103.176 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250925T151131/Express0010.lzma 2025-09-25T15:28:24.178Z,1758814104.178 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0010.lzma.bak 2025-09-25T15:28:24.178Z,1758814104.178 [DataOverHttps](INFO): SBD MOMSN=26074704 2025-09-25T15:28:25.585Z,1758814105.585 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:28:25.585Z,1758814105.585 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:28:25.585Z,1758814105.585 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:28:42.055Z,1758814122.055 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:29:41.836Z,1758814181.836 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:29:55.573Z,1758814195.573 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:30:09.308Z,1758814209.308 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:30:23.450Z,1758814223.450 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:30:37.598Z,1758814237.598 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:30:50.929Z,1758814250.929 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:31:04.664Z,1758814264.664 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:31:18.804Z,1758814278.804 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:31:31.331Z,1758814291.331 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:31:44.660Z,1758814304.660 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:31:58.395Z,1758814318.395 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:32:29.510Z,1758814349.510 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-09-25T15:33:26.091Z,1758814406.091 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:33:26.091Z,1758814406.091 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:33:26.091Z,1758814406.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:33:26.091Z,1758814406.091 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:33:26.509Z,1758814406.509 [Default:CheckIn:D] Stopped 2025-09-25T15:33:26.509Z,1758814406.509 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:33:26.890Z,1758814406.890 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.277352 min 2025-09-25T15:33:26.890Z,1758814406.890 [Default:CheckIn:E] Stopped 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn] Stopped 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn](INFO): Running loop #6 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn] Running Loop=6 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:33:26.891Z,1758814406.891 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:33:28.903Z,1758814408.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153328.00,A,3648.15548,N,12147.21952,W,0.233,210.87,250925,,,D*78 2025-09-25T15:33:28.905Z,1758814408.905 [NAL9602](INFO): GPS fix at 20250925T153328: (36.802591, -121.786992) 2025-09-25T15:33:28.916Z,1758814408.916 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:33:28.916Z,1758814408.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:33:31.375Z,1758814411.375 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-25T15:33:35.856Z,1758814415.856 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002766 CHAN A1 (24V): 0.000592 CHAN A2 (12V): -0.002303 CHAN A3 (5V): -0.001487 CHAN B0 (3.3V): -0.000090 CHAN B1 (3.15aV): 0.000104 CHAN B2 (3.15bV): -0.000260 CHAN B3 (GND): 0.000286 OPEN: -0.000280 Full Scale: +/- 1 mA 2025-09-25T15:33:37.051Z,1758814417.051 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151131/Courier0012.lzma 2025-09-25T15:33:38.054Z,1758814418.054 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0012.lzma.bak 2025-09-25T15:33:38.054Z,1758814418.054 [DataOverHttps](INFO): SBD MOMSN=26074715 2025-09-25T15:33:49.107Z,1758814429.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=64793, MT Status=0, MTMSN=0 2025-09-25T15:33:49.107Z,1758814429.107 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:33:54.272Z,1758814434.272 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250925T151131/Express0013.lzma 2025-09-25T15:33:55.274Z,1758814435.274 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0013.lzma.bak 2025-09-25T15:33:55.274Z,1758814435.274 [DataOverHttps](INFO): SBD MOMSN=26074718 2025-09-25T15:33:56.409Z,1758814436.409 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:33:56.409Z,1758814436.409 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:33:56.409Z,1758814436.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:34:19.813Z,1758814459.813 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:38:56.991Z,1758814736.991 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:38:56.991Z,1758814736.991 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:38:56.991Z,1758814736.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:38:56.992Z,1758814736.992 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:38:57.404Z,1758814737.404 [Default:CheckIn:D] Stopped 2025-09-25T15:38:57.405Z,1758814737.405 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:38:57.781Z,1758814737.781 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.792269 min 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn:E] Stopped 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn] Stopped 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn](INFO): Running loop #7 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn] Running Loop=7 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:38:57.782Z,1758814737.782 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:38:59.792Z,1758814739.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153859.00,A,3648.15423,N,12147.22307,W,0.622,210.87,250925,,,D*74 2025-09-25T15:38:59.795Z,1758814739.795 [NAL9602](INFO): GPS fix at 20250925T153859: (36.802571, -121.787051) 2025-09-25T15:38:59.806Z,1758814739.806 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:38:59.806Z,1758814739.806 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:39:07.387Z,1758814747.387 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0015.lzma 2025-09-25T15:39:08.390Z,1758814748.390 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0015.lzma.bak 2025-09-25T15:39:08.390Z,1758814748.390 [DataOverHttps](INFO): SBD MOMSN=26074788 2025-09-25T15:39:14.332Z,1758814754.332 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:39:24.584Z,1758814764.584 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20250925T151131/Express0016.lzma 2025-09-25T15:39:25.710Z,1758814765.710 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0016.lzma.bak 2025-09-25T15:39:25.710Z,1758814765.710 [DataOverHttps](INFO): SBD MOMSN=26074792 2025-09-25T15:39:26.451Z,1758814766.451 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:39:26.904Z,1758814766.904 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:39:26.904Z,1758814766.904 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:39:26.904Z,1758814766.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:39:38.577Z,1758814778.577 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:39:50.295Z,1758814790.295 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:40:02.414Z,1758814802.414 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:40:14.124Z,1758814814.124 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:40:23.017Z,1758814823.017 [NAL9602](INFO): SBD MO Status=2, MOMSN=64794, MT Status=2, MTMSN=0 2025-09-25T15:40:23.017Z,1758814823.017 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:40:26.246Z,1758814826.246 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:40:37.959Z,1758814837.959 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:40:50.082Z,1758814850.082 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:40:58.183Z,1758814858.183 [NAL9602](INFO): SBD MO Status=2, MOMSN=64794, MT Status=2, MTMSN=0 2025-09-25T15:40:58.183Z,1758814858.183 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:41:01.796Z,1758814861.796 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:41:14.321Z,1758814874.321 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:41:26.036Z,1758814886.036 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T15:42:33.106Z,1758814953.106 [NAL9602](INFO): SBD MO Status=2, MOMSN=64794, MT Status=2, MTMSN=0 2025-09-25T15:42:33.107Z,1758814953.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:42:58.969Z,1758814978.969 [NAL9602](INFO): SBD MO Status=2, MOMSN=64794, MT Status=2, MTMSN=0 2025-09-25T15:42:58.969Z,1758814978.969 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:44:02.385Z,1758815042.385 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T15:44:27.452Z,1758815067.452 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:44:27.452Z,1758815067.452 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:44:27.452Z,1758815067.452 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:44:27.453Z,1758815067.453 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:44:27.850Z,1758815067.850 [Default:CheckIn:D] Stopped 2025-09-25T15:44:27.850Z,1758815067.850 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:44:28.265Z,1758815068.265 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.299711 min 2025-09-25T15:44:28.265Z,1758815068.265 [Default:CheckIn:E] Stopped 2025-09-25T15:44:28.265Z,1758815068.265 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:44:28.265Z,1758815068.265 [Default:CheckIn] Stopped 2025-09-25T15:44:28.265Z,1758815068.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:44:28.265Z,1758815068.265 [Default:CheckIn](INFO): Running loop #8 2025-09-25T15:44:28.266Z,1758815068.266 [Default:CheckIn] Running Loop=8 2025-09-25T15:44:28.266Z,1758815068.266 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:44:28.266Z,1758815068.266 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:44:30.271Z,1758815070.271 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154429.00,A,3648.15521,N,12147.22476,W,0.078,210.87,250925,,,D*73 2025-09-25T15:44:30.273Z,1758815070.273 [NAL9602](INFO): GPS fix at 20250925T154429: (36.802587, -121.787079) 2025-09-25T15:44:30.307Z,1758815070.307 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:44:30.307Z,1758815070.307 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:44:38.087Z,1758815078.087 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0018.lzma 2025-09-25T15:44:39.090Z,1758815079.090 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0018.lzma.bak 2025-09-25T15:44:39.090Z,1758815079.090 [DataOverHttps](INFO): SBD MOMSN=26074825 2025-09-25T15:44:55.288Z,1758815095.288 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20250925T151131/Express0019.lzma 2025-09-25T15:44:56.290Z,1758815096.290 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0019.lzma.bak 2025-09-25T15:44:56.290Z,1758815096.290 [DataOverHttps](INFO): SBD MOMSN=26074828 2025-09-25T15:44:57.752Z,1758815097.752 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:44:57.752Z,1758815097.752 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:44:57.752Z,1758815097.752 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:45:02.585Z,1758815102.585 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:46:03.182Z,1758815163.182 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-09-25T15:46:03.186Z,1758815163.186 [BPC1](FAULT): Failed to receive data from 6 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 50, 51, 54. 2025-09-25T15:49:58.335Z,1758815398.335 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:49:58.335Z,1758815398.335 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:49:58.335Z,1758815398.335 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:49:58.335Z,1758815398.335 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:49:58.723Z,1758815398.723 [Default:CheckIn:D] Stopped 2025-09-25T15:49:58.724Z,1758815398.724 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.814258 min 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn:E] Stopped 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn] Stopped 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn](INFO): Running loop #9 2025-09-25T15:49:59.133Z,1758815399.133 [Default:CheckIn] Running Loop=9 2025-09-25T15:49:59.134Z,1758815399.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:49:59.134Z,1758815399.134 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:50:01.146Z,1758815401.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155000.00,A,3648.16872,N,12147.26376,W,1.361,288.60,250925,,,D*74 2025-09-25T15:50:01.148Z,1758815401.148 [NAL9602](INFO): GPS fix at 20250925T155000: (36.802812, -121.787729) 2025-09-25T15:50:01.159Z,1758815401.159 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:50:01.159Z,1758815401.159 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:50:08.504Z,1758815408.504 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250925T151131/Courier0021.lzma 2025-09-25T15:50:09.506Z,1758815409.506 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0021.lzma.bak 2025-09-25T15:50:09.506Z,1758815409.506 [DataOverHttps](INFO): SBD MOMSN=26074870 2025-09-25T15:50:28.641Z,1758815428.641 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20250925T151131/Express0022.lzma 2025-09-25T15:50:29.642Z,1758815429.642 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0022.lzma.bak 2025-09-25T15:50:29.642Z,1758815429.642 [DataOverHttps](INFO): SBD MOMSN=26074875 2025-09-25T15:50:30.756Z,1758815430.756 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:50:30.756Z,1758815430.756 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:50:30.756Z,1758815430.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:50:31.938Z,1758815431.938 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T15:50:32.012Z,1758815432.012 [NAL9602](ERROR): received: +CSQ:1 OK794, 2, 0, 0, 0 OK 2025-09-25T15:51:29.712Z,1758815489.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=64794, MT Status=0, MTMSN=0 2025-09-25T15:51:29.712Z,1758815489.712 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:52:00.413Z,1758815520.413 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:55:31.320Z,1758815731.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:55:31.320Z,1758815731.320 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:55:31.320Z,1758815731.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:55:31.321Z,1758815731.321 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:55:31.737Z,1758815731.737 [Default:CheckIn:D] Stopped 2025-09-25T15:55:31.737Z,1758815731.737 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:55:32.127Z,1758815732.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.364482 min 2025-09-25T15:55:32.127Z,1758815732.127 [Default:CheckIn:E] Stopped 2025-09-25T15:55:32.127Z,1758815732.127 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:55:32.127Z,1758815732.127 [Default:CheckIn] Stopped 2025-09-25T15:55:32.128Z,1758815732.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:55:32.144Z,1758815732.144 [Default:CheckIn](INFO): Running loop #10 2025-09-25T15:55:32.144Z,1758815732.144 [Default:CheckIn] Running Loop=10 2025-09-25T15:55:32.144Z,1758815732.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:55:32.144Z,1758815732.144 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:55:34.140Z,1758815734.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155533.00,A,3648.17422,N,12147.27834,W,0.058,287.86,250925,,,D*7A 2025-09-25T15:55:34.142Z,1758815734.142 [NAL9602](INFO): GPS fix at 20250925T155533: (36.802904, -121.787972) 2025-09-25T15:55:34.153Z,1758815734.153 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:55:34.153Z,1758815734.153 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:55:41.555Z,1758815741.555 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0024.lzma 2025-09-25T15:55:42.558Z,1758815742.558 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0024.lzma.bak 2025-09-25T15:55:42.558Z,1758815742.558 [DataOverHttps](INFO): SBD MOMSN=26074884 2025-09-25T15:55:58.700Z,1758815758.700 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250925T151131/Express0025.lzma 2025-09-25T15:55:59.703Z,1758815759.703 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0025.lzma.bak 2025-09-25T15:55:59.703Z,1758815759.703 [DataOverHttps](INFO): SBD MOMSN=26074887 2025-09-25T15:56:00.825Z,1758815760.825 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:56:00.825Z,1758815760.825 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:56:00.825Z,1758815760.825 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:58:45.682Z,1758815925.682 [NAL9602](INFO): SBD MO Status=0, MOMSN=64795, MT Status=0, MTMSN=0 2025-09-25T15:58:45.683Z,1758815925.683 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:59:16.422Z,1758815956.422 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:01:01.411Z,1758816061.411 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:01:01.411Z,1758816061.411 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:01:01.411Z,1758816061.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:01:01.412Z,1758816061.412 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:01:01.827Z,1758816061.827 [Default:CheckIn:D] Stopped 2025-09-25T16:01:01.827Z,1758816061.827 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:01:02.224Z,1758816062.224 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.865987 min 2025-09-25T16:01:02.224Z,1758816062.224 [Default:CheckIn:E] Stopped 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn] Stopped 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn](INFO): Running loop #11 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn] Running Loop=11 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:01:02.225Z,1758816062.225 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:01:04.270Z,1758816064.270 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160103.00,A,3648.17366,N,12147.27625,W,0.194,287.86,250925,,,A*76 2025-09-25T16:01:04.272Z,1758816064.272 [NAL9602](INFO): GPS fix at 20250925T160103: (36.802894, -121.787937) 2025-09-25T16:01:04.283Z,1758816064.283 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:01:04.283Z,1758816064.283 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:01:04.629Z,1758816064.629 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:01:12.324Z,1758816072.324 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0027.lzma 2025-09-25T16:01:13.326Z,1758816073.326 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0027.lzma.bak 2025-09-25T16:01:13.326Z,1758816073.326 [DataOverHttps](INFO): SBD MOMSN=26074916 2025-09-25T16:01:17.981Z,1758816077.981 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:01:27.291Z,1758816087.291 [NAL9602](INFO): SBD MO Status=0, MOMSN=64796, MT Status=0, MTMSN=0 2025-09-25T16:01:27.291Z,1758816087.291 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:01:29.588Z,1758816089.588 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250925T151131/Express0028.lzma 2025-09-25T16:01:30.590Z,1758816090.590 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0028.lzma.bak 2025-09-25T16:01:30.590Z,1758816090.590 [DataOverHttps](INFO): SBD MOMSN=26074919 2025-09-25T16:01:31.317Z,1758816091.317 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:01:31.764Z,1758816091.764 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:01:31.764Z,1758816091.764 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:01:31.764Z,1758816091.764 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:01:43.835Z,1758816103.835 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:01:57.168Z,1758816117.168 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:01:57.997Z,1758816117.997 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:02:10.501Z,1758816130.501 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:02:23.832Z,1758816143.832 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:02:37.164Z,1758816157.164 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:02:50.497Z,1758816170.497 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:03:03.829Z,1758816183.829 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:03:12.377Z,1758816192.377 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-09-25T16:03:17.162Z,1758816197.162 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:04:29.479Z,1758816269.479 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T16:06:32.317Z,1758816392.317 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:06:32.317Z,1758816392.317 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:06:32.318Z,1758816392.318 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:06:32.318Z,1758816392.318 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:06:32.715Z,1758816392.715 [Default:CheckIn:D] Stopped 2025-09-25T16:06:32.715Z,1758816392.715 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:06:33.120Z,1758816393.120 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.380786 min 2025-09-25T16:06:33.120Z,1758816393.120 [Default:CheckIn:E] Stopped 2025-09-25T16:06:33.120Z,1758816393.120 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:06:33.120Z,1758816393.120 [Default:CheckIn] Stopped 2025-09-25T16:06:33.120Z,1758816393.120 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:06:33.121Z,1758816393.121 [Default:CheckIn](INFO): Running loop #12 2025-09-25T16:06:33.121Z,1758816393.121 [Default:CheckIn] Running Loop=12 2025-09-25T16:06:33.121Z,1758816393.121 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:06:33.121Z,1758816393.121 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:06:35.129Z,1758816395.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160634.00,A,3648.17203,N,12147.27770,W,0.058,287.86,250925,,,A*77 2025-09-25T16:06:35.131Z,1758816395.131 [NAL9602](INFO): GPS fix at 20250925T160634: (36.802867, -121.787962) 2025-09-25T16:06:35.142Z,1758816395.142 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:06:35.143Z,1758816395.143 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:06:43.191Z,1758816403.191 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0030.lzma 2025-09-25T16:06:44.194Z,1758816404.194 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0030.lzma.bak 2025-09-25T16:06:44.194Z,1758816404.194 [DataOverHttps](INFO): SBD MOMSN=26074930 2025-09-25T16:07:00.288Z,1758816420.288 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250925T151131/Express0031.lzma 2025-09-25T16:07:01.290Z,1758816421.290 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0031.lzma.bak 2025-09-25T16:07:01.290Z,1758816421.290 [DataOverHttps](INFO): SBD MOMSN=26074933 2025-09-25T16:07:02.629Z,1758816422.629 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:07:02.629Z,1758816422.629 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:07:02.629Z,1758816422.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:07:03.418Z,1758816423.418 [NAL9602](INFO): SBD MO Status=0, MOMSN=64797, MT Status=0, MTMSN=0 2025-09-25T16:07:03.419Z,1758816423.419 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:07:34.133Z,1758816454.133 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:10:48.436Z,1758816648.436 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:11:01.789Z,1758816661.789 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:11:15.523Z,1758816675.523 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:11:28.862Z,1758816688.862 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:11:42.187Z,1758816702.187 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:11:55.521Z,1758816715.521 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:12:03.273Z,1758816723.273 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:12:03.273Z,1758816723.273 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:12:03.273Z,1758816723.273 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:12:03.273Z,1758816723.273 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:12:03.631Z,1758816723.631 [Default:CheckIn:D] Stopped 2025-09-25T16:12:03.631Z,1758816723.631 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:12:04.036Z,1758816724.036 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.896053 min 2025-09-25T16:12:04.036Z,1758816724.036 [Default:CheckIn:E] Stopped 2025-09-25T16:12:04.036Z,1758816724.036 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:12:04.036Z,1758816724.036 [Default:CheckIn] Stopped 2025-09-25T16:12:04.036Z,1758816724.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:12:04.037Z,1758816724.037 [Default:CheckIn](INFO): Running loop #13 2025-09-25T16:12:04.037Z,1758816724.037 [Default:CheckIn] Running Loop=13 2025-09-25T16:12:04.037Z,1758816724.037 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:12:04.037Z,1758816724.037 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:12:06.035Z,1758816726.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161205.00,A,3648.17392,N,12147.27897,W,0.136,287.86,250925,,,A*76 2025-09-25T16:12:06.037Z,1758816726.037 [NAL9602](INFO): GPS fix at 20250925T161205: (36.802899, -121.787983) 2025-09-25T16:12:06.065Z,1758816726.065 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:12:06.065Z,1758816726.065 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:12:08.853Z,1758816728.853 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:12:13.792Z,1758816733.792 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0033.lzma 2025-09-25T16:12:14.794Z,1758816734.794 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0033.lzma.bak 2025-09-25T16:12:14.794Z,1758816734.794 [DataOverHttps](INFO): SBD MOMSN=26074970 2025-09-25T16:12:22.639Z,1758816742.639 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:12:34.288Z,1758816754.288 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250925T151131/Express0034.lzma 2025-09-25T16:12:35.290Z,1758816755.290 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0034.lzma.bak 2025-09-25T16:12:35.290Z,1758816755.290 [DataOverHttps](INFO): SBD MOMSN=26074973 2025-09-25T16:12:36.411Z,1758816756.411 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:12:36.411Z,1758816756.411 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:12:36.411Z,1758816756.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:12:37.200Z,1758816757.200 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:12:50.531Z,1758816770.531 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:14:29.546Z,1758816869.546 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T16:16:20.616Z,1758816980.616 [NAL9602](INFO): SBD MO Status=0, MOMSN=64798, MT Status=0, MTMSN=0 2025-09-25T16:16:20.616Z,1758816980.616 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:16:51.330Z,1758817011.330 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:17:36.991Z,1758817056.991 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:17:36.991Z,1758817056.991 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:17:36.991Z,1758817056.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:17:36.992Z,1758817056.992 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:17:37.397Z,1758817057.397 [Default:CheckIn:D] Stopped 2025-09-25T16:17:37.397Z,1758817057.397 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.458813 min 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn:E] Stopped 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn] Stopped 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn](INFO): Running loop #14 2025-09-25T16:17:37.805Z,1758817057.805 [Default:CheckIn] Running Loop=14 2025-09-25T16:17:37.806Z,1758817057.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:17:37.806Z,1758817057.806 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:17:39.824Z,1758817059.824 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161739.00,A,3648.17538,N,12147.27193,W,0.330,0.00,250925,,,A*70 2025-09-25T16:17:39.826Z,1758817059.826 [NAL9602](INFO): GPS fix at 20250925T161739: (36.802923, -121.787865) 2025-09-25T16:17:39.837Z,1758817059.837 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:17:39.837Z,1758817059.837 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:17:47.603Z,1758817067.603 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0036.lzma 2025-09-25T16:17:48.606Z,1758817068.606 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0036.lzma.bak 2025-09-25T16:17:48.606Z,1758817068.606 [DataOverHttps](INFO): SBD MOMSN=26074984 2025-09-25T16:17:56.770Z,1758817076.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=64799, MT Status=0, MTMSN=0 2025-09-25T16:17:56.771Z,1758817076.771 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:18:04.860Z,1758817084.860 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250925T151131/Express0037.lzma 2025-09-25T16:18:05.863Z,1758817085.863 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0037.lzma.bak 2025-09-25T16:18:05.863Z,1758817085.863 [DataOverHttps](INFO): SBD MOMSN=26074987 2025-09-25T16:18:06.951Z,1758817086.951 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:18:06.951Z,1758817086.951 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:18:06.951Z,1758817086.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:18:27.477Z,1758817107.477 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:20:23.019Z,1758817223.019 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:20:36.354Z,1758817236.354 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:20:49.683Z,1758817249.683 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:21:03.015Z,1758817263.015 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:21:16.349Z,1758817276.349 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:21:29.679Z,1758817289.679 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:21:43.418Z,1758817303.418 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:21:56.750Z,1758817316.750 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:22:10.079Z,1758817330.079 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:22:23.412Z,1758817343.412 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T16:23:07.461Z,1758817387.461 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:23:07.461Z,1758817387.461 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:23:07.461Z,1758817387.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:23:07.462Z,1758817387.462 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:23:07.866Z,1758817387.866 [Default:CheckIn:D] Stopped 2025-09-25T16:23:07.866Z,1758817387.866 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:23:08.329Z,1758817388.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.966634 min 2025-09-25T16:23:08.329Z,1758817388.329 [Default:CheckIn:E] Stopped 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn] Stopped 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn](INFO): Running loop #15 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn] Running Loop=15 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:23:08.330Z,1758817388.330 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:23:10.279Z,1758817390.279 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162309.00,A,3648.17215,N,12147.28136,W,0.214,338.78,250925,,,A*7C 2025-09-25T16:23:10.282Z,1758817390.282 [NAL9602](INFO): GPS fix at 20250925T162309: (36.802869, -121.788023) 2025-09-25T16:23:10.317Z,1758817390.317 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:23:10.317Z,1758817390.317 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:23:18.287Z,1758817398.287 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0039.lzma 2025-09-25T16:23:19.290Z,1758817399.290 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0039.lzma.bak 2025-09-25T16:23:19.290Z,1758817399.290 [DataOverHttps](INFO): SBD MOMSN=26075028 2025-09-25T16:23:35.460Z,1758817415.460 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250925T151131/Express0040.lzma 2025-09-25T16:23:36.462Z,1758817416.462 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0040.lzma.bak 2025-09-25T16:23:36.462Z,1758817416.462 [DataOverHttps](INFO): SBD MOMSN=26075031 2025-09-25T16:23:37.770Z,1758817417.770 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:23:37.770Z,1758817417.770 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:23:37.770Z,1758817417.770 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:24:29.870Z,1758817469.870 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T16:25:31.285Z,1758817531.285 [NAL9602](INFO): SBD MO Status=0, MOMSN=64800, MT Status=0, MTMSN=0 2025-09-25T16:25:31.285Z,1758817531.285 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:26:01.993Z,1758817561.993 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:27:04.645Z,1758817624.645 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-09-25T16:27:04.649Z,1758817624.649 [BPC1](FAULT): Failed to receive data from 6 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 50, 51, 54. 2025-09-25T16:28:38.417Z,1758817718.417 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:28:38.417Z,1758817718.417 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:28:38.417Z,1758817718.417 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:28:38.418Z,1758817718.418 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:28:38.832Z,1758817718.832 [Default:CheckIn:D] Stopped 2025-09-25T16:28:38.832Z,1758817718.832 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:28:39.221Z,1758817719.221 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.482731 min 2025-09-25T16:28:39.221Z,1758817719.221 [Default:CheckIn:E] Stopped 2025-09-25T16:28:39.221Z,1758817719.221 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:28:39.221Z,1758817719.221 [Default:CheckIn] Stopped 2025-09-25T16:28:39.221Z,1758817719.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:28:39.222Z,1758817719.222 [Default:CheckIn](INFO): Running loop #16 2025-09-25T16:28:39.222Z,1758817719.222 [Default:CheckIn] Running Loop=16 2025-09-25T16:28:39.222Z,1758817719.222 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:28:39.222Z,1758817719.222 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:28:41.229Z,1758817721.229 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162840.00,A,3648.17442,N,12147.27496,W,0.544,338.78,250925,,,A*7C 2025-09-25T16:28:41.231Z,1758817721.231 [NAL9602](INFO): GPS fix at 20250925T162840: (36.802907, -121.787916) 2025-09-25T16:28:41.242Z,1758817721.242 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:28:41.242Z,1758817721.242 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:28:48.939Z,1758817728.939 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20250925T151131/Courier0042.lzma 2025-09-25T16:28:49.943Z,1758817729.943 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0042.lzma.bak 2025-09-25T16:28:49.943Z,1758817729.943 [DataOverHttps](INFO): SBD MOMSN=26075042 2025-09-25T16:28:55.366Z,1758817735.366 [NAL9602](INFO): SBD MO Status=0, MOMSN=64801, MT Status=0, MTMSN=0 2025-09-25T16:28:55.367Z,1758817735.367 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:29:05.997Z,1758817745.997 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20250925T151131/Express0043.lzma 2025-09-25T16:29:06.998Z,1758817746.998 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0043.lzma.bak 2025-09-25T16:29:06.998Z,1758817746.998 [DataOverHttps](INFO): SBD MOMSN=26075045 2025-09-25T16:29:08.325Z,1758817748.325 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:29:08.325Z,1758817748.325 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:29:08.325Z,1758817748.325 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:29:26.069Z,1758817766.069 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:34:08.893Z,1758818048.893 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:34:08.893Z,1758818048.893 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:34:08.893Z,1758818048.893 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:34:08.893Z,1758818048.893 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:34:09.289Z,1758818049.289 [Default:CheckIn:D] Stopped 2025-09-25T16:34:09.289Z,1758818049.289 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.990348 min 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn:E] Stopped 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn] Stopped 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn](INFO): Running loop #17 2025-09-25T16:34:09.689Z,1758818049.689 [Default:CheckIn] Running Loop=17 2025-09-25T16:34:09.690Z,1758818049.690 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:34:09.690Z,1758818049.690 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:34:11.703Z,1758818051.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163410.00,A,3648.17102,N,12147.27610,W,0.369,338.78,250925,,,A*70 2025-09-25T16:34:11.705Z,1758818051.705 [NAL9602](INFO): GPS fix at 20250925T163410: (36.802850, -121.787935) 2025-09-25T16:34:11.716Z,1758818051.716 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:34:11.716Z,1758818051.716 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:34:20.336Z,1758818060.336 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0045.lzma 2025-09-25T16:34:21.338Z,1758818061.338 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0045.lzma.bak 2025-09-25T16:34:21.338Z,1758818061.338 [DataOverHttps](INFO): SBD MOMSN=26075085 2025-09-25T16:34:29.919Z,1758818069.919 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T16:34:39.584Z,1758818079.584 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250925T151131/Express0046.lzma 2025-09-25T16:34:40.586Z,1758818080.586 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0046.lzma.bak 2025-09-25T16:34:40.586Z,1758818080.586 [DataOverHttps](INFO): SBD MOMSN=26075088 2025-09-25T16:34:42.047Z,1758818082.047 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:34:42.047Z,1758818082.047 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:34:42.048Z,1758818082.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:35:44.659Z,1758818144.659 [NAL9602](INFO): SBD MO Status=0, MOMSN=64802, MT Status=0, MTMSN=0 2025-09-25T16:35:44.659Z,1758818144.659 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:36:15.362Z,1758818175.362 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:39:42.726Z,1758818382.726 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:39:42.726Z,1758818382.726 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:39:42.726Z,1758818382.726 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:39:42.726Z,1758818382.726 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:39:43.141Z,1758818383.141 [Default:CheckIn:D] Stopped 2025-09-25T16:39:43.141Z,1758818383.141 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:39:43.526Z,1758818383.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.554549 min 2025-09-25T16:39:43.526Z,1758818383.526 [Default:CheckIn:E] Stopped 2025-09-25T16:39:43.526Z,1758818383.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:39:43.526Z,1758818383.526 [Default:CheckIn] Stopped 2025-09-25T16:39:43.526Z,1758818383.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:39:43.526Z,1758818383.526 [Default:CheckIn](INFO): Running loop #18 2025-09-25T16:39:43.527Z,1758818383.527 [Default:CheckIn] Running Loop=18 2025-09-25T16:39:43.527Z,1758818383.527 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:39:43.527Z,1758818383.527 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:39:45.538Z,1758818385.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163944.00,A,3648.17971,N,12147.27315,W,0.175,15.34,250925,,,A*4B 2025-09-25T16:39:45.541Z,1758818385.541 [NAL9602](INFO): GPS fix at 20250925T163944: (36.802995, -121.787886) 2025-09-25T16:39:45.553Z,1758818385.553 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:39:45.553Z,1758818385.553 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:39:53.167Z,1758818393.167 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250925T151131/Courier0048.lzma 2025-09-25T16:39:54.172Z,1758818394.172 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0048.lzma.bak 2025-09-25T16:39:54.172Z,1758818394.172 [DataOverHttps](INFO): SBD MOMSN=26075099 2025-09-25T16:40:10.252Z,1758818410.252 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250925T151131/Express0049.lzma 2025-09-25T16:40:11.254Z,1758818411.254 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0049.lzma.bak 2025-09-25T16:40:11.254Z,1758818411.254 [DataOverHttps](INFO): SBD MOMSN=26075102 2025-09-25T16:40:11.393Z,1758818411.393 [NAL9602](INFO): SBD MO Status=0, MOMSN=64803, MT Status=0, MTMSN=0 2025-09-25T16:40:11.393Z,1758818411.393 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:40:12.624Z,1758818412.624 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:40:12.624Z,1758818412.624 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:40:12.624Z,1758818412.624 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:40:42.109Z,1758818442.109 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:42:04.532Z,1758818524.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:42:17.249Z,1758818537.249 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:42:20.321Z,1758818540.321 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-25T16:42:20.321Z,1758818540.321 [DropWeight] Hardware Fault, FailCount= 1 2025-09-25T16:42:20.321Z,1758818540.321 [DropWeight](ERROR): Hardware Fault 2025-09-25T16:42:20.435Z,1758818540.435 [CommandExec](FAULT): Scheduling is paused 2025-09-25T16:42:20.435Z,1758818540.435 [CBIT](INFO): Critical error at 20250925T164220 2025-09-25T16:42:20.446Z,1758818540.446 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-25T16:42:20.447Z,1758818540.447 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-25T16:42:20.807Z,1758818540.807 [CBIT](INFO): Critical error at 20250925T164220 2025-09-25T16:42:30.031Z,1758818550.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:42:43.360Z,1758818563.360 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:42:56.290Z,1758818576.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:43:08.813Z,1758818588.813 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:43:21.739Z,1758818601.739 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:43:34.668Z,1758818614.668 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:43:46.794Z,1758818626.794 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:43:59.311Z,1758818639.311 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:44:12.239Z,1758818652.239 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:44:25.657Z,1758818665.657 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 4. 2025-09-25T16:44:25.659Z,1758818665.659 [BPC1](INFO): Received data from all battery sticks. 2025-09-25T16:44:30.079Z,1758818670.079 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T16:45:13.317Z,1758818713.317 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:45:13.317Z,1758818713.317 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:45:13.317Z,1758818713.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:45:13.317Z,1758818713.317 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:45:13.726Z,1758818713.726 [Default:CheckIn:D] Stopped 2025-09-25T16:45:13.726Z,1758818713.726 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:45:14.140Z,1758818714.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.064307 min 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn:E] Stopped 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn] Stopped 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn](INFO): Running loop #19 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn] Running Loop=19 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:45:14.141Z,1758818714.141 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:45:16.138Z,1758818716.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164515.00,A,3648.16817,N,12147.27745,W,0.311,54.89,250925,,,A*46 2025-09-25T16:45:16.141Z,1758818716.141 [NAL9602](INFO): GPS fix at 20250925T164515: (36.802803, -121.787958) 2025-09-25T16:45:16.152Z,1758818716.152 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:45:16.152Z,1758818716.152 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:45:23.877Z,1758818723.877 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20250925T151131/Courier0051.lzma 2025-09-25T16:45:24.878Z,1758818724.878 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0051.lzma.bak 2025-09-25T16:45:24.878Z,1758818724.878 [DataOverHttps](INFO): SBD MOMSN=26075158 2025-09-25T16:45:33.916Z,1758818733.916 [NAL9602](INFO): SBD MO Status=0, MOMSN=64804, MT Status=0, MTMSN=0 2025-09-25T16:45:33.916Z,1758818733.916 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:45:40.828Z,1758818740.828 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250925T151131/Express0052.lzma 2025-09-25T16:45:41.830Z,1758818741.830 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0052.lzma.bak 2025-09-25T16:45:41.830Z,1758818741.830 [DataOverHttps](INFO): SBD MOMSN=26075163 2025-09-25T16:45:43.217Z,1758818743.217 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:45:43.217Z,1758818743.217 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:45:43.217Z,1758818743.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:46:04.617Z,1758818764.617 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:50:43.912Z,1758819043.912 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:50:43.912Z,1758819043.912 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:50:43.912Z,1758819043.912 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:50:43.913Z,1758819043.913 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:50:44.320Z,1758819044.320 [Default:CheckIn:D] Stopped 2025-09-25T16:50:44.320Z,1758819044.320 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.574194 min 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn:E] Stopped 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn] Stopped 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn](INFO): Running loop #20 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn] Running Loop=20 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:50:44.734Z,1758819044.734 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:50:46.735Z,1758819046.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165046.00,A,3648.17894,N,12147.27533,W,0.330,120.33,250925,,,A*7D 2025-09-25T16:50:46.737Z,1758819046.737 [NAL9602](INFO): GPS fix at 20250925T165046: (36.802982, -121.787922) 2025-09-25T16:50:46.766Z,1758819046.766 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:50:46.766Z,1758819046.766 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:50:54.711Z,1758819054.711 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0054.lzma 2025-09-25T16:50:55.714Z,1758819055.714 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0054.lzma.bak 2025-09-25T16:50:55.714Z,1758819055.714 [DataOverHttps](INFO): SBD MOMSN=26075197 2025-09-25T16:51:11.812Z,1758819071.812 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250925T151131/Express0055.lzma 2025-09-25T16:51:12.814Z,1758819072.814 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0055.lzma.bak 2025-09-25T16:51:12.815Z,1758819072.815 [DataOverHttps](INFO): SBD MOMSN=26075200 2025-09-25T16:51:14.231Z,1758819074.231 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:51:14.231Z,1758819074.231 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:51:14.231Z,1758819074.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:54:30.257Z,1758819270.257 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T16:54:35.910Z,1758819275.910 [NAL9602](INFO): SBD MO Status=0, MOMSN=64805, MT Status=0, MTMSN=0 2025-09-25T16:54:35.910Z,1758819275.910 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:55:06.615Z,1758819306.615 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:56:14.897Z,1758819374.897 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:56:14.897Z,1758819374.897 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:56:14.897Z,1758819374.897 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:56:14.897Z,1758819374.897 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:56:15.309Z,1758819375.309 [Default:CheckIn:D] Stopped 2025-09-25T16:56:15.309Z,1758819375.309 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.090690 min 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn:E] Stopped 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn] Stopped 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn](INFO): Running loop #21 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn] Running Loop=21 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:56:15.726Z,1758819375.726 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:56:17.719Z,1758819377.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165617.00,A,3648.16426,N,12147.27483,W,0.078,33.76,250925,,,A*4C 2025-09-25T16:56:17.721Z,1758819377.721 [NAL9602](INFO): GPS fix at 20250925T165617: (36.802738, -121.787914) 2025-09-25T16:56:17.737Z,1758819377.737 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:56:17.737Z,1758819377.737 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:56:28.180Z,1758819388.180 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0057.lzma 2025-09-25T16:56:29.182Z,1758819389.182 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0057.lzma.bak 2025-09-25T16:56:29.182Z,1758819389.182 [DataOverHttps](INFO): SBD MOMSN=26075258 2025-09-25T16:56:29.519Z,1758819389.519 [NAL9602](INFO): SBD MO Status=0, MOMSN=64806, MT Status=0, MTMSN=0 2025-09-25T16:56:29.519Z,1758819389.519 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:56:45.224Z,1758819405.224 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250925T151131/Express0058.lzma 2025-09-25T16:56:46.226Z,1758819406.226 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0058.lzma.bak 2025-09-25T16:56:46.226Z,1758819406.226 [DataOverHttps](INFO): SBD MOMSN=26075261 2025-09-25T16:56:47.704Z,1758819407.704 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:56:47.704Z,1758819407.704 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:56:47.705Z,1758819407.705 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:57:00.230Z,1758819420.230 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:57:20.468Z,1758819440.468 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-25T16:57:20.469Z,1758819440.469 [DropWeight] No Fault, FailCount= 1 2025-09-25T17:01:48.376Z,1758819708.376 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:01:48.377Z,1758819708.377 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:01:48.377Z,1758819708.377 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:01:48.377Z,1758819708.377 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:01:48.797Z,1758819708.797 [Default:CheckIn:D] Stopped 2025-09-25T17:01:48.797Z,1758819708.797 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:01:49.183Z,1758819709.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.648820 min 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn:E] Stopped 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn] Stopped 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn](INFO): Running loop #22 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn] Running Loop=22 2025-09-25T17:01:49.184Z,1758819709.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:01:49.185Z,1758819709.185 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:01:51.189Z,1758819711.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170150.00,A,3648.18502,N,12147.27557,W,0.019,17.52,250925,,,A*4A 2025-09-25T17:01:51.191Z,1758819711.191 [NAL9602](INFO): GPS fix at 20250925T170150: (36.803084, -121.787926) 2025-09-25T17:01:51.202Z,1758819711.202 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:01:51.202Z,1758819711.202 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:01:58.816Z,1758819718.816 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0060.lzma 2025-09-25T17:01:59.818Z,1758819719.818 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0060.lzma.bak 2025-09-25T17:01:59.818Z,1758819719.818 [DataOverHttps](INFO): SBD MOMSN=26075275 2025-09-25T17:02:15.924Z,1758819735.924 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250925T151131/Express0061.lzma 2025-09-25T17:02:16.926Z,1758819736.926 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0061.lzma.bak 2025-09-25T17:02:16.926Z,1758819736.926 [DataOverHttps](INFO): SBD MOMSN=26075299 2025-09-25T17:02:18.283Z,1758819738.283 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:02:18.283Z,1758819738.283 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:02:18.283Z,1758819738.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:03:48.813Z,1758819828.813 [NAL9602](INFO): SBD MO Status=0, MOMSN=64807, MT Status=0, MTMSN=0 2025-09-25T17:03:48.813Z,1758819828.813 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:04:19.513Z,1758819859.513 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:04:30.515Z,1758819870.515 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T17:07:19.016Z,1758820039.016 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:07:19.016Z,1758820039.016 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:07:19.016Z,1758820039.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:07:19.016Z,1758820039.016 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:07:19.403Z,1758820039.403 [Default:CheckIn:D] Stopped 2025-09-25T17:07:19.403Z,1758820039.403 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:07:19.802Z,1758820039.802 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.158927 min 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn:E] Stopped 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn] Stopped 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn](INFO): Running loop #23 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn] Running Loop=23 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:07:19.803Z,1758820039.803 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:07:21.815Z,1758820041.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170721.00,A,3648.19201,N,12147.26962,W,0.194,196.00,250925,,,A*7F 2025-09-25T17:07:21.817Z,1758820041.817 [NAL9602](INFO): GPS fix at 20250925T170721: (36.803200, -121.787827) 2025-09-25T17:07:21.849Z,1758820041.849 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:07:21.849Z,1758820041.849 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:07:29.615Z,1758820049.615 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0063.lzma 2025-09-25T17:07:30.618Z,1758820050.618 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0063.lzma.bak 2025-09-25T17:07:30.618Z,1758820050.618 [DataOverHttps](INFO): SBD MOMSN=26075307 2025-09-25T17:07:46.612Z,1758820066.612 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250925T151131/Express0064.lzma 2025-09-25T17:07:47.614Z,1758820067.614 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0064.lzma.bak 2025-09-25T17:07:47.614Z,1758820067.614 [DataOverHttps](INFO): SBD MOMSN=26075315 2025-09-25T17:07:48.897Z,1758820068.897 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:07:48.897Z,1758820068.897 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:07:48.897Z,1758820068.897 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:08:15.957Z,1758820095.957 [NAL9602](INFO): SBD MO Status=0, MOMSN=64808, MT Status=0, MTMSN=0 2025-09-25T17:08:15.957Z,1758820095.957 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:08:46.694Z,1758820126.694 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:12:49.532Z,1758820369.532 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:12:49.532Z,1758820369.532 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:12:49.532Z,1758820369.532 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:12:49.533Z,1758820369.533 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:12:49.938Z,1758820369.938 [Default:CheckIn:D] Stopped 2025-09-25T17:12:49.938Z,1758820369.938 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.667839 min 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn:E] Stopped 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn] Stopped 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn](INFO): Running loop #24 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn] Running Loop=24 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:12:50.354Z,1758820370.354 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:12:52.355Z,1758820372.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171251.00,A,3648.18441,N,12147.26948,W,0.253,196.00,250925,,,A*7F 2025-09-25T17:12:52.357Z,1758820372.357 [NAL9602](INFO): GPS fix at 20250925T171251: (36.803073, -121.787825) 2025-09-25T17:12:52.384Z,1758820372.384 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:12:52.384Z,1758820372.384 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:12:59.881Z,1758820379.881 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0066.lzma 2025-09-25T17:13:00.882Z,1758820380.882 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0066.lzma.bak 2025-09-25T17:13:00.882Z,1758820380.882 [DataOverHttps](INFO): SBD MOMSN=26075410 2025-09-25T17:13:12.977Z,1758820392.977 [NAL9602](INFO): SBD MO Status=0, MOMSN=64809, MT Status=0, MTMSN=0 2025-09-25T17:13:12.978Z,1758820392.977 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:13:17.080Z,1758820397.080 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250925T151131/Express0067.lzma 2025-09-25T17:13:18.082Z,1758820398.082 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0067.lzma.bak 2025-09-25T17:13:18.082Z,1758820398.082 [DataOverHttps](INFO): SBD MOMSN=26075413 2025-09-25T17:13:19.445Z,1758820399.445 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:13:19.445Z,1758820399.445 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:13:19.445Z,1758820399.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:13:43.657Z,1758820423.657 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:14:30.573Z,1758820470.573 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T17:18:20.061Z,1758820700.061 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:18:20.061Z,1758820700.061 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:18:20.061Z,1758820700.061 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:18:20.061Z,1758820700.061 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:18:20.477Z,1758820700.477 [Default:CheckIn:D] Stopped 2025-09-25T17:18:20.477Z,1758820700.477 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:18:20.863Z,1758820700.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.176807 min 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn:E] Stopped 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn] Stopped 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn](INFO): Running loop #25 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn] Running Loop=25 2025-09-25T17:18:20.864Z,1758820700.864 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:18:20.865Z,1758820700.865 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:18:22.866Z,1758820702.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171822.00,A,3648.18697,N,12147.25983,W,0.428,196.00,250925,,,A*76 2025-09-25T17:18:22.876Z,1758820702.876 [NAL9602](INFO): GPS fix at 20250925T171822: (36.803116, -121.787664) 2025-09-25T17:18:22.887Z,1758820702.887 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:18:22.887Z,1758820702.887 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:18:30.827Z,1758820710.827 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0069.lzma 2025-09-25T17:18:31.830Z,1758820711.830 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0069.lzma.bak 2025-09-25T17:18:31.830Z,1758820711.830 [DataOverHttps](INFO): SBD MOMSN=26075425 2025-09-25T17:18:47.944Z,1758820727.944 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250925T151131/Express0070.lzma 2025-09-25T17:18:48.946Z,1758820728.946 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0070.lzma.bak 2025-09-25T17:18:48.946Z,1758820728.946 [DataOverHttps](INFO): SBD MOMSN=26075428 2025-09-25T17:18:50.373Z,1758820730.373 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:18:50.373Z,1758820730.373 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:18:50.373Z,1758820730.373 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:23:14.963Z,1758820994.963 [NAL9602](INFO): SBD MO Status=0, MOMSN=64810, MT Status=0, MTMSN=0 2025-09-25T17:23:14.963Z,1758820994.963 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:23:45.664Z,1758821025.664 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:23:50.937Z,1758821030.937 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:23:50.937Z,1758821030.937 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:23:50.937Z,1758821030.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:23:50.937Z,1758821030.937 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:23:51.332Z,1758821031.332 [Default:CheckIn:D] Stopped 2025-09-25T17:23:51.332Z,1758821031.332 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.691064 min 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn:E] Stopped 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn] Stopped 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn](INFO): Running loop #26 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn] Running Loop=26 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:23:51.733Z,1758821031.733 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:23:53.745Z,1758821033.745 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172353.00,A,3648.18635,N,12147.27307,W,0.253,0.00,250925,,,A*70 2025-09-25T17:23:53.747Z,1758821033.747 [NAL9602](INFO): GPS fix at 20250925T172353: (36.803106, -121.787885) 2025-09-25T17:23:53.758Z,1758821033.758 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:23:53.758Z,1758821033.758 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:24:01.171Z,1758821041.171 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0072.lzma 2025-09-25T17:24:02.174Z,1758821042.174 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0072.lzma.bak 2025-09-25T17:24:02.174Z,1758821042.174 [DataOverHttps](INFO): SBD MOMSN=26075503 2025-09-25T17:24:09.907Z,1758821049.907 [NAL9602](INFO): SBD MO Status=2, MOMSN=64811, MT Status=2, MTMSN=0 2025-09-25T17:24:09.907Z,1758821049.907 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:24:18.464Z,1758821058.464 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250925T151131/Express0073.lzma 2025-09-25T17:24:19.466Z,1758821059.466 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0073.lzma.bak 2025-09-25T17:24:19.466Z,1758821059.466 [DataOverHttps](INFO): SBD MOMSN=26075506 2025-09-25T17:24:20.852Z,1758821060.852 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:24:20.852Z,1758821060.852 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:24:20.852Z,1758821060.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:24:27.327Z,1758821067.327 [NAL9602](INFO): SBD MO Status=2, MOMSN=64811, MT Status=2, MTMSN=0 2025-09-25T17:24:27.327Z,1758821067.327 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:24:30.540Z,1758821070.540 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T17:24:43.467Z,1758821083.467 [NAL9602](INFO): SBD MO Status=2, MOMSN=64811, MT Status=2, MTMSN=0 2025-09-25T17:24:43.468Z,1758821083.468 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:25:09.323Z,1758821109.323 [NAL9602](INFO): SBD MO Status=2, MOMSN=64811, MT Status=2, MTMSN=0 2025-09-25T17:25:09.323Z,1758821109.323 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:25:26.294Z,1758821126.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=64811, MT Status=2, MTMSN=0 2025-09-25T17:25:26.295Z,1758821126.295 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:27:21.833Z,1758821241.833 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-25T17:27:21.833Z,1758821241.833 [DropWeight] Hardware Fault, FailCount= 1 2025-09-25T17:27:21.833Z,1758821241.833 [DropWeight](ERROR): Hardware Fault 2025-09-25T17:27:21.883Z,1758821241.883 [CBIT](INFO): Critical error at 20250925T172721 2025-09-25T17:27:21.889Z,1758821241.889 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-25T17:27:21.889Z,1758821241.889 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-25T17:27:22.281Z,1758821242.281 [CBIT](INFO): Critical error at 20250925T172721 2025-09-25T17:28:56.375Z,1758821336.375 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T17:29:21.431Z,1758821361.431 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:29:21.431Z,1758821361.431 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:29:21.431Z,1758821361.431 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:29:21.431Z,1758821361.431 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:29:21.838Z,1758821361.838 [Default:CheckIn:D] Stopped 2025-09-25T17:29:21.838Z,1758821361.838 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:29:22.252Z,1758821362.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.199512 min 2025-09-25T17:29:22.252Z,1758821362.252 [Default:CheckIn:E] Stopped 2025-09-25T17:29:22.252Z,1758821362.252 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:29:22.252Z,1758821362.252 [Default:CheckIn] Stopped 2025-09-25T17:29:22.252Z,1758821362.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:29:22.253Z,1758821362.253 [Default:CheckIn](INFO): Running loop #27 2025-09-25T17:29:22.253Z,1758821362.253 [Default:CheckIn] Running Loop=27 2025-09-25T17:29:22.253Z,1758821362.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:29:22.253Z,1758821362.253 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:29:24.254Z,1758821364.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172923.00,A,3648.17671,N,12147.26514,W,0.039,0.00,250925,,,A*79 2025-09-25T17:29:24.257Z,1758821364.257 [NAL9602](INFO): GPS fix at 20250925T172923: (36.802945, -121.787752) 2025-09-25T17:29:24.288Z,1758821364.288 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:29:24.288Z,1758821364.288 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:29:32.196Z,1758821372.196 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250925T151131/Courier0075.lzma 2025-09-25T17:29:33.210Z,1758821373.210 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0075.lzma.bak 2025-09-25T17:29:33.210Z,1758821373.210 [DataOverHttps](INFO): SBD MOMSN=26075520 2025-09-25T17:29:49.385Z,1758821389.385 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20250925T151131/Express0076.lzma 2025-09-25T17:29:50.386Z,1758821390.386 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0076.lzma.bak 2025-09-25T17:29:50.386Z,1758821390.386 [DataOverHttps](INFO): SBD MOMSN=26075525 2025-09-25T17:29:51.737Z,1758821391.737 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:29:51.737Z,1758821391.737 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:29:51.737Z,1758821391.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:29:56.573Z,1758821396.573 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:34:30.895Z,1758821670.895 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T17:34:52.323Z,1758821692.323 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:34:52.323Z,1758821692.323 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:34:52.324Z,1758821692.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:34:52.324Z,1758821692.324 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:34:52.735Z,1758821692.735 [Default:CheckIn:D] Stopped 2025-09-25T17:34:52.735Z,1758821692.735 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:34:53.143Z,1758821693.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.714453 min 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn:E] Stopped 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn] Stopped 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn](INFO): Running loop #28 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn] Running Loop=28 2025-09-25T17:34:53.144Z,1758821693.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:34:53.145Z,1758821693.145 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:34:55.139Z,1758821695.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173454.00,A,3648.18197,N,12147.26912,W,0.078,0.00,250925,,,A*7A 2025-09-25T17:34:55.142Z,1758821695.142 [NAL9602](INFO): GPS fix at 20250925T173454: (36.803033, -121.787819) 2025-09-25T17:34:55.153Z,1758821695.153 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:34:55.153Z,1758821695.153 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:35:02.447Z,1758821702.447 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0078.lzma 2025-09-25T17:35:03.450Z,1758821703.450 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0078.lzma.bak 2025-09-25T17:35:03.450Z,1758821703.450 [DataOverHttps](INFO): SBD MOMSN=26075565 2025-09-25T17:35:19.509Z,1758821719.509 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250925T151131/Express0079.lzma 2025-09-25T17:35:20.510Z,1758821720.510 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0079.lzma.bak 2025-09-25T17:35:20.510Z,1758821720.510 [DataOverHttps](INFO): SBD MOMSN=26075568 2025-09-25T17:35:21.826Z,1758821721.826 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:35:21.826Z,1758821721.826 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:35:21.826Z,1758821721.826 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:35:25.841Z,1758821725.841 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T17:35:25.924Z,1758821725.924 [NAL9602](ERROR): received: +CSQ:0 OK811, 2, 0, 0, 0 OK 2025-09-25T17:36:11.902Z,1758821771.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=64811, MT Status=0, MTMSN=0 2025-09-25T17:36:11.902Z,1758821771.902 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:36:42.613Z,1758821802.613 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:40:22.402Z,1758822022.402 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:40:22.402Z,1758822022.402 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:40:22.402Z,1758822022.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:40:22.403Z,1758822022.403 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:40:22.830Z,1758822022.830 [Default:CheckIn:D] Stopped 2025-09-25T17:40:22.830Z,1758822022.830 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:40:23.211Z,1758822023.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.216048 min 2025-09-25T17:40:23.211Z,1758822023.211 [Default:CheckIn:E] Stopped 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn] Stopped 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn](INFO): Running loop #29 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn] Running Loop=29 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:40:23.212Z,1758822023.212 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:40:25.219Z,1758822025.219 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174024.00,A,3648.18390,N,12147.27126,W,0.019,0.00,250925,,,A*72 2025-09-25T17:40:25.234Z,1758822025.234 [NAL9602](INFO): GPS fix at 20250925T174024: (36.803065, -121.787854) 2025-09-25T17:40:25.246Z,1758822025.246 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:40:25.246Z,1758822025.246 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:40:33.059Z,1758822033.059 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151131/Courier0081.lzma 2025-09-25T17:40:34.062Z,1758822034.062 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0081.lzma.bak 2025-09-25T17:40:34.062Z,1758822034.062 [DataOverHttps](INFO): SBD MOMSN=26075578 2025-09-25T17:40:50.016Z,1758822050.016 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250925T151131/Express0082.lzma 2025-09-25T17:40:51.018Z,1758822051.018 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0082.lzma.bak 2025-09-25T17:40:51.018Z,1758822051.018 [DataOverHttps](INFO): SBD MOMSN=26075581 2025-09-25T17:40:52.305Z,1758822052.305 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:40:52.305Z,1758822052.305 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:40:52.305Z,1758822052.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:42:22.013Z,1758822142.013 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-25T17:42:22.013Z,1758822142.013 [DropWeight] No Fault, FailCount= 1 2025-09-25T17:43:16.103Z,1758822196.103 [NAL9602](INFO): SBD MO Status=2, MOMSN=64812, MT Status=2, MTMSN=0 2025-09-25T17:43:16.103Z,1758822196.103 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:43:35.495Z,1758822215.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=64812, MT Status=0, MTMSN=0 2025-09-25T17:43:35.495Z,1758822215.495 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:44:06.197Z,1758822246.197 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:44:31.251Z,1758822271.251 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T17:45:31.844Z,1758822331.844 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:45:43.965Z,1758822343.965 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:45:52.868Z,1758822352.868 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:45:52.868Z,1758822352.868 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:45:52.868Z,1758822352.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:45:52.868Z,1758822352.868 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:45:53.289Z,1758822353.289 [Default:CheckIn:D] Stopped 2025-09-25T17:45:53.289Z,1758822353.289 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:45:53.672Z,1758822353.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.723682 min 2025-09-25T17:45:53.672Z,1758822353.672 [Default:CheckIn:E] Stopped 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn] Stopped 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn](INFO): Running loop #30 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn] Running Loop=30 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:45:53.673Z,1758822353.673 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:45:55.681Z,1758822355.681 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:45:55.689Z,1758822355.689 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174555.00,A,3648.17275,N,12147.27774,W,0.136,0.00,250925,,,A*79 2025-09-25T17:45:55.691Z,1758822355.691 [NAL9602](INFO): GPS fix at 20250925T174555: (36.802879, -121.787962) 2025-09-25T17:45:55.703Z,1758822355.703 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:45:55.703Z,1758822355.703 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:46:03.023Z,1758822363.023 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151131/Courier0084.lzma 2025-09-25T17:46:04.026Z,1758822364.026 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0084.lzma.bak 2025-09-25T17:46:04.026Z,1758822364.026 [DataOverHttps](INFO): SBD MOMSN=26075614 2025-09-25T17:46:07.800Z,1758822367.800 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:46:16.700Z,1758822376.700 [NAL9602](INFO): SBD MO Status=0, MOMSN=64813, MT Status=0, MTMSN=0 2025-09-25T17:46:16.700Z,1758822376.700 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:46:18.726Z,1758822378.726 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:46:21.531Z,1758822381.531 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250925T151131/Express0085.lzma 2025-09-25T17:46:22.530Z,1758822382.530 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0085.lzma.bak 2025-09-25T17:46:22.530Z,1758822382.530 [DataOverHttps](INFO): SBD MOMSN=26075617 2025-09-25T17:46:24.006Z,1758822384.006 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:46:24.007Z,1758822384.007 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:46:24.007Z,1758822384.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:46:30.072Z,1758822390.072 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:46:42.191Z,1758822402.191 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:46:47.455Z,1758822407.455 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:46:53.920Z,1758822413.920 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-09-25T17:47:06.842Z,1758822426.842 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-09-25T17:49:31.468Z,1758822571.468 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:49:44.800Z,1758822584.800 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:49:58.132Z,1758822598.132 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:50:10.656Z,1758822610.656 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:50:23.182Z,1758822623.182 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:50:36.107Z,1758822636.107 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:50:48.633Z,1758822648.633 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:51:00.758Z,1758822660.758 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:51:13.680Z,1758822673.680 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:51:24.601Z,1758822684.601 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:51:24.601Z,1758822684.601 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:51:24.601Z,1758822684.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:51:24.601Z,1758822684.601 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:51:25.011Z,1758822685.011 [Default:CheckIn:D] Stopped 2025-09-25T17:51:25.011Z,1758822685.011 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:51:25.410Z,1758822685.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.252393 min 2025-09-25T17:51:25.410Z,1758822685.410 [Default:CheckIn:E] Stopped 2025-09-25T17:51:25.410Z,1758822685.410 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:51:25.411Z,1758822685.411 [Default:CheckIn] Stopped 2025-09-25T17:51:25.411Z,1758822685.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:51:25.411Z,1758822685.411 [Default:CheckIn](INFO): Running loop #31 2025-09-25T17:51:25.411Z,1758822685.411 [Default:CheckIn] Running Loop=31 2025-09-25T17:51:25.411Z,1758822685.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:51:25.411Z,1758822685.411 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:51:26.203Z,1758822686.203 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:51:27.429Z,1758822687.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175126.00,A,3648.17536,N,12147.27279,W,0.233,0.00,250925,,,A*76 2025-09-25T17:51:27.431Z,1758822687.431 [NAL9602](INFO): GPS fix at 20250925T175126: (36.802923, -121.787880) 2025-09-25T17:51:27.443Z,1758822687.443 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:51:27.443Z,1758822687.443 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:51:35.043Z,1758822695.043 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250925T151131/Courier0087.lzma 2025-09-25T17:51:36.046Z,1758822696.046 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0087.lzma.bak 2025-09-25T17:51:36.046Z,1758822696.046 [DataOverHttps](INFO): SBD MOMSN=26075631 2025-09-25T17:51:39.131Z,1758822699.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:51:52.067Z,1758822712.067 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5. 2025-09-25T17:51:52.070Z,1758822712.070 [BPC1](INFO): Received data from all battery sticks. 2025-09-25T17:51:52.148Z,1758822712.148 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250925T151131/Express0088.lzma 2025-09-25T17:51:53.150Z,1758822713.150 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0088.lzma.bak 2025-09-25T17:51:53.150Z,1758822713.150 [DataOverHttps](INFO): SBD MOMSN=26075634 2025-09-25T17:51:54.507Z,1758822714.507 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:51:54.507Z,1758822714.507 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:51:54.507Z,1758822714.507 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:52:26.418Z,1758822746.418 [NAL9602](INFO): SBD MO Status=0, MOMSN=64814, MT Status=0, MTMSN=0 2025-09-25T17:52:26.419Z,1758822746.419 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:52:57.121Z,1758822777.121 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:54:31.659Z,1758822871.659 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T17:56:55.095Z,1758823015.095 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:56:55.095Z,1758823015.095 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:56:55.095Z,1758823015.095 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:56:55.095Z,1758823015.095 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:56:55.731Z,1758823015.731 [Default:CheckIn:D] Stopped 2025-09-25T17:56:55.731Z,1758823015.731 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.764388 min 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn:E] Stopped 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn] Stopped 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn](INFO): Running loop #32 2025-09-25T17:56:55.906Z,1758823015.906 [Default:CheckIn] Running Loop=32 2025-09-25T17:56:55.907Z,1758823015.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:56:55.907Z,1758823015.907 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:56:57.915Z,1758823017.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175657.00,A,3648.17148,N,12147.27788,W,0.622,192.43,250925,,,A*78 2025-09-25T17:56:57.918Z,1758823017.918 [NAL9602](INFO): GPS fix at 20250925T175657: (36.802858, -121.787965) 2025-09-25T17:56:57.946Z,1758823017.946 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:56:57.946Z,1758823017.946 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:57:05.895Z,1758823025.895 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250925T151131/Courier0090.lzma 2025-09-25T17:57:06.898Z,1758823026.898 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0090.lzma.bak 2025-09-25T17:57:06.898Z,1758823026.898 [DataOverHttps](INFO): SBD MOMSN=26075670 2025-09-25T17:57:22.900Z,1758823042.900 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250925T151131/Express0091.lzma 2025-09-25T17:57:23.902Z,1758823043.902 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0091.lzma.bak 2025-09-25T17:57:23.902Z,1758823043.902 [DataOverHttps](INFO): SBD MOMSN=26075673 2025-09-25T17:57:25.391Z,1758823045.391 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:57:25.391Z,1758823045.391 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:57:25.391Z,1758823045.391 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:02:00.515Z,1758823320.515 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T18:02:25.968Z,1758823345.968 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:02:25.968Z,1758823345.968 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:02:25.968Z,1758823345.968 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:02:25.968Z,1758823345.968 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:02:26.381Z,1758823346.381 [Default:CheckIn:D] Stopped 2025-09-25T18:02:26.381Z,1758823346.381 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:02:26.789Z,1758823346.789 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.275212 min 2025-09-25T18:02:26.789Z,1758823346.789 [Default:CheckIn:E] Stopped 2025-09-25T18:02:26.789Z,1758823346.789 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:02:26.789Z,1758823346.789 [Default:CheckIn] Stopped 2025-09-25T18:02:26.790Z,1758823346.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:02:26.790Z,1758823346.790 [Default:CheckIn](INFO): Running loop #33 2025-09-25T18:02:26.790Z,1758823346.790 [Default:CheckIn] Running Loop=33 2025-09-25T18:02:26.790Z,1758823346.790 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:02:26.790Z,1758823346.790 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:02:28.792Z,1758823348.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180228.00,A,3648.16445,N,12147.28330,W,0.447,192.43,250925,,,A*7E 2025-09-25T18:02:28.794Z,1758823348.794 [NAL9602](INFO): GPS fix at 20250925T180228: (36.802741, -121.788055) 2025-09-25T18:02:28.829Z,1758823348.829 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:02:28.829Z,1758823348.829 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:02:36.467Z,1758823356.467 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250925T151131/Courier0093.lzma 2025-09-25T18:02:37.470Z,1758823357.470 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0093.lzma.bak 2025-09-25T18:02:37.470Z,1758823357.470 [DataOverHttps](INFO): SBD MOMSN=26075714 2025-09-25T18:02:53.460Z,1758823373.460 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250925T151131/Express0094.lzma 2025-09-25T18:02:54.462Z,1758823374.462 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0094.lzma.bak 2025-09-25T18:02:54.462Z,1758823374.462 [DataOverHttps](INFO): SBD MOMSN=26075721 2025-09-25T18:02:55.880Z,1758823375.880 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:02:55.880Z,1758823375.880 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:02:55.880Z,1758823375.880 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:03:01.512Z,1758823381.512 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T18:04:32.007Z,1758823472.007 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T18:07:56.503Z,1758823676.503 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:07:56.503Z,1758823676.503 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:07:56.503Z,1758823676.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:07:56.503Z,1758823676.503 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:07:56.900Z,1758823676.900 [Default:CheckIn:D] Stopped 2025-09-25T18:07:56.900Z,1758823676.900 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:07:57.309Z,1758823677.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.783870 min 2025-09-25T18:07:57.309Z,1758823677.309 [Default:CheckIn:E] Stopped 2025-09-25T18:07:57.309Z,1758823677.309 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:07:57.309Z,1758823677.309 [Default:CheckIn] Stopped 2025-09-25T18:07:57.309Z,1758823677.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:07:57.310Z,1758823677.310 [Default:CheckIn](INFO): Running loop #34 2025-09-25T18:07:57.310Z,1758823677.310 [Default:CheckIn] Running Loop=34 2025-09-25T18:07:57.310Z,1758823677.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:07:57.310Z,1758823677.310 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:07:59.313Z,1758823679.313 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180758.00,A,3648.16420,N,12147.28371,W,0.797,205.30,250925,,,A*7D 2025-09-25T18:07:59.315Z,1758823679.315 [NAL9602](INFO): GPS fix at 20250925T180758: (36.802737, -121.788062) 2025-09-25T18:07:59.326Z,1758823679.326 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:07:59.326Z,1758823679.326 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:08:06.799Z,1758823686.799 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151131/Courier0096.lzma 2025-09-25T18:08:07.802Z,1758823687.802 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0096.lzma.bak 2025-09-25T18:08:07.802Z,1758823687.802 [DataOverHttps](INFO): SBD MOMSN=26075775 2025-09-25T18:08:26.537Z,1758823706.537 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250925T151131/Express0097.lzma 2025-09-25T18:08:27.538Z,1758823707.538 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0097.lzma.bak 2025-09-25T18:08:27.538Z,1758823707.538 [DataOverHttps](INFO): SBD MOMSN=26075779 2025-09-25T18:08:28.861Z,1758823708.861 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:08:28.861Z,1758823708.861 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:08:28.861Z,1758823708.861 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:08:30.063Z,1758823710.063 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T18:08:30.144Z,1758823710.144 [NAL9602](ERROR): received: +CSQ:0 OK814, 0, 0, 0, 0 OK 2025-09-25T18:12:23.574Z,1758823943.574 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-25T18:12:23.574Z,1758823943.574 [DropWeight] Hardware Fault, FailCount= 1 2025-09-25T18:12:23.574Z,1758823943.574 [DropWeight](ERROR): Hardware Fault 2025-09-25T18:12:23.626Z,1758823943.626 [CBIT](INFO): Critical error at 20250925T181223 2025-09-25T18:12:23.641Z,1758823943.641 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-25T18:12:23.641Z,1758823943.641 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-25T18:12:24.003Z,1758823944.003 [CBIT](INFO): Critical error at 20250925T181223 2025-09-25T18:13:01.557Z,1758823981.557 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T18:13:29.449Z,1758824009.449 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:13:29.449Z,1758824009.449 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:13:29.449Z,1758824009.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:13:29.450Z,1758824009.450 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:13:29.847Z,1758824009.847 [Default:CheckIn:D] Stopped 2025-09-25T18:13:29.847Z,1758824009.847 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:13:30.257Z,1758824010.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 192.332992 min 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn:E] Stopped 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn] Stopped 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn](INFO): Running loop #35 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn] Running Loop=35 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:13:30.258Z,1758824010.258 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:13:32.266Z,1758824012.266 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181331.00,A,3648.16893,N,12147.26963,W,0.933,205.30,250925,,,A*74 2025-09-25T18:13:32.269Z,1758824012.269 [NAL9602](INFO): GPS fix at 20250925T181331: (36.802816, -121.787827) 2025-09-25T18:13:32.279Z,1758824012.279 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:13:32.280Z,1758824012.280 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:13:39.632Z,1758824019.632 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250925T151131/Courier0099.lzma 2025-09-25T18:13:40.634Z,1758824020.634 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0099.lzma.bak 2025-09-25T18:13:40.634Z,1758824020.634 [DataOverHttps](INFO): SBD MOMSN=26075788 2025-09-25T18:14:04.590Z,1758824044.590 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T18:14:27.636Z,1758824067.636 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20250925T151131/Express0100.lzma 2025-09-25T18:14:28.638Z,1758824068.638 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0100.lzma.bak 2025-09-25T18:14:28.638Z,1758824068.638 [DataOverHttps](INFO): SBD MOMSN=26075793 2025-09-25T18:14:30.049Z,1758824070.049 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:14:30.049Z,1758824070.049 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:14:30.049Z,1758824070.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:14:32.055Z,1758824072.055 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-09-25T18:19:30.639Z,1758824370.639 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:19:30.639Z,1758824370.639 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:19:30.639Z,1758824370.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:19:30.639Z,1758824370.639 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:19:31.024Z,1758824371.024 [Default:CheckIn:D] Stopped 2025-09-25T18:19:31.024Z,1758824371.024 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:19:31.425Z,1758824371.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 198.352604 min 2025-09-25T18:19:31.425Z,1758824371.425 [Default:CheckIn:E] Stopped 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn] Stopped 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn](INFO): Running loop #36 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn] Running Loop=36 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:19:31.426Z,1758824371.426 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:19:33.438Z,1758824373.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181932.00,A,3648.17026,N,12147.26214,W,1.050,0.00,250925,,,A*78 2025-09-25T18:19:33.440Z,1758824373.440 [NAL9602](INFO): GPS fix at 20250925T181932: (36.802838, -121.787702) 2025-09-25T18:19:33.451Z,1758824373.451 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:19:33.451Z,1758824373.451 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:19:39.785Z,1758824379.785 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151131/Courier0102.lzma 2025-09-25T18:19:40.782Z,1758824380.782 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Courier0102.lzma.bak 2025-09-25T18:19:40.782Z,1758824380.782 [DataOverHttps](INFO): SBD MOMSN=26075836 2025-09-25T18:19:56.800Z,1758824396.800 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250925T151131/Express0103.lzma 2025-09-25T18:19:57.802Z,1758824397.802 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151131/Express0103.lzma.bak 2025-09-25T18:19:57.802Z,1758824397.802 [DataOverHttps](INFO): SBD MOMSN=26075843 2025-09-25T18:19:58.907Z,1758824398.907 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:19:58.908Z,1758824398.908 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:19:58.908Z,1758824398.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:20:04.150Z,1758824404.150 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T18:20:04.228Z,1758824404.228 [NAL9602](ERROR): received: +CSQ:0 OK814, 0, 0, 0, 0 OK 2025-09-25T18:22:04.533Z,1758824524.533 [CommandExec](IMPORTANT): got command quit 2025-09-25T18:22:05.540Z,1758824525.540 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:05.540Z,1758824525.540 [CommandExec](INFO): Uninitializing the command executive. 2025-09-25T18:22:05.541Z,1758824525.541 [CommandExec](INFO): Uninitializing the command scheduler. 2025-09-25T18:22:05.541Z,1758824525.541 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:05.608Z,1758824525.608 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-09-25T18:22:05.608Z,1758824525.608 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-09-25T18:22:05.608Z,1758824525.608 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:05.609Z,1758824525.609 [NavChartDb](INFO): Join timeout helper Thread ID is 9928 2025-09-25T18:22:05.720Z,1758824525.720 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:05.721Z,1758824525.721 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:05.728Z,1758824525.728 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-09-25T18:22:05.728Z,1758824525.728 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:05.729Z,1758824525.729 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9929 2025-09-25T18:22:06.188Z,1758824526.188 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:06.188Z,1758824526.188 [WetLabsBB2FL](INFO): Powering down 2025-09-25T18:22:06.189Z,1758824526.189 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:06.196Z,1758824526.196 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-09-25T18:22:06.196Z,1758824526.196 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:06.197Z,1758824526.197 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9930 2025-09-25T18:22:07.008Z,1758824527.008 [CTD_Seabird](INFO): Powering down 2025-09-25T18:22:07.020Z,1758824527.020 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:07.020Z,1758824527.020 [CTD_Seabird](INFO): Powering down 2025-09-25T18:22:07.032Z,1758824527.032 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:07.041Z,1758824527.041 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-09-25T18:22:07.041Z,1758824527.041 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:07.043Z,1758824527.043 [Radio_Surface](INFO): Join timeout helper Thread ID is 9931 2025-09-25T18:22:07.244Z,1758824527.244 [Radio_Surface](INFO): Powering down 2025-09-25T18:22:07.245Z,1758824527.245 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:07.245Z,1758824527.245 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:07.260Z,1758824527.260 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-09-25T18:22:07.260Z,1758824527.260 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:07.261Z,1758824527.261 [Onboard](INFO): Join timeout helper Thread ID is 9932 2025-09-25T18:22:09.944Z,1758824529.944 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:09.944Z,1758824529.944 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:09.953Z,1758824529.953 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-09-25T18:22:09.953Z,1758824529.953 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:09.954Z,1758824529.954 [DataOverHttps](INFO): Join timeout helper Thread ID is 9933 2025-09-25T18:22:10.141Z,1758824530.141 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:10.145Z,1758824530.145 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.153Z,1758824530.153 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-09-25T18:22:10.153Z,1758824530.153 [DAT ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.153Z,1758824530.153 [DAT](INFO): Join timeout helper Thread ID is 9934 2025-09-25T18:22:10.356Z,1758824530.356 [DAT](INFO): Powering down 2025-09-25T18:22:10.425Z,1758824530.425 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:10.425Z,1758824530.425 [DAT](INFO): Powering down 2025-09-25T18:22:10.426Z,1758824530.426 [DAT ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.443Z,1758824530.443 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-09-25T18:22:10.443Z,1758824530.443 [logger ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.444Z,1758824530.444 [logger](INFO): Join timeout helper Thread ID is 9935 2025-09-25T18:22:10.465Z,1758824530.465 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:10.466Z,1758824530.466 [logger ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.467Z,1758824530.467 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-09-25T18:22:10.467Z,1758824530.467 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.468Z,1758824530.468 [CommandLine](INFO): Join timeout helper Thread ID is 9936 2025-09-25T18:22:10.492Z,1758824530.492 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:10.492Z,1758824530.492 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.509Z,1758824530.509 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-09-25T18:22:10.509Z,1758824530.509 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.509Z,1758824530.509 [CommandExec](INFO): Join timeout helper Thread ID is 9937 2025-09-25T18:22:10.510Z,1758824530.510 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-09-25T18:22:10.510Z,1758824530.510 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.511Z,1758824530.511 [controlThread](INFO): Join timeout helper Thread ID is 9938 2025-09-25T18:22:10.576Z,1758824530.576 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:22:10.576Z,1758824530.576 [controlThread](DEBUG): Uninitializing ControlThread 2025-09-25T18:22:10.577Z,1758824530.577 [AHRS_M2](INFO): Powering down 2025-09-25T18:22:10.656Z,1758824530.656 [NAL9602](INFO): Powering down 2025-09-25T18:22:10.658Z,1758824530.658 [Sonardyne_Nano](INFO): Powering down 2025-09-25T18:22:10.864Z,1758824530.864 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-09-25T18:22:10.865Z,1758824530.865 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-09-25T18:22:10.865Z,1758824530.865 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-09-25T18:22:10.866Z,1758824530.866 [MissionManager](INFO): Uninitializing Mission Default 2025-09-25T18:22:10.866Z,1758824530.866 [Default] Stopped 2025-09-25T18:22:10.866Z,1758824530.866 [Default](DEBUG): Aggregate::uninitialize Default 2025-09-25T18:22:10.866Z,1758824530.866 [Default:B.GoToSurface] Stopped 2025-09-25T18:22:10.866Z,1758824530.866 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-25T18:22:10.866Z,1758824530.866 [Default:CheckIn] Stopped 2025-09-25T18:22:10.867Z,1758824530.867 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:22:10.867Z,1758824530.867 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:22:10.867Z,1758824530.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:22:10.870Z,1758824530.870 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-09-25T18:22:10.870Z,1758824530.870 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-09-25T18:22:10.870Z,1758824530.870 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-09-25T18:22:10.871Z,1758824530.871 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-09-25T18:22:10.871Z,1758824530.871 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-09-25T18:22:10.871Z,1758824530.871 [BuoyancyServo](INFO): Powering down 2025-09-25T18:22:10.884Z,1758824530.884 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-09-25T18:22:10.884Z,1758824530.884 [ElevatorServo](INFO): Powering down 2025-09-25T18:22:10.885Z,1758824530.885 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-09-25T18:22:10.885Z,1758824530.885 [MassServo](INFO): Powering down 2025-09-25T18:22:10.886Z,1758824530.886 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-09-25T18:22:10.886Z,1758824530.886 [RudderServo](INFO): Powering down 2025-09-25T18:22:10.887Z,1758824530.887 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-09-25T18:22:10.887Z,1758824530.887 [ThrusterHE](INFO): Powering down 2025-09-25T18:22:10.888Z,1758824530.888 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-09-25T18:22:10.888Z,1758824530.888 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-09-25T18:22:10.888Z,1758824530.888 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-09-25T18:22:10.889Z,1758824530.889 [CBIT](DEBUG): Powering off loads. 2025-09-25T18:22:10.900Z,1758824530.900 [CBIT](DEBUG): Disabling WDT. 2025-09-25T18:22:10.912Z,1758824530.912 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-09-25T18:22:10.912Z,1758824530.912 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-09-25T18:22:10.913Z,1758824530.913 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.976Z,1758824530.976 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.979Z,1758824530.979 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.986Z,1758824530.986 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:10.995Z,1758824530.995 [DAT ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:11.068Z,1758824531.068 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:11.072Z,1758824531.072 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:11.096Z,1758824531.096 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-09-25T18:22:11.097Z,1758824531.097 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-09-25T18:22:11.117Z,1758824531.117 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:22:11.231Z,1758824531.231 [logger ThreadHandler](INFO): Thread cancelled.