2024-01-24T17:26:39.241Z,1706117199.241 [CommandExec](IMPORTANT): got command restart logs 2024-01-24T17:27:33.708Z,1706117253.708 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-01-24T17:29:59.095Z,1706117399.095 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T17:30:03.157Z,1706117403.157 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T17:30:03.157Z,1706117403.157 [Default:CheckIn:C.Wait] Stopped 2024-01-24T17:30:03.157Z,1706117403.157 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T17:30:03.157Z,1706117403.157 [Default:CheckIn:D] Running Loop=1 2024-01-24T17:30:03.546Z,1706117403.546 [Default:CheckIn:D] Stopped 2024-01-24T17:30:03.546Z,1706117403.546 [Default:CheckIn:E] Running Loop=1 2024-01-24T17:30:03.955Z,1706117403.955 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.064675 min 2024-01-24T17:30:03.955Z,1706117403.955 [Default:CheckIn:E] Stopped 2024-01-24T17:30:03.956Z,1706117403.956 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T17:30:03.956Z,1706117403.956 [Default:CheckIn] Stopped 2024-01-24T17:30:03.956Z,1706117403.956 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T17:30:03.956Z,1706117403.956 [Default:CheckIn](INFO): Running loop #4 2024-01-24T17:30:03.956Z,1706117403.956 [Default:CheckIn] Running Loop=4 2024-01-24T17:30:03.956Z,1706117403.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T17:30:03.957Z,1706117403.957 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T17:30:05.963Z,1706117405.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173006.00,A,3648.10748,N,12150.40154,W,14.540,79.26,240124,,,D*75 2024-01-24T17:30:05.965Z,1706117405.965 [NAL9602](INFO): GPS fix at 20240124T173006: (36.801791, -121.840026) 2024-01-24T17:30:05.977Z,1706117405.977 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T17:30:05.977Z,1706117405.977 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T17:30:08.877Z,1706117408.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:30:24.546Z,1706117424.546 [NAL9602](INFO): SBD MO Status=1, MOMSN=46888, MT Status=0, MTMSN=0 2024-01-24T17:30:24.546Z,1706117424.546 [NAL9602](INFO): No messages in MT queue 2024-01-24T17:30:52.003Z,1706117452.003 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240123T235107/Courier0090.lzma 2024-01-24T17:30:53.006Z,1706117453.006 [DataOverHttps](INFO): Moved sent file to Logs/20240123T235107/Courier0090.lzma.bak 2024-01-24T17:30:53.006Z,1706117453.006 [DataOverHttps](INFO): SBD MOMSN=19208801 2024-01-24T17:30:55.281Z,1706117455.281 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T17:31:30.461Z,1706117490.461 [CBIT](IMPORTANT): Beginning ground fault scan 2024-01-24T17:31:33.281Z,1706117493.281 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-01-24T17:31:40.948Z,1706117500.948 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240124T172639/Courier0000.lzma 2024-01-24T17:31:41.371Z,1706117501.371 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002401 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002006 CHAN A3 (5V): -0.001895 CHAN B0 (3.3V): -0.000249 CHAN B1 (3.15aV): -0.000261 CHAN B2 (3.15bV): -0.000234 CHAN B3 (GND): 0.000155 OPEN: -0.000591 Full Scale: +/- 1 mA 2024-01-24T17:31:41.950Z,1706117501.950 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0000.lzma.bak 2024-01-24T17:31:41.950Z,1706117501.950 [DataOverHttps](INFO): SBD MOMSN=19208803 2024-01-24T17:32:29.631Z,1706117549.631 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20240123T235107/Express0091.lzma 2024-01-24T17:32:30.634Z,1706117550.634 [DataOverHttps](INFO): Moved sent file to Logs/20240123T235107/Express0091.lzma.bak 2024-01-24T17:32:30.634Z,1706117550.634 [DataOverHttps](INFO): SBD MOMSN=19208807 2024-01-24T17:32:48.460Z,1706117568.460 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20240124T172639/Express0001.lzma 2024-01-24T17:32:49.462Z,1706117569.462 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0001.lzma.bak 2024-01-24T17:32:49.462Z,1706117569.462 [DataOverHttps](INFO): SBD MOMSN=19208810 2024-01-24T17:33:10.379Z,1706117590.379 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240124T172639/Express0004.lzma 2024-01-24T17:33:11.381Z,1706117591.381 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0004.lzma.bak 2024-01-24T17:33:11.382Z,1706117591.382 [DataOverHttps](INFO): SBD MOMSN=19208814 2024-01-24T17:33:12.705Z,1706117592.705 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T17:33:12.705Z,1706117592.705 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T17:33:12.705Z,1706117592.705 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T17:36:15.466Z,1706117775.466 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:36:46.596Z,1706117806.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:37:17.736Z,1706117837.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:37:48.860Z,1706117868.860 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:38:13.315Z,1706117893.315 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T17:38:13.315Z,1706117893.315 [Default:CheckIn:C.Wait] Stopped 2024-01-24T17:38:13.315Z,1706117893.315 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T17:38:13.324Z,1706117893.324 [Default:CheckIn:D] Running Loop=1 2024-01-24T17:38:13.736Z,1706117893.736 [Default:CheckIn:D] Stopped 2024-01-24T17:38:13.737Z,1706117893.737 [Default:CheckIn:E] Running Loop=1 2024-01-24T17:38:14.122Z,1706117894.122 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.234511 min 2024-01-24T17:38:14.122Z,1706117894.122 [Default:CheckIn:E] Stopped 2024-01-24T17:38:14.122Z,1706117894.122 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T17:38:14.122Z,1706117894.122 [Default:CheckIn] Stopped 2024-01-24T17:38:14.123Z,1706117894.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T17:38:14.123Z,1706117894.123 [Default:CheckIn](INFO): Running loop #5 2024-01-24T17:38:14.123Z,1706117894.123 [Default:CheckIn] Running Loop=5 2024-01-24T17:38:14.123Z,1706117894.123 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T17:38:14.123Z,1706117894.123 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T17:38:16.139Z,1706117896.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173816.00,A,3648.49058,N,12149.60118,W,0.914,279.91,240124,,,D*72 2024-01-24T17:38:16.141Z,1706117896.141 [NAL9602](INFO): GPS fix at 20240124T173816: (36.808176, -121.826686) 2024-01-24T17:38:16.153Z,1706117896.153 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T17:38:16.153Z,1706117896.153 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T17:38:20.012Z,1706117900.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:38:25.033Z,1706117905.033 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.775299 2024-01-24T17:38:30.273Z,1706117910.273 [NAL9602](INFO): SBD MO Status=1, MOMSN=46889, MT Status=0, MTMSN=0 2024-01-24T17:38:30.273Z,1706117910.273 [NAL9602](INFO): No messages in MT queue 2024-01-24T17:38:40.027Z,1706117920.027 [NAL9602](INFO): SBD MO Status=1, MOMSN=46890, MT Status=0, MTMSN=0 2024-01-24T17:38:40.076Z,1706117920.076 [NAL9602](INFO): Sent 72 bytes from file Logs/20240124T172639/Courier0006.lzma 2024-01-24T17:38:40.076Z,1706117920.076 [NAL9602](INFO): Packets left to send: 0 2024-01-24T17:38:53.506Z,1706117933.506 [NAL9602](INFO): SBD MO Status=1, MOMSN=46891, MT Status=0, MTMSN=0 2024-01-24T17:38:53.576Z,1706117933.576 [NAL9602](INFO): Sent 332 bytes from file Logs/20240124T172639/Express0007.lzma 2024-01-24T17:38:53.576Z,1706117933.576 [NAL9602](INFO): Packets left to send: 1 2024-01-24T17:38:56.156Z,1706117936.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:39:11.339Z,1706117951.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=46892, MT Status=2, MTMSN=0 2024-01-24T17:39:11.339Z,1706117951.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T17:39:27.292Z,1706117967.292 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:39:38.442Z,1706117978.442 [NAL9602](INFO): SBD MO Status=1, MOMSN=46892, MT Status=0, MTMSN=0 2024-01-24T17:39:38.500Z,1706117978.500 [NAL9602](INFO): Sent 135 bytes from file Logs/20240124T172639/Express0007.lzma 2024-01-24T17:39:38.500Z,1706117978.500 [NAL9602](INFO): Packets left to send: 0 2024-01-24T17:39:45.310Z,1706117985.310 [NAL9602](INFO): SBD MO Status=0, MOMSN=46893, MT Status=0, MTMSN=0 2024-01-24T17:39:45.407Z,1706117985.407 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T17:39:45.407Z,1706117985.407 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T17:39:45.407Z,1706117985.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T17:39:59.446Z,1706117999.446 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T17:40:16.017Z,1706118016.017 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T17:40:29.852Z,1706118029.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:41:01.004Z,1706118061.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:41:32.140Z,1706118092.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:42:03.264Z,1706118123.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:42:34.388Z,1706118154.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:43:05.512Z,1706118185.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:43:35.365Z,1706118215.365 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003463 2024-01-24T17:44:45.917Z,1706118285.917 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T17:44:45.917Z,1706118285.917 [Default:CheckIn:C.Wait] Stopped 2024-01-24T17:44:45.917Z,1706118285.917 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T17:44:45.918Z,1706118285.918 [Default:CheckIn:D] Running Loop=1 2024-01-24T17:44:46.330Z,1706118286.330 [Default:CheckIn:D] Stopped 2024-01-24T17:44:46.330Z,1706118286.330 [Default:CheckIn:E] Running Loop=1 2024-01-24T17:44:46.744Z,1706118286.744 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.777730 min 2024-01-24T17:44:46.744Z,1706118286.744 [Default:CheckIn:E] Stopped 2024-01-24T17:44:46.744Z,1706118286.744 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T17:44:46.744Z,1706118286.744 [Default:CheckIn] Stopped 2024-01-24T17:44:46.744Z,1706118286.744 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T17:44:46.745Z,1706118286.745 [Default:CheckIn](INFO): Running loop #6 2024-01-24T17:44:46.745Z,1706118286.745 [Default:CheckIn] Running Loop=6 2024-01-24T17:44:46.745Z,1706118286.745 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T17:44:46.745Z,1706118286.745 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T17:44:48.711Z,1706118288.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174449.00,A,3648.53400,N,12149.58728,W,1.225,325.40,240124,,,D*73 2024-01-24T17:44:48.714Z,1706118288.714 [NAL9602](INFO): GPS fix at 20240124T174449: (36.808900, -121.826455) 2024-01-24T17:44:48.725Z,1706118288.725 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T17:44:48.725Z,1706118288.725 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T17:44:59.855Z,1706118299.855 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0009.lzma 2024-01-24T17:45:00.858Z,1706118300.858 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0009.lzma.bak 2024-01-24T17:45:00.858Z,1706118300.858 [DataOverHttps](INFO): SBD MOMSN=19208839 2024-01-24T17:45:07.433Z,1706118307.433 [NAL9602](INFO): SBD MO Status=0, MOMSN=46894, MT Status=0, MTMSN=0 2024-01-24T17:45:07.433Z,1706118307.433 [NAL9602](INFO): No messages in MT queue 2024-01-24T17:45:38.125Z,1706118338.125 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T17:46:09.215Z,1706118369.215 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240124T172639/Express0010.lzma 2024-01-24T17:46:10.218Z,1706118370.218 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0010.lzma.bak 2024-01-24T17:46:10.218Z,1706118370.218 [DataOverHttps](INFO): SBD MOMSN=19208842 2024-01-24T17:46:11.669Z,1706118371.669 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T17:46:11.669Z,1706118371.669 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T17:46:11.669Z,1706118371.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T17:46:54.096Z,1706118414.096 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 6. 2024-01-24T17:46:54.099Z,1706118414.099 [BPC1](INFO): Received data from all battery sticks. 2024-01-24T17:48:45.872Z,1706118525.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:49:17.029Z,1706118557.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:49:37.108Z,1706118577.108 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.163422 2024-01-24T17:49:59.527Z,1706118599.527 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T17:50:18.300Z,1706118618.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:50:49.424Z,1706118649.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:51:12.257Z,1706118672.257 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T17:51:12.257Z,1706118672.257 [Default:CheckIn:C.Wait] Stopped 2024-01-24T17:51:12.257Z,1706118672.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T17:51:12.257Z,1706118672.257 [Default:CheckIn:D] Running Loop=1 2024-01-24T17:51:12.672Z,1706118672.672 [Default:CheckIn:D] Stopped 2024-01-24T17:51:12.673Z,1706118672.673 [Default:CheckIn:E] Running Loop=1 2024-01-24T17:51:13.059Z,1706118673.059 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.216777 min 2024-01-24T17:51:13.059Z,1706118673.059 [Default:CheckIn:E] Stopped 2024-01-24T17:51:13.059Z,1706118673.059 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T17:51:13.059Z,1706118673.059 [Default:CheckIn] Stopped 2024-01-24T17:51:13.059Z,1706118673.059 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T17:51:13.059Z,1706118673.059 [Default:CheckIn](INFO): Running loop #7 2024-01-24T17:51:13.060Z,1706118673.060 [Default:CheckIn] Running Loop=7 2024-01-24T17:51:13.060Z,1706118673.060 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T17:51:13.060Z,1706118673.060 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T17:51:15.070Z,1706118675.070 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175115.00,A,3648.50602,N,12149.60672,W,0.292,339.40,240124,,,A*7D 2024-01-24T17:51:15.073Z,1706118675.073 [NAL9602](INFO): GPS fix at 20240124T175115: (36.808434, -121.826779) 2024-01-24T17:51:15.084Z,1706118675.084 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T17:51:15.084Z,1706118675.084 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T17:51:20.548Z,1706118680.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:51:32.883Z,1706118692.883 [NAL9602](INFO): SBD MO Status=1, MOMSN=46895, MT Status=0, MTMSN=0 2024-01-24T17:51:32.936Z,1706118692.936 [NAL9602](INFO): Sent 203 bytes from file Logs/20240124T172639/Courier0012.lzma 2024-01-24T17:51:32.936Z,1706118692.936 [NAL9602](INFO): Packets left to send: 0 2024-01-24T17:51:41.075Z,1706118701.075 [NAL9602](INFO): SBD MO Status=1, MOMSN=46896, MT Status=0, MTMSN=0 2024-01-24T17:51:41.128Z,1706118701.128 [NAL9602](INFO): Sent 165 bytes from file Logs/20240124T172639/Express0013.lzma 2024-01-24T17:51:41.128Z,1706118701.128 [NAL9602](INFO): Packets left to send: 0 2024-01-24T17:51:48.343Z,1706118708.343 [NAL9602](INFO): SBD MO Status=0, MOMSN=46897, MT Status=0, MTMSN=0 2024-01-24T17:51:48.458Z,1706118708.458 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T17:51:48.458Z,1706118708.458 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T17:51:48.458Z,1706118708.458 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T17:51:51.684Z,1706118711.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:52:19.045Z,1706118739.045 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T17:52:22.808Z,1706118742.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:52:53.944Z,1706118773.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:53:25.068Z,1706118805.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:53:56.192Z,1706118836.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:54:27.320Z,1706118867.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:54:58.452Z,1706118898.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:55:29.584Z,1706118929.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:56:00.740Z,1706118960.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:56:31.864Z,1706118991.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:56:48.931Z,1706119008.931 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T17:56:48.931Z,1706119008.931 [Default:CheckIn:C.Wait] Stopped 2024-01-24T17:56:48.931Z,1706119008.931 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T17:56:48.933Z,1706119008.933 [Default:CheckIn:D] Running Loop=1 2024-01-24T17:56:49.348Z,1706119009.348 [Default:CheckIn:D] Stopped 2024-01-24T17:56:49.348Z,1706119009.348 [Default:CheckIn:E] Running Loop=1 2024-01-24T17:56:49.766Z,1706119009.766 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.828040 min 2024-01-24T17:56:49.766Z,1706119009.766 [Default:CheckIn:E] Stopped 2024-01-24T17:56:49.766Z,1706119009.766 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T17:56:49.766Z,1706119009.766 [Default:CheckIn] Stopped 2024-01-24T17:56:49.766Z,1706119009.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T17:56:49.767Z,1706119009.767 [Default:CheckIn](INFO): Running loop #8 2024-01-24T17:56:49.767Z,1706119009.767 [Default:CheckIn] Running Loop=8 2024-01-24T17:56:49.767Z,1706119009.767 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T17:56:49.767Z,1706119009.767 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T17:56:51.751Z,1706119011.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175652.00,A,3648.52652,N,12149.63454,W,1.749,322.71,240124,,,D*74 2024-01-24T17:56:51.754Z,1706119011.754 [NAL9602](INFO): GPS fix at 20240124T175652: (36.808775, -121.827242) 2024-01-24T17:56:51.765Z,1706119011.765 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T17:56:51.765Z,1706119011.765 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T17:57:02.988Z,1706119022.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:57:27.747Z,1706119047.747 [NAL9602](INFO): SBD MO Status=1, MOMSN=46898, MT Status=0, MTMSN=0 2024-01-24T17:57:27.808Z,1706119047.808 [NAL9602](INFO): Sent 72 bytes from file Logs/20240124T172639/Courier0015.lzma 2024-01-24T17:57:27.808Z,1706119047.808 [NAL9602](INFO): Packets left to send: 0 2024-01-24T17:57:34.112Z,1706119054.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:57:46.082Z,1706119066.082 [NAL9602](INFO): SBD MO Status=1, MOMSN=46899, MT Status=0, MTMSN=0 2024-01-24T17:57:46.136Z,1706119066.136 [NAL9602](INFO): Sent 165 bytes from file Logs/20240124T172639/Express0016.lzma 2024-01-24T17:57:46.136Z,1706119066.136 [NAL9602](INFO): Packets left to send: 0 2024-01-24T17:58:05.236Z,1706119085.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:58:07.090Z,1706119087.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=46900, MT Status=2, MTMSN=0 2024-01-24T17:58:07.090Z,1706119087.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T17:58:12.072Z,1706119092.072 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2024-01-24T17:58:21.662Z,1706119101.662 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2024-01-24T17:58:36.360Z,1706119116.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:58:38.683Z,1706119118.683 [NAL9602](INFO): SBD MO Status=0, MOMSN=46900, MT Status=0, MTMSN=0 2024-01-24T17:58:38.787Z,1706119118.787 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T17:58:38.787Z,1706119118.787 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T17:58:38.787Z,1706119118.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T17:59:07.496Z,1706119147.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:59:09.381Z,1706119149.381 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T17:59:38.620Z,1706119178.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T17:59:59.883Z,1706119199.883 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T18:00:10.053Z,1706119210.053 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003459 2024-01-24T18:00:41.200Z,1706119241.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:03:15.512Z,1706119395.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:03:39.323Z,1706119419.323 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:03:39.323Z,1706119419.323 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:03:39.323Z,1706119419.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:03:39.323Z,1706119419.323 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:03:39.732Z,1706119419.732 [Default:CheckIn:D] Stopped 2024-01-24T18:03:39.732Z,1706119419.732 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:03:40.141Z,1706119420.141 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.667765 min 2024-01-24T18:03:40.141Z,1706119420.141 [Default:CheckIn:E] Stopped 2024-01-24T18:03:40.141Z,1706119420.141 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:03:40.142Z,1706119420.142 [Default:CheckIn] Stopped 2024-01-24T18:03:40.142Z,1706119420.142 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:03:40.142Z,1706119420.142 [Default:CheckIn](INFO): Running loop #9 2024-01-24T18:03:40.142Z,1706119420.142 [Default:CheckIn] Running Loop=9 2024-01-24T18:03:40.142Z,1706119420.142 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:03:40.142Z,1706119420.142 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:03:42.147Z,1706119422.147 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180342.00,A,3648.54298,N,12149.65784,W,1.555,326.37,240124,,,D*7F 2024-01-24T18:03:42.149Z,1706119422.149 [NAL9602](INFO): GPS fix at 20240124T180342: (36.809050, -121.827631) 2024-01-24T18:03:42.176Z,1706119422.176 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:03:42.176Z,1706119422.176 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:03:46.636Z,1706119426.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:03:59.197Z,1706119439.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=46901, MT Status=0, MTMSN=0 2024-01-24T18:03:59.197Z,1706119439.197 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:04:17.784Z,1706119457.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:04:29.897Z,1706119469.897 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:04:48.908Z,1706119488.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:04:58.949Z,1706119498.949 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.751862 2024-01-24T18:05:17.632Z,1706119517.632 [NAL9602](INFO): SBD MO Status=1, MOMSN=46902, MT Status=0, MTMSN=0 2024-01-24T18:05:17.680Z,1706119517.680 [NAL9602](INFO): Sent 73 bytes from file Logs/20240124T172639/Courier0018.lzma 2024-01-24T18:05:17.680Z,1706119517.680 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:05:28.179Z,1706119528.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=46903, MT Status=0, MTMSN=0 2024-01-24T18:05:28.232Z,1706119528.232 [NAL9602](INFO): Sent 168 bytes from file Logs/20240124T172639/Express0019.lzma 2024-01-24T18:05:28.232Z,1706119528.232 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:05:30.072Z,1706119530.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:05:43.931Z,1706119543.931 [NAL9602](INFO): SBD MO Status=0, MOMSN=46904, MT Status=0, MTMSN=0 2024-01-24T18:05:44.024Z,1706119544.024 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:05:44.024Z,1706119544.024 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:05:44.024Z,1706119544.024 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:06:01.196Z,1706119561.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:06:14.637Z,1706119574.637 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:06:32.320Z,1706119592.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:07:03.456Z,1706119623.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:07:34.592Z,1706119654.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:08:05.728Z,1706119685.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:08:36.872Z,1706119716.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:09:07.996Z,1706119747.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:09:30.425Z,1706119770.425 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003427 2024-01-24T18:10:00.154Z,1706119800.154 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T18:10:40.252Z,1706119840.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:10:44.607Z,1706119844.607 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:10:44.608Z,1706119844.608 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:10:44.608Z,1706119844.608 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:10:44.608Z,1706119844.608 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:10:45.019Z,1706119845.019 [Default:CheckIn:D] Stopped 2024-01-24T18:10:45.019Z,1706119845.019 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:10:45.425Z,1706119845.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.755888 min 2024-01-24T18:10:45.425Z,1706119845.425 [Default:CheckIn:E] Stopped 2024-01-24T18:10:45.425Z,1706119845.425 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:10:45.425Z,1706119845.425 [Default:CheckIn] Stopped 2024-01-24T18:10:45.426Z,1706119845.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:10:45.426Z,1706119845.426 [Default:CheckIn](INFO): Running loop #10 2024-01-24T18:10:45.426Z,1706119845.426 [Default:CheckIn] Running Loop=10 2024-01-24T18:10:45.426Z,1706119845.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:10:45.426Z,1706119845.426 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:10:47.422Z,1706119847.422 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181048.00,A,3648.54053,N,12149.67045,W,0.447,14.14,240124,,,A*4F 2024-01-24T18:10:47.424Z,1706119847.424 [NAL9602](INFO): GPS fix at 20240124T181048: (36.809009, -121.827841) 2024-01-24T18:10:47.435Z,1706119847.435 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:10:47.435Z,1706119847.435 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:11:00.354Z,1706119860.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=46905, MT Status=0, MTMSN=0 2024-01-24T18:11:00.355Z,1706119860.355 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:11:11.376Z,1706119871.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:11:31.057Z,1706119891.057 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:11:42.500Z,1706119902.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:12:13.624Z,1706119933.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:12:44.756Z,1706119964.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:12:47.769Z,1706119967.769 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.727844 2024-01-24T18:13:04.823Z,1706119984.823 [NAL9602](INFO): SBD MO Status=1, MOMSN=46906, MT Status=0, MTMSN=0 2024-01-24T18:13:04.880Z,1706119984.880 [NAL9602](INFO): Sent 71 bytes from file Logs/20240124T172639/Courier0021.lzma 2024-01-24T18:13:04.880Z,1706119984.880 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:13:18.651Z,1706119998.651 [NAL9602](INFO): SBD MO Status=2, MOMSN=46907, MT Status=2, MTMSN=0 2024-01-24T18:13:18.651Z,1706119998.651 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T18:13:18.904Z,1706119998.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:13:30.818Z,1706120010.818 [NAL9602](INFO): SBD MO Status=1, MOMSN=46907, MT Status=0, MTMSN=0 2024-01-24T18:13:30.872Z,1706120010.872 [NAL9602](INFO): Sent 163 bytes from file Logs/20240124T172639/Express0022.lzma 2024-01-24T18:13:30.872Z,1706120010.872 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:13:38.895Z,1706120018.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=46908, MT Status=0, MTMSN=0 2024-01-24T18:13:38.973Z,1706120018.973 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:13:38.973Z,1706120018.973 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:13:38.973Z,1706120018.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:13:50.040Z,1706120030.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:14:09.592Z,1706120049.592 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:14:19.405Z,1706120059.405 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003452 2024-01-24T18:16:53.872Z,1706120213.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:17:24.996Z,1706120244.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:18:27.680Z,1706120307.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:18:39.474Z,1706120319.474 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:18:39.475Z,1706120319.475 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:18:39.475Z,1706120319.475 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:18:39.475Z,1706120319.475 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:18:39.885Z,1706120319.885 [Default:CheckIn:D] Stopped 2024-01-24T18:18:39.885Z,1706120319.885 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:18:40.285Z,1706120320.285 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.670321 min 2024-01-24T18:18:40.285Z,1706120320.285 [Default:CheckIn:E] Stopped 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn] Stopped 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn](INFO): Running loop #11 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn] Running Loop=11 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:18:40.286Z,1706120320.286 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:18:42.299Z,1706120322.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181843.00,A,3648.55430,N,12149.58740,W,3.110,106.14,240124,,,D*71 2024-01-24T18:18:42.311Z,1706120322.311 [NAL9602](INFO): GPS fix at 20240124T181843: (36.809238, -121.826457) 2024-01-24T18:18:42.322Z,1706120322.322 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:18:42.322Z,1706120322.322 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:18:58.054Z,1706120338.054 [NAL9602](INFO): SBD MO Status=0, MOMSN=46909, MT Status=0, MTMSN=0 2024-01-24T18:18:58.054Z,1706120338.054 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:18:58.816Z,1706120338.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:19:01.828Z,1706120341.828 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.219421 2024-01-24T18:19:26.371Z,1706120366.371 [NAL9602](INFO): SBD MO Status=1, MOMSN=46910, MT Status=0, MTMSN=0 2024-01-24T18:19:26.426Z,1706120366.426 [NAL9602](INFO): Sent 73 bytes from file Logs/20240124T172639/Courier0024.lzma 2024-01-24T18:19:26.426Z,1706120366.426 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:19:32.960Z,1706120372.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:19:38.127Z,1706120378.127 [NAL9602](INFO): SBD MO Status=1, MOMSN=46911, MT Status=0, MTMSN=0 2024-01-24T18:19:38.180Z,1706120378.180 [NAL9602](INFO): Sent 170 bytes from file Logs/20240124T172639/Express0025.lzma 2024-01-24T18:19:38.180Z,1706120378.180 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:19:47.014Z,1706120387.014 [NAL9602](INFO): SBD MO Status=0, MOMSN=46912, MT Status=0, MTMSN=0 2024-01-24T18:19:47.103Z,1706120387.103 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:19:47.104Z,1706120387.104 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:19:47.104Z,1706120387.104 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:20:00.347Z,1706120400.347 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T18:20:04.084Z,1706120404.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:20:17.722Z,1706120417.722 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:20:35.220Z,1706120435.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:21:06.356Z,1706120466.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:21:37.492Z,1706120497.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:22:08.616Z,1706120528.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:22:39.752Z,1706120559.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:23:10.896Z,1706120590.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:23:42.020Z,1706120622.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:24:13.144Z,1706120653.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:24:44.272Z,1706120684.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:24:47.598Z,1706120687.598 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:24:47.599Z,1706120687.599 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:24:47.599Z,1706120687.599 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:24:47.599Z,1706120687.599 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:24:48.007Z,1706120688.007 [Default:CheckIn:D] Stopped 2024-01-24T18:24:48.007Z,1706120688.007 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:24:48.411Z,1706120688.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.805688 min 2024-01-24T18:24:48.411Z,1706120688.411 [Default:CheckIn:E] Stopped 2024-01-24T18:24:48.411Z,1706120688.411 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:24:48.411Z,1706120688.411 [Default:CheckIn] Stopped 2024-01-24T18:24:48.411Z,1706120688.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:24:48.412Z,1706120688.412 [Default:CheckIn](INFO): Running loop #12 2024-01-24T18:24:48.412Z,1706120688.412 [Default:CheckIn] Running Loop=12 2024-01-24T18:24:48.412Z,1706120688.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:24:48.412Z,1706120688.412 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:24:50.423Z,1706120690.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182451.00,A,3648.47493,N,12149.17466,W,3.868,100.64,240124,,,D*7C 2024-01-24T18:24:50.425Z,1706120690.425 [NAL9602](INFO): GPS fix at 20240124T182451: (36.807916, -121.819578) 2024-01-24T18:24:50.436Z,1706120690.436 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:24:50.436Z,1706120690.436 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:25:15.397Z,1706120715.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:25:15.918Z,1706120715.918 [NAL9602](INFO): SBD MO Status=1, MOMSN=46913, MT Status=0, MTMSN=0 2024-01-24T18:25:15.972Z,1706120715.972 [NAL9602](INFO): Sent 73 bytes from file Logs/20240124T172639/Courier0027.lzma 2024-01-24T18:25:15.973Z,1706120715.973 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:25:25.726Z,1706120725.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=46914, MT Status=0, MTMSN=0 2024-01-24T18:25:25.780Z,1706120725.780 [NAL9602](INFO): Sent 164 bytes from file Logs/20240124T172639/Express0028.lzma 2024-01-24T18:25:25.780Z,1706120725.780 [NAL9602](INFO): Packets left to send: 0 2024-01-24T18:25:37.451Z,1706120737.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=46915, MT Status=0, MTMSN=0 2024-01-24T18:25:37.522Z,1706120737.522 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:25:37.522Z,1706120737.522 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:25:37.522Z,1706120737.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:25:46.528Z,1706120746.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:26:08.149Z,1706120768.149 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:26:17.652Z,1706120777.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:26:48.788Z,1706120808.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:27:19.916Z,1706120839.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:27:51.072Z,1706120871.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:28:02.581Z,1706120882.581 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003605 2024-01-24T18:30:00.451Z,1706121000.451 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T18:30:38.027Z,1706121038.027 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:30:38.027Z,1706121038.027 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:30:38.027Z,1706121038.027 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:30:38.028Z,1706121038.028 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:30:38.443Z,1706121038.443 [Default:CheckIn:D] Stopped 2024-01-24T18:30:38.443Z,1706121038.443 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.646289 min 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn:E] Stopped 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn] Stopped 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn](INFO): Running loop #13 2024-01-24T18:30:38.837Z,1706121038.837 [Default:CheckIn] Running Loop=13 2024-01-24T18:30:38.838Z,1706121038.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:30:38.838Z,1706121038.838 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:30:40.847Z,1706121040.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183041.00,A,3648.41792,N,12148.77521,W,2.721,110.82,240124,,,D*73 2024-01-24T18:30:40.850Z,1706121040.850 [NAL9602](INFO): GPS fix at 20240124T183041: (36.806965, -121.812920) 2024-01-24T18:30:40.861Z,1706121040.861 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:30:40.861Z,1706121040.861 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:30:54.596Z,1706121054.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:31:04.278Z,1706121064.278 [NAL9602](INFO): SBD MO Status=0, MOMSN=46916, MT Status=0, MTMSN=0 2024-01-24T18:31:04.279Z,1706121064.279 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:31:25.720Z,1706121085.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:31:34.985Z,1706121094.985 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:31:56.852Z,1706121116.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:32:27.980Z,1706121147.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-01-24T18:32:35.299Z,1706121155.299 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0030.lzma 2024-01-24T18:32:36.302Z,1706121156.302 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0030.lzma.bak 2024-01-24T18:32:36.302Z,1706121156.302 [DataOverHttps](INFO): SBD MOMSN=19208925 2024-01-24T18:32:54.627Z,1706121174.627 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20240124T172639/Express0031.lzma 2024-01-24T18:32:55.630Z,1706121175.630 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0031.lzma.bak 2024-01-24T18:32:55.630Z,1706121175.630 [DataOverHttps](INFO): SBD MOMSN=19208928 2024-01-24T18:32:57.080Z,1706121177.080 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:32:57.081Z,1706121177.081 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:32:57.081Z,1706121177.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:37:57.736Z,1706121477.736 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:37:57.736Z,1706121477.736 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:37:57.736Z,1706121477.736 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:37:57.736Z,1706121477.736 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:37:58.131Z,1706121478.131 [Default:CheckIn:D] Stopped 2024-01-24T18:37:58.131Z,1706121478.131 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:37:58.530Z,1706121478.530 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.974422 min 2024-01-24T18:37:58.530Z,1706121478.530 [Default:CheckIn:E] Stopped 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn] Stopped 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn](INFO): Running loop #14 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn] Running Loop=14 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:37:58.531Z,1706121478.531 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:38:00.551Z,1706121480.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183801.00,A,3648.34835,N,12148.26056,W,4.063,92.07,240124,,,D*4F 2024-01-24T18:38:00.553Z,1706121480.553 [NAL9602](INFO): GPS fix at 20240124T183801: (36.805806, -121.804343) 2024-01-24T18:38:00.564Z,1706121480.564 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:38:00.564Z,1706121480.564 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:38:07.663Z,1706121487.663 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0033.lzma 2024-01-24T18:38:08.666Z,1706121488.666 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0033.lzma.bak 2024-01-24T18:38:08.666Z,1706121488.666 [DataOverHttps](INFO): SBD MOMSN=19208933 2024-01-24T18:38:21.954Z,1706121501.954 [NAL9602](INFO): SBD MO Status=0, MOMSN=46917, MT Status=0, MTMSN=0 2024-01-24T18:38:21.955Z,1706121501.955 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:38:24.523Z,1706121504.523 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20240124T172639/Express0034.lzma 2024-01-24T18:38:25.526Z,1706121505.526 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0034.lzma.bak 2024-01-24T18:38:25.526Z,1706121505.526 [DataOverHttps](INFO): SBD MOMSN=19208936 2024-01-24T18:38:26.811Z,1706121506.811 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:38:26.811Z,1706121506.811 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:38:26.811Z,1706121506.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:38:52.657Z,1706121532.657 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:40:00.541Z,1706121600.541 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T18:43:27.408Z,1706121807.408 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:43:27.408Z,1706121807.408 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:43:27.409Z,1706121807.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:43:27.409Z,1706121807.409 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:43:27.828Z,1706121807.828 [Default:CheckIn:D] Stopped 2024-01-24T18:43:27.828Z,1706121807.828 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.469295 min 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn:E] Stopped 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn] Stopped 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn](INFO): Running loop #15 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn] Running Loop=15 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:43:28.238Z,1706121808.238 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:43:30.227Z,1706121810.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184330.00,A,3648.30692,N,12147.88784,W,2.799,102.64,240124,,,D*7C 2024-01-24T18:43:30.229Z,1706121810.229 [NAL9602](INFO): GPS fix at 20240124T184330: (36.805115, -121.798131) 2024-01-24T18:43:30.261Z,1706121810.261 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:43:30.261Z,1706121810.261 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:43:37.167Z,1706121817.167 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0036.lzma 2024-01-24T18:43:38.170Z,1706121818.170 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0036.lzma.bak 2024-01-24T18:43:38.170Z,1706121818.170 [DataOverHttps](INFO): SBD MOMSN=19208946 2024-01-24T18:43:53.975Z,1706121833.975 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20240124T172639/Express0037.lzma 2024-01-24T18:43:54.978Z,1706121834.978 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0037.lzma.bak 2024-01-24T18:43:54.978Z,1706121834.978 [DataOverHttps](INFO): SBD MOMSN=19208949 2024-01-24T18:43:56.501Z,1706121836.501 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:43:56.501Z,1706121836.501 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:43:56.501Z,1706121836.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:44:11.465Z,1706121851.465 [NAL9602](INFO): SBD MO Status=0, MOMSN=46918, MT Status=0, MTMSN=0 2024-01-24T18:44:11.466Z,1706121851.466 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:44:42.133Z,1706121882.133 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:48:57.209Z,1706122137.209 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:48:57.209Z,1706122137.209 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:48:57.209Z,1706122137.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:48:57.209Z,1706122137.209 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:48:57.607Z,1706122137.607 [Default:CheckIn:D] Stopped 2024-01-24T18:48:57.607Z,1706122137.607 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:48:58.021Z,1706122138.021 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.965682 min 2024-01-24T18:48:58.021Z,1706122138.021 [Default:CheckIn:E] Stopped 2024-01-24T18:48:58.021Z,1706122138.021 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:48:58.021Z,1706122138.021 [Default:CheckIn] Stopped 2024-01-24T18:48:58.021Z,1706122138.021 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:48:58.022Z,1706122138.022 [Default:CheckIn](INFO): Running loop #16 2024-01-24T18:48:58.022Z,1706122138.022 [Default:CheckIn] Running Loop=16 2024-01-24T18:48:58.022Z,1706122138.022 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:48:58.022Z,1706122138.022 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:49:00.046Z,1706122140.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184900.00,A,3648.31769,N,12147.54654,W,2.410,67.27,240124,,,D*4B 2024-01-24T18:49:00.048Z,1706122140.048 [NAL9602](INFO): GPS fix at 20240124T184900: (36.805295, -121.792442) 2024-01-24T18:49:00.083Z,1706122140.083 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:49:00.083Z,1706122140.083 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:49:07.311Z,1706122147.311 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20240124T172639/Courier0039.lzma 2024-01-24T18:49:08.314Z,1706122148.314 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0039.lzma.bak 2024-01-24T18:49:08.314Z,1706122148.314 [DataOverHttps](INFO): SBD MOMSN=19208953 2024-01-24T18:49:24.455Z,1706122164.455 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20240124T172639/Express0040.lzma 2024-01-24T18:49:25.470Z,1706122165.470 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0040.lzma.bak 2024-01-24T18:49:25.470Z,1706122165.470 [DataOverHttps](INFO): SBD MOMSN=19208956 2024-01-24T18:49:25.878Z,1706122165.878 [NAL9602](INFO): SBD MO Status=0, MOMSN=46919, MT Status=0, MTMSN=0 2024-01-24T18:49:25.878Z,1706122165.878 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:49:26.711Z,1706122166.711 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:49:26.711Z,1706122166.711 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:49:26.711Z,1706122166.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:49:56.585Z,1706122196.585 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T18:50:00.627Z,1706122200.627 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T18:51:51.323Z,1706122311.323 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 6. 2024-01-24T18:51:51.325Z,1706122311.325 [BPC1](INFO): Received data from all battery sticks. 2024-01-24T18:54:27.290Z,1706122467.290 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T18:54:27.290Z,1706122467.290 [Default:CheckIn:C.Wait] Stopped 2024-01-24T18:54:27.290Z,1706122467.290 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T18:54:27.290Z,1706122467.290 [Default:CheckIn:D] Running Loop=1 2024-01-24T18:54:27.675Z,1706122467.675 [Default:CheckIn:D] Stopped 2024-01-24T18:54:27.676Z,1706122467.676 [Default:CheckIn:E] Running Loop=1 2024-01-24T18:54:28.085Z,1706122468.085 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.466829 min 2024-01-24T18:54:28.085Z,1706122468.085 [Default:CheckIn:E] Stopped 2024-01-24T18:54:28.085Z,1706122468.085 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T18:54:28.085Z,1706122468.085 [Default:CheckIn] Stopped 2024-01-24T18:54:28.085Z,1706122468.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T18:54:28.086Z,1706122468.086 [Default:CheckIn](INFO): Running loop #17 2024-01-24T18:54:28.086Z,1706122468.086 [Default:CheckIn] Running Loop=17 2024-01-24T18:54:28.086Z,1706122468.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T18:54:28.086Z,1706122468.086 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T18:54:30.091Z,1706122470.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185430.00,A,3648.45170,N,12147.30336,W,0.447,36.52,240124,,,D*4C 2024-01-24T18:54:30.094Z,1706122470.094 [NAL9602](INFO): GPS fix at 20240124T185430: (36.807528, -121.788389) 2024-01-24T18:54:30.105Z,1706122470.105 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T18:54:30.105Z,1706122470.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T18:54:38.007Z,1706122478.007 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240124T172639/Courier0042.lzma 2024-01-24T18:54:39.010Z,1706122479.010 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0042.lzma.bak 2024-01-24T18:54:39.010Z,1706122479.010 [DataOverHttps](INFO): SBD MOMSN=19208967 2024-01-24T18:54:58.115Z,1706122498.115 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20240124T172639/Express0043.lzma 2024-01-24T18:54:59.118Z,1706122499.118 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0043.lzma.bak 2024-01-24T18:54:59.118Z,1706122499.118 [DataOverHttps](INFO): SBD MOMSN=19208970 2024-01-24T18:55:00.443Z,1706122500.443 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T18:55:00.443Z,1706122500.443 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T18:55:00.443Z,1706122500.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T18:55:36.794Z,1706122536.794 [NAL9602](INFO): SBD MO Status=0, MOMSN=46920, MT Status=0, MTMSN=0 2024-01-24T18:55:36.795Z,1706122536.795 [NAL9602](INFO): No messages in MT queue 2024-01-24T18:56:07.501Z,1706122567.501 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:00:01.015Z,1706122801.015 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T19:00:01.056Z,1706122801.056 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:00:01.057Z,1706122801.057 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:00:01.057Z,1706122801.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:00:01.057Z,1706122801.057 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:00:01.433Z,1706122801.433 [Default:CheckIn:D] Stopped 2024-01-24T19:00:01.433Z,1706122801.433 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.029451 min 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn:E] Stopped 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn] Stopped 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn](INFO): Running loop #18 2024-01-24T19:00:01.840Z,1706122801.840 [Default:CheckIn] Running Loop=18 2024-01-24T19:00:01.841Z,1706122801.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:00:01.841Z,1706122801.841 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:00:03.843Z,1706122803.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190004.00,A,3648.39454,N,12147.12699,W,3.499,180.21,240124,,,D*7B 2024-01-24T19:00:03.845Z,1706122803.845 [NAL9602](INFO): GPS fix at 20240124T190004: (36.806576, -121.785450) 2024-01-24T19:00:03.886Z,1706122803.886 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:00:03.887Z,1706122803.886 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:00:10.795Z,1706122810.795 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240124T172639/Courier0045.lzma 2024-01-24T19:00:11.798Z,1706122811.798 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0045.lzma.bak 2024-01-24T19:00:11.798Z,1706122811.798 [DataOverHttps](INFO): SBD MOMSN=19208974 2024-01-24T19:00:28.175Z,1706122828.175 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20240124T172639/Express0046.lzma 2024-01-24T19:00:29.178Z,1706122829.178 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0046.lzma.bak 2024-01-24T19:00:29.178Z,1706122829.178 [DataOverHttps](INFO): SBD MOMSN=19208977 2024-01-24T19:00:30.550Z,1706122830.550 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:00:30.550Z,1706122830.550 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:00:30.550Z,1706122830.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:00:35.374Z,1706122835.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=46921, MT Status=2, MTMSN=0 2024-01-24T19:00:35.375Z,1706122835.375 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:00:51.944Z,1706122851.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=46921, MT Status=2, MTMSN=0 2024-01-24T19:00:51.944Z,1706122851.944 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:01:11.331Z,1706122871.331 [NAL9602](INFO): SBD MO Status=0, MOMSN=46921, MT Status=0, MTMSN=0 2024-01-24T19:01:11.331Z,1706122871.331 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:01:42.037Z,1706122902.037 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:05:31.121Z,1706123131.121 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:05:31.121Z,1706123131.121 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:05:31.121Z,1706123131.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:05:31.122Z,1706123131.122 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:05:31.538Z,1706123131.538 [Default:CheckIn:D] Stopped 2024-01-24T19:05:31.538Z,1706123131.538 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.531201 min 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn:E] Stopped 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn] Stopped 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn](INFO): Running loop #19 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn] Running Loop=19 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:05:31.923Z,1706123131.923 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:05:33.943Z,1706123133.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190534.00,A,3648.13199,N,12147.20955,W,0.467,293.36,240124,,,D*7A 2024-01-24T19:05:33.945Z,1706123133.945 [NAL9602](INFO): GPS fix at 20240124T190534: (36.802200, -121.786826) 2024-01-24T19:05:33.956Z,1706123133.956 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:05:33.956Z,1706123133.956 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:05:41.719Z,1706123141.719 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0048.lzma 2024-01-24T19:05:42.722Z,1706123142.722 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0048.lzma.bak 2024-01-24T19:05:42.722Z,1706123142.722 [DataOverHttps](INFO): SBD MOMSN=19208986 2024-01-24T19:05:58.579Z,1706123158.579 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240124T172639/Express0049.lzma 2024-01-24T19:05:59.582Z,1706123159.582 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0049.lzma.bak 2024-01-24T19:05:59.582Z,1706123159.582 [DataOverHttps](INFO): SBD MOMSN=19208989 2024-01-24T19:06:01.017Z,1706123161.017 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:06:01.017Z,1706123161.017 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:06:01.017Z,1706123161.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:06:49.482Z,1706123209.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=46922, MT Status=2, MTMSN=0 2024-01-24T19:06:49.482Z,1706123209.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:08:40.595Z,1706123320.595 [NAL9602](INFO): SBD MO Status=0, MOMSN=46922, MT Status=0, MTMSN=0 2024-01-24T19:08:40.595Z,1706123320.595 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:09:11.298Z,1706123351.298 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:10:01.391Z,1706123401.391 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T19:11:01.601Z,1706123461.601 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:11:01.601Z,1706123461.601 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:11:01.601Z,1706123461.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:11:01.602Z,1706123461.602 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:11:02.013Z,1706123462.013 [Default:CheckIn:D] Stopped 2024-01-24T19:11:02.013Z,1706123462.013 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.039119 min 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn:E] Stopped 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn] Stopped 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn](INFO): Running loop #20 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn] Running Loop=20 2024-01-24T19:11:02.409Z,1706123462.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:11:02.410Z,1706123462.410 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:11:04.419Z,1706123464.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191105.00,A,3648.13085,N,12147.20996,W,0.097,293.36,240124,,,A*70 2024-01-24T19:11:04.421Z,1706123464.421 [NAL9602](INFO): GPS fix at 20240124T191105: (36.802181, -121.786833) 2024-01-24T19:11:04.432Z,1706123464.432 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:11:04.432Z,1706123464.432 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:11:12.403Z,1706123472.403 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240124T172639/Courier0051.lzma 2024-01-24T19:11:13.406Z,1706123473.406 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0051.lzma.bak 2024-01-24T19:11:13.406Z,1706123473.406 [DataOverHttps](INFO): SBD MOMSN=19208993 2024-01-24T19:11:29.175Z,1706123489.175 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240124T172639/Express0052.lzma 2024-01-24T19:11:30.174Z,1706123490.174 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0052.lzma.bak 2024-01-24T19:11:30.174Z,1706123490.174 [DataOverHttps](INFO): SBD MOMSN=19208996 2024-01-24T19:11:31.083Z,1706123491.083 [NAL9602](INFO): SBD MO Status=2, MOMSN=46923, MT Status=2, MTMSN=0 2024-01-24T19:11:31.083Z,1706123491.083 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:11:31.519Z,1706123491.519 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:11:31.520Z,1706123491.520 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:11:31.520Z,1706123491.520 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:12:24.412Z,1706123544.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=46923, MT Status=2, MTMSN=0 2024-01-24T19:12:24.412Z,1706123544.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:12:40.174Z,1706123560.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=46923, MT Status=2, MTMSN=0 2024-01-24T19:12:40.174Z,1706123560.174 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:13:19.354Z,1706123599.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=46923, MT Status=0, MTMSN=0 2024-01-24T19:13:19.355Z,1706123599.355 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:13:50.052Z,1706123630.052 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:16:32.083Z,1706123792.083 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:16:32.084Z,1706123792.084 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:16:32.084Z,1706123792.084 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:16:32.084Z,1706123792.084 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:16:32.493Z,1706123792.493 [Default:CheckIn:D] Stopped 2024-01-24T19:16:32.493Z,1706123792.493 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.547095 min 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn:E] Stopped 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn] Stopped 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn](INFO): Running loop #21 2024-01-24T19:16:32.888Z,1706123792.888 [Default:CheckIn] Running Loop=21 2024-01-24T19:16:32.889Z,1706123792.889 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:16:32.889Z,1706123792.889 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:16:34.899Z,1706123794.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191635.00,A,3648.14456,N,12147.19487,W,2.410,33.07,240124,,,D*48 2024-01-24T19:16:34.901Z,1706123794.901 [NAL9602](INFO): GPS fix at 20240124T191635: (36.802409, -121.786581) 2024-01-24T19:16:34.912Z,1706123794.912 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:16:34.912Z,1706123794.912 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:16:42.671Z,1706123802.671 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0054.lzma 2024-01-24T19:16:43.674Z,1706123803.674 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0054.lzma.bak 2024-01-24T19:16:43.674Z,1706123803.674 [DataOverHttps](INFO): SBD MOMSN=19209009 2024-01-24T19:16:58.779Z,1706123818.779 [NAL9602](INFO): SBD MO Status=2, MOMSN=46924, MT Status=2, MTMSN=0 2024-01-24T19:16:58.779Z,1706123818.779 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:17:02.491Z,1706123822.491 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20240124T172639/Express0055.lzma 2024-01-24T19:17:03.494Z,1706123823.494 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0055.lzma.bak 2024-01-24T19:17:03.494Z,1706123823.494 [DataOverHttps](INFO): SBD MOMSN=19209012 2024-01-24T19:17:04.849Z,1706123824.849 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:17:04.849Z,1706123824.849 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:17:04.849Z,1706123824.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:17:06.074Z,1706123826.074 [NAL9602](INFO): SBD MO Status=0, MOMSN=46924, MT Status=0, MTMSN=0 2024-01-24T19:17:06.074Z,1706123826.074 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:17:36.749Z,1706123856.749 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:20:01.790Z,1706124001.790 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T19:22:05.431Z,1706124125.431 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:22:05.431Z,1706124125.431 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:22:05.432Z,1706124125.432 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:22:05.432Z,1706124125.432 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:22:05.842Z,1706124125.842 [Default:CheckIn:D] Stopped 2024-01-24T19:22:05.842Z,1706124125.842 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:22:06.235Z,1706124126.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.102938 min 2024-01-24T19:22:06.235Z,1706124126.235 [Default:CheckIn:E] Stopped 2024-01-24T19:22:06.235Z,1706124126.235 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:22:06.235Z,1706124126.235 [Default:CheckIn] Stopped 2024-01-24T19:22:06.235Z,1706124126.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:22:06.236Z,1706124126.236 [Default:CheckIn](INFO): Running loop #22 2024-01-24T19:22:06.236Z,1706124126.236 [Default:CheckIn] Running Loop=22 2024-01-24T19:22:06.236Z,1706124126.236 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:22:06.236Z,1706124126.236 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:22:08.242Z,1706124128.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192209.00,A,3648.14960,N,12147.22168,W,0.019,234.23,240124,,,D*78 2024-01-24T19:22:08.245Z,1706124128.245 [NAL9602](INFO): GPS fix at 20240124T192209: (36.802493, -121.787028) 2024-01-24T19:22:08.256Z,1706124128.256 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:22:08.256Z,1706124128.256 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:22:15.507Z,1706124135.507 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0057.lzma 2024-01-24T19:22:16.510Z,1706124136.510 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0057.lzma.bak 2024-01-24T19:22:16.510Z,1706124136.510 [DataOverHttps](INFO): SBD MOMSN=19209019 2024-01-24T19:22:32.433Z,1706124152.433 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20240124T172639/Express0058.lzma 2024-01-24T19:22:33.434Z,1706124153.434 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0058.lzma.bak 2024-01-24T19:22:33.434Z,1706124153.434 [DataOverHttps](INFO): SBD MOMSN=19209022 2024-01-24T19:22:34.981Z,1706124154.981 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:22:34.981Z,1706124154.981 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:22:34.981Z,1706124154.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:24:16.346Z,1706124256.346 [NAL9602](INFO): SBD MO Status=2, MOMSN=46925, MT Status=2, MTMSN=0 2024-01-24T19:24:16.346Z,1706124256.346 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:25:47.254Z,1706124347.254 [NAL9602](INFO): SBD MO Status=0, MOMSN=46925, MT Status=0, MTMSN=0 2024-01-24T19:25:47.255Z,1706124347.255 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:26:17.947Z,1706124377.947 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:27:35.528Z,1706124455.528 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:27:35.528Z,1706124455.528 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:27:35.528Z,1706124455.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:27:35.529Z,1706124455.529 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:27:35.943Z,1706124455.943 [Default:CheckIn:D] Stopped 2024-01-24T19:27:35.943Z,1706124455.943 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:27:36.339Z,1706124456.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.604622 min 2024-01-24T19:27:36.339Z,1706124456.339 [Default:CheckIn:E] Stopped 2024-01-24T19:27:36.339Z,1706124456.339 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:27:36.340Z,1706124456.340 [Default:CheckIn] Stopped 2024-01-24T19:27:36.340Z,1706124456.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:27:36.340Z,1706124456.340 [Default:CheckIn](INFO): Running loop #23 2024-01-24T19:27:36.340Z,1706124456.340 [Default:CheckIn] Running Loop=23 2024-01-24T19:27:36.340Z,1706124456.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:27:36.340Z,1706124456.340 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:27:38.347Z,1706124458.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192739.00,A,3648.14949,N,12147.22466,W,0.019,233.17,240124,,,D*7E 2024-01-24T19:27:38.349Z,1706124458.349 [NAL9602](INFO): GPS fix at 20240124T192739: (36.802492, -121.787078) 2024-01-24T19:27:38.384Z,1706124458.384 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:27:38.384Z,1706124458.384 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:27:45.259Z,1706124465.259 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0060.lzma 2024-01-24T19:27:46.262Z,1706124466.262 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0060.lzma.bak 2024-01-24T19:27:46.262Z,1706124466.262 [DataOverHttps](INFO): SBD MOMSN=19209033 2024-01-24T19:27:52.959Z,1706124472.959 [NAL9602](INFO): SBD MO Status=0, MOMSN=46926, MT Status=0, MTMSN=0 2024-01-24T19:27:52.959Z,1706124472.959 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:28:02.063Z,1706124482.063 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240124T172639/Express0061.lzma 2024-01-24T19:28:03.066Z,1706124483.066 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0061.lzma.bak 2024-01-24T19:28:03.066Z,1706124483.066 [DataOverHttps](INFO): SBD MOMSN=19209037 2024-01-24T19:28:04.280Z,1706124484.280 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:28:04.281Z,1706124484.281 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:28:04.281Z,1706124484.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:28:23.665Z,1706124503.665 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:30:01.839Z,1706124601.839 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T19:33:04.867Z,1706124784.867 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:33:04.867Z,1706124784.867 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:33:04.867Z,1706124784.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:33:04.867Z,1706124784.867 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:33:05.267Z,1706124785.267 [Default:CheckIn:D] Stopped 2024-01-24T19:33:05.267Z,1706124785.267 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:33:05.679Z,1706124785.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.093359 min 2024-01-24T19:33:05.679Z,1706124785.679 [Default:CheckIn:E] Stopped 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn] Stopped 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn](INFO): Running loop #24 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn] Running Loop=24 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:33:05.680Z,1706124785.680 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:33:07.681Z,1706124787.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193308.00,A,3648.15051,N,12147.22443,W,0.000,233.17,240124,,,D*77 2024-01-24T19:33:07.684Z,1706124787.684 [NAL9602](INFO): GPS fix at 20240124T193308: (36.802509, -121.787074) 2024-01-24T19:33:07.713Z,1706124787.713 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:33:07.713Z,1706124787.713 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:33:14.799Z,1706124794.799 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240124T172639/Courier0063.lzma 2024-01-24T19:33:15.802Z,1706124795.802 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0063.lzma.bak 2024-01-24T19:33:15.802Z,1706124795.802 [DataOverHttps](INFO): SBD MOMSN=19209041 2024-01-24T19:33:31.587Z,1706124811.587 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240124T172639/Express0064.lzma 2024-01-24T19:33:31.938Z,1706124811.938 [NAL9602](INFO): SBD MO Status=2, MOMSN=46927, MT Status=2, MTMSN=0 2024-01-24T19:33:31.939Z,1706124811.939 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:33:32.590Z,1706124812.590 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0064.lzma.bak 2024-01-24T19:33:32.590Z,1706124812.590 [DataOverHttps](INFO): SBD MOMSN=19209044 2024-01-24T19:33:33.978Z,1706124813.978 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:33:33.978Z,1706124813.978 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:33:33.979Z,1706124813.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:33:41.634Z,1706124821.634 [NAL9602](INFO): SBD MO Status=0, MOMSN=46927, MT Status=0, MTMSN=0 2024-01-24T19:33:41.635Z,1706124821.635 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:34:12.337Z,1706124852.337 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:38:34.585Z,1706125114.585 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:38:34.585Z,1706125114.585 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:38:34.585Z,1706125114.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:38:34.586Z,1706125114.586 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:38:34.971Z,1706125114.971 [Default:CheckIn:D] Stopped 2024-01-24T19:38:34.971Z,1706125114.971 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:38:35.379Z,1706125115.379 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.588411 min 2024-01-24T19:38:35.379Z,1706125115.379 [Default:CheckIn:E] Stopped 2024-01-24T19:38:35.379Z,1706125115.379 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:38:35.379Z,1706125115.379 [Default:CheckIn] Stopped 2024-01-24T19:38:35.379Z,1706125115.379 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:38:35.380Z,1706125115.380 [Default:CheckIn](INFO): Running loop #25 2024-01-24T19:38:35.380Z,1706125115.380 [Default:CheckIn] Running Loop=25 2024-01-24T19:38:35.380Z,1706125115.380 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:38:35.380Z,1706125115.380 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:38:37.387Z,1706125117.387 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193838.00,A,3648.14992,N,12147.22293,W,0.058,233.17,240124,,,A*7B 2024-01-24T19:38:37.390Z,1706125117.390 [NAL9602](INFO): GPS fix at 20240124T193838: (36.802499, -121.787049) 2024-01-24T19:38:37.401Z,1706125117.401 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:38:37.401Z,1706125117.401 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:38:45.347Z,1706125125.347 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0066.lzma 2024-01-24T19:38:46.350Z,1706125126.350 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0066.lzma.bak 2024-01-24T19:38:46.350Z,1706125126.350 [DataOverHttps](INFO): SBD MOMSN=19209054 2024-01-24T19:39:05.271Z,1706125145.271 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240124T172639/Express0067.lzma 2024-01-24T19:39:05.715Z,1706125145.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=46928, MT Status=2, MTMSN=0 2024-01-24T19:39:05.715Z,1706125145.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:39:06.866Z,1706125146.866 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0067.lzma.bak 2024-01-24T19:39:06.866Z,1706125146.866 [DataOverHttps](INFO): SBD MOMSN=19209057 2024-01-24T19:39:07.752Z,1706125147.752 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:39:07.752Z,1706125147.752 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:39:07.752Z,1706125147.752 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:39:49.346Z,1706125189.346 [NAL9602](INFO): SBD MO Status=2, MOMSN=46928, MT Status=2, MTMSN=0 2024-01-24T19:39:49.347Z,1706125189.347 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T19:40:01.876Z,1706125201.876 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T19:40:37.022Z,1706125237.022 [NAL9602](INFO): SBD MO Status=0, MOMSN=46928, MT Status=0, MTMSN=0 2024-01-24T19:40:37.022Z,1706125237.022 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:41:07.745Z,1706125267.745 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:44:08.343Z,1706125448.343 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:44:08.343Z,1706125448.343 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:44:08.343Z,1706125448.343 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:44:08.344Z,1706125448.344 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:44:08.755Z,1706125448.755 [Default:CheckIn:D] Stopped 2024-01-24T19:44:08.755Z,1706125448.755 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:44:09.151Z,1706125449.151 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.151481 min 2024-01-24T19:44:09.151Z,1706125449.151 [Default:CheckIn:E] Stopped 2024-01-24T19:44:09.151Z,1706125449.151 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:44:09.152Z,1706125449.152 [Default:CheckIn] Stopped 2024-01-24T19:44:09.152Z,1706125449.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:44:09.152Z,1706125449.152 [Default:CheckIn](INFO): Running loop #26 2024-01-24T19:44:09.152Z,1706125449.152 [Default:CheckIn] Running Loop=26 2024-01-24T19:44:09.152Z,1706125449.152 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:44:09.152Z,1706125449.152 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:44:11.159Z,1706125451.159 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194411.00,A,3648.14965,N,12147.22424,W,0.039,233.17,240124,,,A*7E 2024-01-24T19:44:11.161Z,1706125451.161 [NAL9602](INFO): GPS fix at 20240124T194411: (36.802494, -121.787071) 2024-01-24T19:44:11.172Z,1706125451.172 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:44:11.172Z,1706125451.172 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:44:18.091Z,1706125458.091 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0069.lzma 2024-01-24T19:44:19.094Z,1706125459.094 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0069.lzma.bak 2024-01-24T19:44:19.094Z,1706125459.094 [DataOverHttps](INFO): SBD MOMSN=19209061 2024-01-24T19:44:24.894Z,1706125464.894 [NAL9602](INFO): SBD MO Status=0, MOMSN=46929, MT Status=0, MTMSN=0 2024-01-24T19:44:24.894Z,1706125464.894 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:44:34.955Z,1706125474.955 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20240124T172639/Express0070.lzma 2024-01-24T19:44:35.954Z,1706125475.954 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0070.lzma.bak 2024-01-24T19:44:35.954Z,1706125475.954 [DataOverHttps](INFO): SBD MOMSN=19209064 2024-01-24T19:44:37.427Z,1706125477.427 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:44:37.427Z,1706125477.427 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:44:37.427Z,1706125477.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:44:55.601Z,1706125495.601 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:49:38.072Z,1706125778.072 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:49:38.072Z,1706125778.072 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:49:38.072Z,1706125778.072 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:49:38.072Z,1706125778.072 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:49:38.487Z,1706125778.487 [Default:CheckIn:D] Stopped 2024-01-24T19:49:38.487Z,1706125778.487 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:49:38.893Z,1706125778.893 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.647021 min 2024-01-24T19:49:38.893Z,1706125778.893 [Default:CheckIn:E] Stopped 2024-01-24T19:49:38.893Z,1706125778.893 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:49:38.894Z,1706125778.894 [Default:CheckIn] Stopped 2024-01-24T19:49:38.894Z,1706125778.894 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:49:38.894Z,1706125778.894 [Default:CheckIn](INFO): Running loop #27 2024-01-24T19:49:38.894Z,1706125778.894 [Default:CheckIn] Running Loop=27 2024-01-24T19:49:38.894Z,1706125778.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:49:38.894Z,1706125778.894 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:49:40.895Z,1706125780.895 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194941.00,A,3648.17441,N,12147.27868,W,0.078,323.98,240124,,,D*78 2024-01-24T19:49:40.897Z,1706125780.897 [NAL9602](INFO): GPS fix at 20240124T194941: (36.802907, -121.787978) 2024-01-24T19:49:40.925Z,1706125780.925 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:49:40.925Z,1706125780.925 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:49:48.855Z,1706125788.855 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0072.lzma 2024-01-24T19:49:49.858Z,1706125789.858 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0072.lzma.bak 2024-01-24T19:49:49.858Z,1706125789.858 [DataOverHttps](INFO): SBD MOMSN=19209074 2024-01-24T19:50:01.914Z,1706125801.914 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T19:50:05.779Z,1706125805.779 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20240124T172639/Express0073.lzma 2024-01-24T19:50:06.782Z,1706125806.782 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0073.lzma.bak 2024-01-24T19:50:06.782Z,1706125806.782 [DataOverHttps](INFO): SBD MOMSN=19209077 2024-01-24T19:50:07.982Z,1706125807.982 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:50:07.982Z,1706125807.982 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:50:07.982Z,1706125807.982 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:52:26.143Z,1706125946.143 [NAL9602](INFO): SBD MO Status=0, MOMSN=46930, MT Status=0, MTMSN=0 2024-01-24T19:52:26.143Z,1706125946.143 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:52:56.837Z,1706125976.837 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:52:59.272Z,1706125979.272 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-01-24T19:53:14.613Z,1706125994.613 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-01-24T19:53:29.962Z,1706126009.962 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-01-24T19:53:45.718Z,1706126025.718 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-01-24T19:54:01.071Z,1706126041.071 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-01-24T19:54:16.024Z,1706126056.024 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-01-24T19:55:08.613Z,1706126108.613 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T19:55:08.613Z,1706126108.613 [Default:CheckIn:C.Wait] Stopped 2024-01-24T19:55:08.613Z,1706126108.613 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T19:55:08.613Z,1706126108.613 [Default:CheckIn:D] Running Loop=1 2024-01-24T19:55:09.075Z,1706126109.075 [Default:CheckIn:D] Stopped 2024-01-24T19:55:09.075Z,1706126109.075 [Default:CheckIn:E] Running Loop=1 2024-01-24T19:55:09.424Z,1706126109.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.156820 min 2024-01-24T19:55:09.424Z,1706126109.424 [Default:CheckIn:E] Stopped 2024-01-24T19:55:09.424Z,1706126109.424 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T19:55:09.424Z,1706126109.424 [Default:CheckIn] Stopped 2024-01-24T19:55:09.424Z,1706126109.424 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T19:55:09.424Z,1706126109.424 [Default:CheckIn](INFO): Running loop #28 2024-01-24T19:55:09.425Z,1706126109.425 [Default:CheckIn] Running Loop=28 2024-01-24T19:55:09.425Z,1706126109.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T19:55:09.425Z,1706126109.425 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T19:55:11.427Z,1706126111.427 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195512.00,A,3648.17790,N,12147.27930,W,0.058,323.98,240124,,,D*72 2024-01-24T19:55:11.439Z,1706126111.439 [NAL9602](INFO): GPS fix at 20240124T195512: (36.802965, -121.787988) 2024-01-24T19:55:11.453Z,1706126111.453 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T19:55:11.453Z,1706126111.453 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T19:55:19.171Z,1706126119.171 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240124T172639/Courier0075.lzma 2024-01-24T19:55:20.174Z,1706126120.174 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0075.lzma.bak 2024-01-24T19:55:20.174Z,1706126120.174 [DataOverHttps](INFO): SBD MOMSN=19209081 2024-01-24T19:55:25.980Z,1706126125.980 [NAL9602](INFO): SBD MO Status=0, MOMSN=46931, MT Status=0, MTMSN=0 2024-01-24T19:55:25.980Z,1706126125.980 [NAL9602](INFO): No messages in MT queue 2024-01-24T19:55:36.391Z,1706126136.391 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240124T172639/Express0076.lzma 2024-01-24T19:55:37.394Z,1706126137.394 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0076.lzma.bak 2024-01-24T19:55:37.394Z,1706126137.394 [DataOverHttps](INFO): SBD MOMSN=19209084 2024-01-24T19:55:38.925Z,1706126138.925 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T19:55:38.925Z,1706126138.925 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T19:55:38.925Z,1706126138.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T19:55:56.701Z,1706126156.701 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T19:56:56.470Z,1706126216.470 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-01-24T19:56:56.473Z,1706126216.473 [BPC1](INFO): Received data from all battery sticks. 2024-01-24T20:00:01.939Z,1706126401.939 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T20:00:39.534Z,1706126439.534 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T20:00:39.534Z,1706126439.534 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:00:39.534Z,1706126439.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:00:39.534Z,1706126439.534 [Default:CheckIn:D] Running Loop=1 2024-01-24T20:00:39.918Z,1706126439.918 [Default:CheckIn:D] Stopped 2024-01-24T20:00:39.918Z,1706126439.918 [Default:CheckIn:E] Running Loop=1 2024-01-24T20:00:40.327Z,1706126440.327 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.670866 min 2024-01-24T20:00:40.327Z,1706126440.327 [Default:CheckIn:E] Stopped 2024-01-24T20:00:40.327Z,1706126440.327 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T20:00:40.327Z,1706126440.327 [Default:CheckIn] Stopped 2024-01-24T20:00:40.327Z,1706126440.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:00:40.328Z,1706126440.328 [Default:CheckIn](INFO): Running loop #29 2024-01-24T20:00:40.328Z,1706126440.328 [Default:CheckIn] Running Loop=29 2024-01-24T20:00:40.328Z,1706126440.328 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T20:00:40.328Z,1706126440.328 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T20:00:42.335Z,1706126442.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200043.00,A,3648.17721,N,12147.27942,W,0.019,323.98,240124,,,A*73 2024-01-24T20:00:42.338Z,1706126442.338 [NAL9602](INFO): GPS fix at 20240124T200043: (36.802954, -121.787990) 2024-01-24T20:00:42.349Z,1706126442.349 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T20:00:42.349Z,1706126442.349 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T20:00:52.999Z,1706126452.999 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20240124T172639/Courier0078.lzma 2024-01-24T20:00:54.002Z,1706126454.002 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0078.lzma.bak 2024-01-24T20:00:54.002Z,1706126454.002 [DataOverHttps](INFO): SBD MOMSN=19209095 2024-01-24T20:01:10.763Z,1706126470.763 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240124T172639/Express0079.lzma 2024-01-24T20:01:11.766Z,1706126471.766 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0079.lzma.bak 2024-01-24T20:01:11.766Z,1706126471.766 [DataOverHttps](INFO): SBD MOMSN=19209098 2024-01-24T20:01:13.099Z,1706126473.099 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T20:01:13.099Z,1706126473.099 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T20:01:13.099Z,1706126473.099 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T20:01:46.207Z,1706126506.207 [NAL9602](INFO): SBD MO Status=0, MOMSN=46932, MT Status=0, MTMSN=0 2024-01-24T20:01:46.207Z,1706126506.207 [NAL9602](INFO): No messages in MT queue 2024-01-24T20:02:16.909Z,1706126536.909 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T20:06:13.831Z,1706126773.831 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T20:06:13.831Z,1706126773.831 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:06:13.831Z,1706126773.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:06:13.832Z,1706126773.832 [Default:CheckIn:D] Running Loop=1 2024-01-24T20:06:14.242Z,1706126774.242 [Default:CheckIn:D] Stopped 2024-01-24T20:06:14.242Z,1706126774.242 [Default:CheckIn:E] Running Loop=1 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 177.242936 min 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn:E] Stopped 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn] Stopped 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn](INFO): Running loop #30 2024-01-24T20:06:14.652Z,1706126774.652 [Default:CheckIn] Running Loop=30 2024-01-24T20:06:14.653Z,1706126774.653 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T20:06:14.653Z,1706126774.653 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T20:06:16.708Z,1706126776.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200617.00,A,3648.17543,N,12147.28069,W,0.039,323.98,240124,,,A*7F 2024-01-24T20:06:16.710Z,1706126776.710 [NAL9602](INFO): GPS fix at 20240124T200617: (36.802924, -121.788011) 2024-01-24T20:06:16.764Z,1706126776.764 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T20:06:16.764Z,1706126776.764 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T20:06:25.483Z,1706126785.483 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0081.lzma 2024-01-24T20:06:26.487Z,1706126786.487 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0081.lzma.bak 2024-01-24T20:06:26.487Z,1706126786.487 [DataOverHttps](INFO): SBD MOMSN=19209104 2024-01-24T20:06:42.259Z,1706126802.259 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240124T172639/Express0082.lzma 2024-01-24T20:06:43.262Z,1706126803.262 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0082.lzma.bak 2024-01-24T20:06:43.262Z,1706126803.262 [DataOverHttps](INFO): SBD MOMSN=19209111 2024-01-24T20:06:44.558Z,1706126804.558 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T20:06:44.558Z,1706126804.558 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T20:06:44.558Z,1706126804.558 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T20:07:07.208Z,1706126827.208 [NAL9602](INFO): SBD MO Status=2, MOMSN=46933, MT Status=2, MTMSN=0 2024-01-24T20:07:07.208Z,1706126827.208 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-01-24T20:10:02.184Z,1706127002.184 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T20:11:09.697Z,1706127069.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=46933, MT Status=0, MTMSN=0 2024-01-24T20:11:09.697Z,1706127069.697 [NAL9602](INFO): No messages in MT queue 2024-01-24T20:11:40.401Z,1706127100.401 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T20:11:45.263Z,1706127105.263 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T20:11:45.263Z,1706127105.263 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:11:45.263Z,1706127105.263 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:11:45.264Z,1706127105.264 [Default:CheckIn:D] Running Loop=1 2024-01-24T20:11:45.664Z,1706127105.664 [Default:CheckIn:D] Stopped 2024-01-24T20:11:45.664Z,1706127105.664 [Default:CheckIn:E] Running Loop=1 2024-01-24T20:11:46.081Z,1706127106.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.766634 min 2024-01-24T20:11:46.081Z,1706127106.081 [Default:CheckIn:E] Stopped 2024-01-24T20:11:46.081Z,1706127106.081 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T20:11:46.081Z,1706127106.081 [Default:CheckIn] Stopped 2024-01-24T20:11:46.081Z,1706127106.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:11:46.082Z,1706127106.082 [Default:CheckIn](INFO): Running loop #31 2024-01-24T20:11:46.082Z,1706127106.082 [Default:CheckIn] Running Loop=31 2024-01-24T20:11:46.082Z,1706127106.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T20:11:46.082Z,1706127106.082 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T20:11:48.079Z,1706127108.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201148.00,A,3648.17717,N,12147.28038,W,0.078,323.98,240124,,,A*71 2024-01-24T20:11:48.081Z,1706127108.081 [NAL9602](INFO): GPS fix at 20240124T201148: (36.802953, -121.788006) 2024-01-24T20:11:48.092Z,1706127108.092 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T20:11:48.092Z,1706127108.092 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T20:11:55.179Z,1706127115.179 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240124T172639/Courier0084.lzma 2024-01-24T20:11:56.182Z,1706127116.182 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0084.lzma.bak 2024-01-24T20:11:56.182Z,1706127116.182 [DataOverHttps](INFO): SBD MOMSN=19209114 2024-01-24T20:12:12.291Z,1706127132.291 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20240124T172639/Express0085.lzma 2024-01-24T20:12:13.294Z,1706127133.294 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0085.lzma.bak 2024-01-24T20:12:13.294Z,1706127133.294 [DataOverHttps](INFO): SBD MOMSN=19209117 2024-01-24T20:12:14.763Z,1706127134.763 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T20:12:14.763Z,1706127134.763 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T20:12:14.764Z,1706127134.764 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T20:13:00.067Z,1706127180.067 [NAL9602](INFO): SBD MO Status=0, MOMSN=46934, MT Status=0, MTMSN=0 2024-01-24T20:13:00.067Z,1706127180.067 [NAL9602](INFO): No messages in MT queue 2024-01-24T20:13:30.765Z,1706127210.765 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T20:17:15.507Z,1706127435.507 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T20:17:15.507Z,1706127435.507 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:17:15.507Z,1706127435.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:17:15.508Z,1706127435.508 [Default:CheckIn:D] Running Loop=1 2024-01-24T20:17:15.911Z,1706127435.911 [Default:CheckIn:D] Stopped 2024-01-24T20:17:15.911Z,1706127435.911 [Default:CheckIn:E] Running Loop=1 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 188.270752 min 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn:E] Stopped 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn] Stopped 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn](INFO): Running loop #32 2024-01-24T20:17:16.324Z,1706127436.324 [Default:CheckIn] Running Loop=32 2024-01-24T20:17:16.325Z,1706127436.325 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T20:17:16.325Z,1706127436.325 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T20:17:18.323Z,1706127438.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201719.00,A,3648.17567,N,12147.28014,W,0.058,323.98,240124,,,A*7A 2024-01-24T20:17:18.325Z,1706127438.325 [NAL9602](INFO): GPS fix at 20240124T201719: (36.802928, -121.788002) 2024-01-24T20:17:18.353Z,1706127438.353 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T20:17:18.353Z,1706127438.353 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T20:17:26.063Z,1706127446.063 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0087.lzma 2024-01-24T20:17:27.066Z,1706127447.066 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0087.lzma.bak 2024-01-24T20:17:27.066Z,1706127447.066 [DataOverHttps](INFO): SBD MOMSN=19209128 2024-01-24T20:17:43.099Z,1706127463.099 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240124T172639/Express0088.lzma 2024-01-24T20:17:44.102Z,1706127464.102 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0088.lzma.bak 2024-01-24T20:17:44.102Z,1706127464.102 [DataOverHttps](INFO): SBD MOMSN=19209131 2024-01-24T20:17:45.409Z,1706127465.409 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T20:17:45.409Z,1706127465.409 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T20:17:45.409Z,1706127465.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T20:20:02.394Z,1706127602.394 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T20:22:21.009Z,1706127741.009 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-01-24T20:22:46.063Z,1706127766.063 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T20:22:46.064Z,1706127766.064 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:22:46.064Z,1706127766.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:22:46.065Z,1706127766.065 [Default:CheckIn:D] Running Loop=1 2024-01-24T20:22:46.471Z,1706127766.471 [Default:CheckIn:D] Stopped 2024-01-24T20:22:46.471Z,1706127766.471 [Default:CheckIn:E] Running Loop=1 2024-01-24T20:22:46.880Z,1706127766.880 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.780094 min 2024-01-24T20:22:46.880Z,1706127766.880 [Default:CheckIn:E] Stopped 2024-01-24T20:22:46.880Z,1706127766.880 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T20:22:46.881Z,1706127766.881 [Default:CheckIn] Stopped 2024-01-24T20:22:46.881Z,1706127766.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:22:46.881Z,1706127766.881 [Default:CheckIn](INFO): Running loop #33 2024-01-24T20:22:46.881Z,1706127766.881 [Default:CheckIn] Running Loop=33 2024-01-24T20:22:46.881Z,1706127766.881 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T20:22:46.881Z,1706127766.881 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T20:22:48.885Z,1706127768.885 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202249.00,A,3648.16526,N,12147.28187,W,1.302,278.52,240124,,,A*72 2024-01-24T20:22:48.887Z,1706127768.887 [NAL9602](INFO): GPS fix at 20240124T202249: (36.802754, -121.788031) 2024-01-24T20:22:48.898Z,1706127768.898 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T20:22:48.898Z,1706127768.898 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T20:22:59.799Z,1706127779.799 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240124T172639/Courier0090.lzma 2024-01-24T20:23:00.802Z,1706127780.802 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0090.lzma.bak 2024-01-24T20:23:00.802Z,1706127780.802 [DataOverHttps](INFO): SBD MOMSN=19209134 2024-01-24T20:23:16.731Z,1706127796.731 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20240124T172639/Express0091.lzma 2024-01-24T20:23:17.734Z,1706127797.734 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0091.lzma.bak 2024-01-24T20:23:17.734Z,1706127797.734 [DataOverHttps](INFO): SBD MOMSN=19209137 2024-01-24T20:23:19.238Z,1706127799.238 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T20:23:19.238Z,1706127799.238 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T20:23:19.238Z,1706127799.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T20:23:20.845Z,1706127800.845 [NAL9602](INFO): Not Powering down - fast GPS 2024-01-24T20:28:19.823Z,1706128099.823 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-01-24T20:28:19.823Z,1706128099.823 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:28:19.823Z,1706128099.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:28:19.823Z,1706128099.823 [Default:CheckIn:D] Running Loop=1 2024-01-24T20:28:20.241Z,1706128100.241 [Default:CheckIn:D] Stopped 2024-01-24T20:28:20.241Z,1706128100.241 [Default:CheckIn:E] Running Loop=1 2024-01-24T20:28:20.627Z,1706128100.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.342920 min 2024-01-24T20:28:20.627Z,1706128100.627 [Default:CheckIn:E] Stopped 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn] Stopped 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn](INFO): Running loop #34 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn] Running Loop=34 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-01-24T20:28:20.628Z,1706128100.628 [Default:CheckIn:Read_GPS] Running Loop=1 2024-01-24T20:28:22.639Z,1706128102.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202823.00,A,3648.16378,N,12147.27819,W,0.661,318.05,240124,,,A*7C 2024-01-24T20:28:22.641Z,1706128102.641 [NAL9602](INFO): GPS fix at 20240124T202823: (36.802730, -121.787970) 2024-01-24T20:28:22.652Z,1706128102.652 [Default:CheckIn:Read_GPS] Stopped 2024-01-24T20:28:22.652Z,1706128102.652 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-01-24T20:28:30.043Z,1706128110.043 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240124T172639/Courier0093.lzma 2024-01-24T20:28:31.046Z,1706128111.046 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Courier0093.lzma.bak 2024-01-24T20:28:31.046Z,1706128111.046 [DataOverHttps](INFO): SBD MOMSN=19209148 2024-01-24T20:28:47.307Z,1706128127.307 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240124T172639/Express0094.lzma 2024-01-24T20:28:48.311Z,1706128128.311 [DataOverHttps](INFO): Moved sent file to Logs/20240124T172639/Express0094.lzma.bak 2024-01-24T20:28:48.311Z,1706128128.311 [DataOverHttps](INFO): SBD MOMSN=19209151 2024-01-24T20:28:49.724Z,1706128129.724 [Default:CheckIn:Read_Iridium] Stopped 2024-01-24T20:28:49.724Z,1706128129.724 [Default:CheckIn:C.Wait] Running Loop=1 2024-01-24T20:28:49.724Z,1706128129.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-01-24T20:28:53.377Z,1706128133.377 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-01-24T20:28:53.452Z,1706128133.452 [NAL9602](ERROR): received: +CSQ:0 OK934, 0, 0, 0, 0 OK 2024-01-24T20:30:02.494Z,1706128202.494 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-01-24T20:30:46.965Z,1706128246.965 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-01-24T20:30:46.965Z,1706128246.965 [DropWeight] Hardware Fault, FailCount= 1 2024-01-24T20:30:46.965Z,1706128246.965 [DropWeight](ERROR): Hardware Fault 2024-01-24T20:30:47.057Z,1706128247.057 [CommandExec](FAULT): Scheduling is paused 2024-01-24T20:30:47.057Z,1706128247.057 [CBIT](INFO): Critical error at 20240124T203046 2024-01-24T20:30:47.063Z,1706128247.063 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-01-24T20:30:47.063Z,1706128247.063 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-01-24T20:30:47.449Z,1706128247.449 [CBIT](INFO): Critical error at 20240124T203047 2024-01-24T20:31:26.813Z,1706128286.813 [CommandExec](IMPORTANT): got command failComponent 2024-01-24T20:31:26.816Z,1706128286.816 [CommandExec](IMPORTANT): Failed components: 2024-01-24T20:31:26.816Z,1706128286.816 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2024-01-24T20:31:32.324Z,1706128292.324 [CommandExec](IMPORTANT): got command quit 2024-01-24T20:31:33.332Z,1706128293.332 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:33.332Z,1706128293.332 [CommandExec](INFO): Uninitializing the command executive. 2024-01-24T20:31:33.332Z,1706128293.332 [CommandExec](INFO): Uninitializing the command scheduler. 2024-01-24T20:31:33.333Z,1706128293.333 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:33.348Z,1706128293.348 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-01-24T20:31:33.348Z,1706128293.348 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-01-24T20:31:33.349Z,1706128293.349 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:33.350Z,1706128293.350 [NavChartDb](INFO): Join timeout helper Thread ID is 4029 2024-01-24T20:31:33.712Z,1706128293.712 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:33.712Z,1706128293.712 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:33.716Z,1706128293.716 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-01-24T20:31:33.716Z,1706128293.716 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:33.717Z,1706128293.717 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4030 2024-01-24T20:31:34.096Z,1706128294.096 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:34.097Z,1706128294.097 [WetLabsBB2FL](INFO): Powering down 2024-01-24T20:31:34.097Z,1706128294.097 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:34.104Z,1706128294.104 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-01-24T20:31:34.104Z,1706128294.104 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:34.105Z,1706128294.105 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4031 2024-01-24T20:31:34.792Z,1706128294.792 [CTD_Seabird](INFO): Powering down 2024-01-24T20:31:34.804Z,1706128294.804 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:34.804Z,1706128294.804 [CTD_Seabird](INFO): Powering down 2024-01-24T20:31:34.816Z,1706128294.816 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:34.836Z,1706128294.836 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-01-24T20:31:34.836Z,1706128294.836 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:34.837Z,1706128294.837 [Radio_Surface](INFO): Join timeout helper Thread ID is 4032 2024-01-24T20:31:35.240Z,1706128295.240 [Radio_Surface](INFO): Powering down 2024-01-24T20:31:35.241Z,1706128295.241 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:35.241Z,1706128295.241 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.244Z,1706128295.244 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-01-24T20:31:35.244Z,1706128295.244 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.245Z,1706128295.245 [Onboard](INFO): Join timeout helper Thread ID is 4033 2024-01-24T20:31:35.328Z,1706128295.328 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:35.329Z,1706128295.329 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.333Z,1706128295.333 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-01-24T20:31:35.333Z,1706128295.333 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.334Z,1706128295.334 [DataOverHttps](INFO): Join timeout helper Thread ID is 4034 2024-01-24T20:31:35.596Z,1706128295.596 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:35.597Z,1706128295.597 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.613Z,1706128295.613 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-01-24T20:31:35.613Z,1706128295.613 [logger ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.613Z,1706128295.613 [logger](INFO): Join timeout helper Thread ID is 4035 2024-01-24T20:31:35.614Z,1706128295.614 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:35.614Z,1706128295.614 [logger ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.615Z,1706128295.615 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-01-24T20:31:35.615Z,1706128295.615 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.615Z,1706128295.615 [CommandLine](INFO): Join timeout helper Thread ID is 4036 2024-01-24T20:31:35.684Z,1706128295.684 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:35.684Z,1706128295.684 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.697Z,1706128295.697 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-01-24T20:31:35.697Z,1706128295.697 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.697Z,1706128295.697 [CommandExec](INFO): Join timeout helper Thread ID is 4037 2024-01-24T20:31:35.729Z,1706128295.729 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-01-24T20:31:35.729Z,1706128295.729 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:35.729Z,1706128295.729 [controlThread](INFO): Join timeout helper Thread ID is 4038 2024-01-24T20:31:35.836Z,1706128295.836 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-01-24T20:31:35.836Z,1706128295.836 [controlThread](DEBUG): Uninitializing ControlThread 2024-01-24T20:31:35.837Z,1706128295.837 [AHRS_M2](INFO): Powering down 2024-01-24T20:31:35.909Z,1706128295.909 [NAL9602](INFO): Powering down 2024-01-24T20:31:35.910Z,1706128295.910 [Sonardyne_Nano](INFO): Powering down 2024-01-24T20:31:36.030Z,1706128296.030 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-01-24T20:31:36.030Z,1706128296.030 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-01-24T20:31:36.031Z,1706128296.031 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-01-24T20:31:36.031Z,1706128296.031 [MissionManager](INFO): Uninitializing Mission Default 2024-01-24T20:31:36.032Z,1706128296.032 [Default] Stopped 2024-01-24T20:31:36.032Z,1706128296.032 [Default](DEBUG): Aggregate::uninitialize Default 2024-01-24T20:31:36.032Z,1706128296.032 [Default:B.GoToSurface] Stopped 2024-01-24T20:31:36.032Z,1706128296.032 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-01-24T20:31:36.032Z,1706128296.032 [Default:CheckIn] Stopped 2024-01-24T20:31:36.032Z,1706128296.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-01-24T20:31:36.032Z,1706128296.032 [Default:CheckIn:C.Wait] Stopped 2024-01-24T20:31:36.032Z,1706128296.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-01-24T20:31:36.035Z,1706128296.035 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-01-24T20:31:36.035Z,1706128296.035 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-01-24T20:31:36.036Z,1706128296.036 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-01-24T20:31:36.036Z,1706128296.036 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-01-24T20:31:36.036Z,1706128296.036 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-01-24T20:31:36.036Z,1706128296.036 [BuoyancyServo](INFO): Powering down 2024-01-24T20:31:36.048Z,1706128296.048 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-01-24T20:31:36.048Z,1706128296.048 [ElevatorServo](INFO): Powering down 2024-01-24T20:31:36.049Z,1706128296.049 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-01-24T20:31:36.049Z,1706128296.049 [MassServo](INFO): Powering down 2024-01-24T20:31:36.050Z,1706128296.050 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-01-24T20:31:36.050Z,1706128296.050 [RudderServo](INFO): Powering down 2024-01-24T20:31:36.051Z,1706128296.051 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-01-24T20:31:36.051Z,1706128296.051 [ThrusterHE](INFO): Powering down 2024-01-24T20:31:36.052Z,1706128296.052 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-01-24T20:31:36.052Z,1706128296.052 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-01-24T20:31:36.053Z,1706128296.053 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-01-24T20:31:36.053Z,1706128296.053 [CBIT](DEBUG): Powering off loads. 2024-01-24T20:31:36.064Z,1706128296.064 [CBIT](DEBUG): Disabling WDT. 2024-01-24T20:31:36.076Z,1706128296.076 [CBIT](DEBUG): Opening all GF detection circuits. 2024-01-24T20:31:36.077Z,1706128296.077 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.160Z,1706128296.160 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.177Z,1706128296.177 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.183Z,1706128296.183 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.339Z,1706128296.339 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.350Z,1706128296.350 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.433Z,1706128296.433 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-01-24T20:31:36.679Z,1706128296.679 [logger ThreadHandler](INFO): Thread cancelled.