2025-01-09T17:37:12.286Z,1736444232.286 [CommandExec](IMPORTANT): got command restart logs 2025-01-09T17:37:53.480Z,1736444273.480 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T17:37:53.480Z,1736444273.480 [Default:CheckIn:C.Wait] Stopped 2025-01-09T17:37:53.480Z,1736444273.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T17:37:53.480Z,1736444273.480 [Default:CheckIn:D] Running Loop=1 2025-01-09T17:37:53.880Z,1736444273.880 [Default:CheckIn:D] Stopped 2025-01-09T17:37:53.880Z,1736444273.880 [Default:CheckIn:E] Running Loop=1 2025-01-09T17:37:54.293Z,1736444274.293 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.410166 min 2025-01-09T17:37:54.293Z,1736444274.293 [Default:CheckIn:E] Stopped 2025-01-09T17:37:54.293Z,1736444274.293 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T17:37:54.293Z,1736444274.293 [Default:CheckIn] Stopped 2025-01-09T17:37:54.294Z,1736444274.294 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T17:37:54.294Z,1736444274.294 [Default:CheckIn](INFO): Running loop #4 2025-01-09T17:37:54.294Z,1736444274.294 [Default:CheckIn] Running Loop=4 2025-01-09T17:37:54.294Z,1736444274.294 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T17:37:54.294Z,1736444274.294 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T17:37:56.296Z,1736444276.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173755.00,A,3647.81705,N,12151.01745,W,1.749,279.30,090125,,,D*7C 2025-01-09T17:37:56.298Z,1736444276.298 [NAL9602](INFO): GPS fix at 20250109T173755: (36.796951, -121.850291) 2025-01-09T17:37:56.325Z,1736444276.325 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T17:37:56.325Z,1736444276.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T17:38:03.204Z,1736444283.204 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250108T214732/Courier0102.lzma 2025-01-09T17:38:04.207Z,1736444284.207 [DataOverHttps](INFO): Moved sent file to Logs/20250108T214732/Courier0102.lzma.bak 2025-01-09T17:38:04.207Z,1736444284.207 [DataOverHttps](INFO): SBD MOMSN=23890034 2025-01-09T17:38:15.687Z,1736444295.687 [NAL9602](INFO): SBD MO Status=0, MOMSN=61573, MT Status=0, MTMSN=0 2025-01-09T17:38:15.687Z,1736444295.687 [NAL9602](INFO): No messages in MT queue 2025-01-09T17:38:21.865Z,1736444301.865 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250109T173712/Courier0000.lzma 2025-01-09T17:38:22.867Z,1736444302.867 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0000.lzma.bak 2025-01-09T17:38:22.867Z,1736444302.867 [DataOverHttps](INFO): SBD MOMSN=23890050 2025-01-09T17:38:46.414Z,1736444326.414 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T17:38:54.484Z,1736444334.484 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20250108T214732/Express0103.lzma 2025-01-09T17:38:55.487Z,1736444335.487 [DataOverHttps](INFO): Moved sent file to Logs/20250108T214732/Express0103.lzma.bak 2025-01-09T17:38:55.487Z,1736444335.487 [DataOverHttps](INFO): SBD MOMSN=23890072 2025-01-09T17:39:12.249Z,1736444352.249 [DataOverHttps](INFO): Sending 345 bytes from file Logs/20250109T173712/Express0001.lzma 2025-01-09T17:39:13.251Z,1736444353.251 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0001.lzma.bak 2025-01-09T17:39:13.251Z,1736444353.251 [DataOverHttps](INFO): SBD MOMSN=23890088 2025-01-09T17:39:14.361Z,1736444354.361 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T17:39:14.362Z,1736444354.362 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T17:39:14.362Z,1736444354.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T17:41:17.613Z,1736444477.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:41:48.737Z,1736444508.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:42:19.881Z,1736444539.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:42:51.021Z,1736444571.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:42:53.029Z,1736444573.029 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.542770 2025-01-09T17:43:52.277Z,1736444632.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:44:14.999Z,1736444654.999 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T17:44:14.999Z,1736444654.999 [Default:CheckIn:C.Wait] Stopped 2025-01-09T17:44:14.999Z,1736444654.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T17:44:14.999Z,1736444654.999 [Default:CheckIn:D] Running Loop=1 2025-01-09T17:44:15.410Z,1736444655.410 [Default:CheckIn:D] Stopped 2025-01-09T17:44:15.410Z,1736444655.410 [Default:CheckIn:E] Running Loop=1 2025-01-09T17:44:15.795Z,1736444655.795 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.768990 min 2025-01-09T17:44:15.795Z,1736444655.795 [Default:CheckIn:E] Stopped 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn] Stopped 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn](INFO): Running loop #5 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn] Running Loop=5 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T17:44:15.796Z,1736444655.796 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T17:44:17.811Z,1736444657.811 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174417.00,A,3648.37148,N,12149.63837,W,6.045,43.49,090125,,,A*48 2025-01-09T17:44:17.814Z,1736444657.814 [NAL9602](INFO): GPS fix at 20250109T174417: (36.806191, -121.827306) 2025-01-09T17:44:17.824Z,1736444657.824 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T17:44:17.825Z,1736444657.825 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T17:44:23.405Z,1736444663.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:44:47.347Z,1736444687.347 [NAL9602](INFO): SBD MO Status=1, MOMSN=61574, MT Status=0, MTMSN=0 2025-01-09T17:44:47.394Z,1736444687.394 [NAL9602](INFO): Sent 74 bytes from file Logs/20250109T173712/Courier0003.lzma 2025-01-09T17:44:47.394Z,1736444687.394 [NAL9602](INFO): Packets left to send: 0 2025-01-09T17:44:54.529Z,1736444694.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:45:00.306Z,1736444700.306 [NAL9602](INFO): SBD MO Status=1, MOMSN=61575, MT Status=0, MTMSN=0 2025-01-09T17:45:00.357Z,1736444700.357 [NAL9602](INFO): Sent 256 bytes from file Logs/20250109T173712/Express0005.lzma 2025-01-09T17:45:00.357Z,1736444700.357 [NAL9602](INFO): Packets left to send: 0 2025-01-09T17:45:18.899Z,1736444718.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=61576, MT Status=2, MTMSN=0 2025-01-09T17:45:18.900Z,1736444718.900 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T17:45:25.653Z,1736444725.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:45:40.308Z,1736444740.308 [NAL9602](INFO): SBD MO Status=2, MOMSN=61576, MT Status=2, MTMSN=0 2025-01-09T17:45:40.309Z,1736444740.309 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T17:45:49.998Z,1736444749.998 [NAL9602](INFO): SBD MO Status=0, MOMSN=61576, MT Status=0, MTMSN=0 2025-01-09T17:45:50.078Z,1736444750.078 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T17:45:50.079Z,1736444750.079 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T17:45:50.079Z,1736444750.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T17:45:56.029Z,1736444756.029 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003284 2025-01-09T17:46:20.701Z,1736444780.701 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T17:50:50.596Z,1736445050.596 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T17:50:50.596Z,1736445050.596 [Default:CheckIn:C.Wait] Stopped 2025-01-09T17:50:50.596Z,1736445050.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T17:50:50.596Z,1736445050.596 [Default:CheckIn:D] Running Loop=1 2025-01-09T17:50:50.992Z,1736445050.992 [Default:CheckIn:D] Stopped 2025-01-09T17:50:50.992Z,1736445050.992 [Default:CheckIn:E] Running Loop=1 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.362022 min 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn:E] Stopped 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn] Stopped 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn](INFO): Running loop #6 2025-01-09T17:50:51.415Z,1736445051.415 [Default:CheckIn] Running Loop=6 2025-01-09T17:50:51.416Z,1736445051.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T17:50:51.416Z,1736445051.416 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T17:50:53.407Z,1736445053.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175052.00,A,3648.42611,N,12149.63155,W,0.447,25.26,090125,,,A*41 2025-01-09T17:50:53.409Z,1736445053.409 [NAL9602](INFO): GPS fix at 20250109T175052: (36.807102, -121.827192) 2025-01-09T17:50:53.428Z,1736445053.428 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T17:50:53.428Z,1736445053.428 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T17:51:01.260Z,1736445061.260 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250109T173712/Courier0009.lzma 2025-01-09T17:51:02.262Z,1736445062.262 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0009.lzma.bak 2025-01-09T17:51:02.263Z,1736445062.263 [DataOverHttps](INFO): SBD MOMSN=23890119 2025-01-09T17:51:12.394Z,1736445072.394 [NAL9602](INFO): SBD MO Status=2, MOMSN=61577, MT Status=2, MTMSN=0 2025-01-09T17:51:12.394Z,1736445072.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T17:51:18.060Z,1736445078.060 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20250109T173712/Express0010.lzma 2025-01-09T17:51:19.063Z,1736445079.063 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0010.lzma.bak 2025-01-09T17:51:19.063Z,1736445079.063 [DataOverHttps](INFO): SBD MOMSN=23890122 2025-01-09T17:51:20.492Z,1736445080.492 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T17:51:20.492Z,1736445080.492 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T17:51:20.492Z,1736445080.492 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T17:51:29.391Z,1736445089.391 [NAL9602](INFO): SBD MO Status=2, MOMSN=61577, MT Status=2, MTMSN=0 2025-01-09T17:51:29.391Z,1736445089.391 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T17:52:05.748Z,1736445125.748 [NAL9602](INFO): SBD MO Status=0, MOMSN=61577, MT Status=0, MTMSN=0 2025-01-09T17:52:05.748Z,1736445125.748 [NAL9602](INFO): No messages in MT queue 2025-01-09T17:52:36.486Z,1736445156.486 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T17:54:22.994Z,1736445262.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:54:54.117Z,1736445294.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-01-09T17:56:21.132Z,1736445381.132 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T17:56:21.132Z,1736445381.132 [Default:CheckIn:C.Wait] Stopped 2025-01-09T17:56:21.132Z,1736445381.132 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T17:56:21.132Z,1736445381.132 [Default:CheckIn:D] Running Loop=1 2025-01-09T17:56:21.786Z,1736445381.786 [Default:CheckIn:D] Stopped 2025-01-09T17:56:21.786Z,1736445381.786 [Default:CheckIn:E] Running Loop=1 2025-01-09T17:56:21.948Z,1736445381.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.875264 min 2025-01-09T17:56:21.948Z,1736445381.948 [Default:CheckIn:E] Stopped 2025-01-09T17:56:21.948Z,1736445381.948 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T17:56:21.948Z,1736445381.948 [Default:CheckIn] Stopped 2025-01-09T17:56:21.948Z,1736445381.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T17:56:21.973Z,1736445381.973 [Default:CheckIn](INFO): Running loop #7 2025-01-09T17:56:21.973Z,1736445381.973 [Default:CheckIn] Running Loop=7 2025-01-09T17:56:21.973Z,1736445381.973 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T17:56:21.973Z,1736445381.973 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T17:56:23.952Z,1736445383.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175623.00,A,3648.42336,N,12149.28713,W,3.518,102.85,090125,,,D*7A 2025-01-09T17:56:23.954Z,1736445383.954 [NAL9602](INFO): GPS fix at 20250109T175623: (36.807056, -121.821452) 2025-01-09T17:56:23.965Z,1736445383.965 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T17:56:23.965Z,1736445383.965 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T17:56:31.936Z,1736445391.936 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0012.lzma 2025-01-09T17:56:32.939Z,1736445392.939 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0012.lzma.bak 2025-01-09T17:56:32.939Z,1736445392.939 [DataOverHttps](INFO): SBD MOMSN=23890126 2025-01-09T17:56:47.787Z,1736445407.787 [NAL9602](INFO): SBD MO Status=0, MOMSN=61578, MT Status=0, MTMSN=0 2025-01-09T17:56:47.788Z,1736445407.788 [NAL9602](INFO): No messages in MT queue 2025-01-09T17:56:48.672Z,1736445408.672 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20250109T173712/Express0013.lzma 2025-01-09T17:56:49.675Z,1736445409.675 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0013.lzma.bak 2025-01-09T17:56:49.676Z,1736445409.676 [DataOverHttps](INFO): SBD MOMSN=23890129 2025-01-09T17:56:51.037Z,1736445411.037 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T17:56:51.037Z,1736445411.037 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T17:56:51.037Z,1736445411.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T17:57:18.526Z,1736445438.526 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:01:51.680Z,1736445711.680 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:01:51.680Z,1736445711.680 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:01:51.680Z,1736445711.680 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:01:51.680Z,1736445711.680 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:01:52.093Z,1736445712.093 [Default:CheckIn:D] Stopped 2025-01-09T18:01:52.093Z,1736445712.093 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:01:52.479Z,1736445712.479 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.380379 min 2025-01-09T18:01:52.479Z,1736445712.479 [Default:CheckIn:E] Stopped 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn] Stopped 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn](INFO): Running loop #8 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn] Running Loop=8 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:01:52.480Z,1736445712.480 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:01:54.499Z,1736445714.499 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180153.00,A,3648.35198,N,12148.81958,W,5.501,96.29,090125,,,D*41 2025-01-09T18:01:54.502Z,1736445714.502 [NAL9602](INFO): GPS fix at 20250109T180153: (36.805866, -121.813660) 2025-01-09T18:01:54.512Z,1736445714.512 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:01:54.512Z,1736445714.512 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:02:02.152Z,1736445722.152 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0015.lzma 2025-01-09T18:02:03.154Z,1736445723.154 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0015.lzma.bak 2025-01-09T18:02:03.155Z,1736445723.155 [DataOverHttps](INFO): SBD MOMSN=23890147 2025-01-09T18:02:16.715Z,1736445736.715 [NAL9602](INFO): SBD MO Status=0, MOMSN=61579, MT Status=0, MTMSN=0 2025-01-09T18:02:16.716Z,1736445736.716 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:02:20.024Z,1736445740.024 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250109T173712/Express0016.lzma 2025-01-09T18:02:24.047Z,1736445744.047 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0016.lzma.bak 2025-01-09T18:02:24.047Z,1736445744.047 [DataOverHttps](INFO): SBD MOMSN=23890150 2025-01-09T18:02:25.207Z,1736445745.207 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:02:25.207Z,1736445745.207 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:02:25.207Z,1736445745.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:02:47.418Z,1736445767.418 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:07:25.851Z,1736446045.851 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:07:25.851Z,1736446045.851 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:07:25.851Z,1736446045.852 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:07:25.852Z,1736446045.852 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:07:26.253Z,1736446046.253 [Default:CheckIn:D] Stopped 2025-01-09T18:07:26.254Z,1736446046.254 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:07:26.688Z,1736446046.688 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.949719 min 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn:E] Stopped 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn] Stopped 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn](INFO): Running loop #9 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn] Running Loop=9 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:07:26.689Z,1736446046.689 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:07:28.668Z,1736446048.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180727.00,A,3648.27020,N,12148.32687,W,4.432,98.35,090125,,,D*43 2025-01-09T18:07:28.670Z,1736446048.670 [NAL9602](INFO): GPS fix at 20250109T180727: (36.804503, -121.805448) 2025-01-09T18:07:28.680Z,1736446048.680 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:07:28.680Z,1736446048.680 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:07:35.992Z,1736446055.992 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0018.lzma 2025-01-09T18:07:36.995Z,1736446056.995 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0018.lzma.bak 2025-01-09T18:07:36.995Z,1736446056.995 [DataOverHttps](INFO): SBD MOMSN=23890154 2025-01-09T18:07:52.845Z,1736446072.845 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250109T173712/Express0019.lzma 2025-01-09T18:07:53.847Z,1736446073.847 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0019.lzma.bak 2025-01-09T18:07:53.847Z,1736446073.847 [DataOverHttps](INFO): SBD MOMSN=23890157 2025-01-09T18:07:54.949Z,1736446074.949 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:07:54.949Z,1736446074.949 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:07:54.949Z,1736446074.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:08:03.831Z,1736446083.831 [NAL9602](INFO): SBD MO Status=0, MOMSN=61580, MT Status=0, MTMSN=0 2025-01-09T18:08:03.832Z,1736446083.832 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:08:34.530Z,1736446114.530 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:12:55.547Z,1736446375.547 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:12:55.547Z,1736446375.547 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:12:55.547Z,1736446375.547 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:12:55.548Z,1736446375.548 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:12:55.960Z,1736446375.960 [Default:CheckIn:D] Stopped 2025-01-09T18:12:55.960Z,1736446375.960 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:12:56.345Z,1736446376.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.444828 min 2025-01-09T18:12:56.345Z,1736446376.345 [Default:CheckIn:E] Stopped 2025-01-09T18:12:56.345Z,1736446376.345 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:12:56.345Z,1736446376.345 [Default:CheckIn] Stopped 2025-01-09T18:12:56.345Z,1736446376.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:12:56.346Z,1736446376.346 [Default:CheckIn](INFO): Running loop #10 2025-01-09T18:12:56.346Z,1736446376.346 [Default:CheckIn] Running Loop=10 2025-01-09T18:12:56.346Z,1736446376.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:12:56.346Z,1736446376.346 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:12:58.357Z,1736446378.357 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181257.00,A,3648.21448,N,12147.83631,W,4.102,103.63,090125,,,D*72 2025-01-09T18:12:58.359Z,1736446378.359 [NAL9602](INFO): GPS fix at 20250109T181257: (36.803575, -121.797272) 2025-01-09T18:12:58.378Z,1736446378.378 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:12:58.378Z,1736446378.378 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:13:05.684Z,1736446385.684 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0021.lzma 2025-01-09T18:13:06.687Z,1736446386.687 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0021.lzma.bak 2025-01-09T18:13:06.687Z,1736446386.687 [DataOverHttps](INFO): SBD MOMSN=23890175 2025-01-09T18:13:13.702Z,1736446393.702 [NAL9602](INFO): SBD MO Status=0, MOMSN=61581, MT Status=0, MTMSN=0 2025-01-09T18:13:13.702Z,1736446393.702 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:13:22.420Z,1736446402.420 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250109T173712/Express0022.lzma 2025-01-09T18:13:23.423Z,1736446403.423 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0022.lzma.bak 2025-01-09T18:13:23.423Z,1736446403.423 [DataOverHttps](INFO): SBD MOMSN=23890178 2025-01-09T18:13:24.627Z,1736446404.627 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:13:24.627Z,1736446404.627 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:13:24.627Z,1736446404.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:13:44.410Z,1736446424.410 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:18:25.262Z,1736446705.262 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:18:25.262Z,1736446705.262 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:18:25.262Z,1736446705.262 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:18:25.262Z,1736446705.262 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:18:25.651Z,1736446705.651 [Default:CheckIn:D] Stopped 2025-01-09T18:18:25.651Z,1736446705.651 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.939673 min 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn:E] Stopped 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn] Stopped 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn](INFO): Running loop #11 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn] Running Loop=11 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:18:26.045Z,1736446706.045 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:18:28.032Z,1736446708.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181827.00,A,3648.34322,N,12147.47166,W,3.518,45.94,090125,,,D*4E 2025-01-09T18:18:28.034Z,1736446708.034 [NAL9602](INFO): GPS fix at 20250109T181827: (36.805720, -121.791194) 2025-01-09T18:18:28.045Z,1736446708.045 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:18:28.045Z,1736446708.045 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:18:36.120Z,1736446716.120 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0024.lzma 2025-01-09T18:18:37.123Z,1736446717.123 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0024.lzma.bak 2025-01-09T18:18:37.123Z,1736446717.123 [DataOverHttps](INFO): SBD MOMSN=23890204 2025-01-09T18:18:55.893Z,1736446735.893 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250109T173712/Express0025.lzma 2025-01-09T18:18:56.895Z,1736446736.895 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0025.lzma.bak 2025-01-09T18:18:56.895Z,1736446736.895 [DataOverHttps](INFO): SBD MOMSN=23890211 2025-01-09T18:18:58.105Z,1736446738.105 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:18:58.105Z,1736446738.105 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:18:58.106Z,1736446738.106 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:19:02.031Z,1736446742.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=61582, MT Status=2, MTMSN=0 2025-01-09T18:19:02.032Z,1736446742.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T18:19:11.726Z,1736446751.726 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-01-09T18:19:23.843Z,1736446763.843 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:19:29.915Z,1736446769.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=61582, MT Status=2, MTMSN=0 2025-01-09T18:19:29.916Z,1736446769.916 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T18:19:37.175Z,1736446777.175 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:19:47.283Z,1736446787.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=61582, MT Status=2, MTMSN=0 2025-01-09T18:19:47.283Z,1736446787.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T18:19:50.914Z,1736446790.914 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:19:57.380Z,1736446797.380 [NAL9602](INFO): SBD MO Status=0, MOMSN=61582, MT Status=0, MTMSN=0 2025-01-09T18:19:57.380Z,1736446797.380 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:20:04.243Z,1736446804.243 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:20:18.383Z,1736446818.383 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:20:28.094Z,1736446828.094 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:20:31.724Z,1736446831.724 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:20:45.459Z,1736446845.459 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:20:59.599Z,1736446859.599 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:21:12.936Z,1736446872.936 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:21:26.264Z,1736446886.264 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:21:40.403Z,1736446900.403 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:23:58.600Z,1736447038.600 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:23:58.600Z,1736447038.600 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:23:58.600Z,1736447038.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:23:58.600Z,1736447038.600 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:23:59.045Z,1736447039.045 [Default:CheckIn:D] Stopped 2025-01-09T18:23:59.045Z,1736447039.045 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:23:59.402Z,1736447039.402 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.496244 min 2025-01-09T18:23:59.402Z,1736447039.402 [Default:CheckIn:E] Stopped 2025-01-09T18:23:59.402Z,1736447039.402 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:23:59.402Z,1736447039.402 [Default:CheckIn] Stopped 2025-01-09T18:23:59.402Z,1736447039.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:23:59.402Z,1736447039.402 [Default:CheckIn](INFO): Running loop #12 2025-01-09T18:23:59.403Z,1736447039.403 [Default:CheckIn] Running Loop=12 2025-01-09T18:23:59.403Z,1736447039.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:23:59.403Z,1736447039.403 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:24:01.414Z,1736447041.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182400.00,A,3648.45751,N,12147.28926,W,3.305,63.60,090125,,,D*42 2025-01-09T18:24:01.416Z,1736447041.416 [NAL9602](INFO): GPS fix at 20250109T182400: (36.807625, -121.788154) 2025-01-09T18:24:01.428Z,1736447041.428 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:24:01.428Z,1736447041.428 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:24:09.148Z,1736447049.148 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250109T173712/Courier0027.lzma 2025-01-09T18:24:10.151Z,1736447050.151 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0027.lzma.bak 2025-01-09T18:24:10.151Z,1736447050.151 [DataOverHttps](INFO): SBD MOMSN=23890259 2025-01-09T18:24:31.052Z,1736447071.052 [NAL9602](INFO): SBD MO Status=0, MOMSN=61583, MT Status=0, MTMSN=0 2025-01-09T18:24:31.052Z,1736447071.052 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:24:35.144Z,1736447075.144 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250109T173712/Express0028.lzma 2025-01-09T18:24:36.147Z,1736447076.147 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0028.lzma.bak 2025-01-09T18:24:36.147Z,1736447076.147 [DataOverHttps](INFO): SBD MOMSN=23890262 2025-01-09T18:24:37.537Z,1736447077.537 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:24:37.538Z,1736447077.538 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:24:37.538Z,1736447077.538 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:25:01.762Z,1736447101.762 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:29:07.815Z,1736447347.815 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:29:21.147Z,1736447361.147 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:29:34.480Z,1736447374.480 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:29:38.142Z,1736447378.142 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:29:38.142Z,1736447378.142 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:29:38.142Z,1736447378.142 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:29:38.142Z,1736447378.142 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:29:38.532Z,1736447378.532 [Default:CheckIn:D] Stopped 2025-01-09T18:29:38.532Z,1736447378.532 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:29:38.940Z,1736447378.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.154362 min 2025-01-09T18:29:38.940Z,1736447378.940 [Default:CheckIn:E] Stopped 2025-01-09T18:29:38.940Z,1736447378.940 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:29:38.940Z,1736447378.940 [Default:CheckIn] Stopped 2025-01-09T18:29:38.940Z,1736447378.940 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:29:38.941Z,1736447378.941 [Default:CheckIn](INFO): Running loop #13 2025-01-09T18:29:38.941Z,1736447378.941 [Default:CheckIn] Running Loop=13 2025-01-09T18:29:38.941Z,1736447378.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:29:38.941Z,1736447378.941 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:29:40.948Z,1736447380.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182940.00,A,3648.29076,N,12147.13014,W,4.354,195.32,090125,,,D*7F 2025-01-09T18:29:40.950Z,1736447380.950 [NAL9602](INFO): GPS fix at 20250109T182940: (36.804846, -121.785502) 2025-01-09T18:29:40.961Z,1736447380.961 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:29:40.961Z,1736447380.961 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:29:47.816Z,1736447387.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:29:48.704Z,1736447388.704 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0030.lzma 2025-01-09T18:29:49.707Z,1736447389.707 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0030.lzma.bak 2025-01-09T18:29:49.707Z,1736447389.707 [DataOverHttps](INFO): SBD MOMSN=23890272 2025-01-09T18:30:01.168Z,1736447401.168 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:30:05.613Z,1736447405.613 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250109T173712/Express0031.lzma 2025-01-09T18:30:06.832Z,1736447406.832 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0031.lzma.bak 2025-01-09T18:30:06.832Z,1736447406.832 [DataOverHttps](INFO): SBD MOMSN=23890283 2025-01-09T18:30:08.029Z,1736447408.029 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:30:08.029Z,1736447408.029 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:30:08.029Z,1736447408.029 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:30:14.475Z,1736447414.475 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:30:27.809Z,1736447427.809 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:30:41.145Z,1736447441.145 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:30:54.875Z,1736447454.875 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:30:56.092Z,1736447456.092 [NAL9602](INFO): SBD MO Status=0, MOMSN=61584, MT Status=0, MTMSN=0 2025-01-09T18:30:56.092Z,1736447456.092 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:31:09.016Z,1736447469.016 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:31:22.350Z,1736447482.350 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T18:31:26.794Z,1736447486.794 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:35:08.630Z,1736447708.630 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:35:08.630Z,1736447708.630 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:35:08.630Z,1736447708.630 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:35:08.630Z,1736447708.630 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:35:09.039Z,1736447709.039 [Default:CheckIn:D] Stopped 2025-01-09T18:35:09.039Z,1736447709.039 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:35:09.432Z,1736447709.432 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.662817 min 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn:E] Stopped 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn] Stopped 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn](INFO): Running loop #14 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn] Running Loop=14 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:35:09.433Z,1736447709.433 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:35:11.502Z,1736447711.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183510.00,A,3648.13283,N,12147.20926,W,0.058,252.33,090125,,,D*7C 2025-01-09T18:35:11.505Z,1736447711.505 [NAL9602](INFO): GPS fix at 20250109T183510: (36.802214, -121.786821) 2025-01-09T18:35:11.515Z,1736447711.515 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:35:11.515Z,1736447711.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:35:18.872Z,1736447718.872 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0033.lzma 2025-01-09T18:35:19.874Z,1736447719.874 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0033.lzma.bak 2025-01-09T18:35:19.875Z,1736447719.875 [DataOverHttps](INFO): SBD MOMSN=23890287 2025-01-09T18:35:35.676Z,1736447735.676 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250109T173712/Express0034.lzma 2025-01-09T18:35:36.683Z,1736447736.683 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0034.lzma.bak 2025-01-09T18:35:36.683Z,1736447736.683 [DataOverHttps](INFO): SBD MOMSN=23890290 2025-01-09T18:35:37.765Z,1736447737.765 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:35:37.765Z,1736447737.765 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:35:37.765Z,1736447737.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:36:26.616Z,1736447786.616 [NAL9602](INFO): SBD MO Status=2, MOMSN=61585, MT Status=2, MTMSN=0 2025-01-09T18:36:26.616Z,1736447786.616 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T18:36:35.907Z,1736447795.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=61585, MT Status=0, MTMSN=0 2025-01-09T18:36:35.907Z,1736447795.907 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:37:06.609Z,1736447826.609 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:38:33.886Z,1736447913.886 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2025-01-09T18:38:33.889Z,1736447913.889 [BPC1](INFO): Received data from all battery sticks. 2025-01-09T18:40:38.315Z,1736448038.315 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:40:38.315Z,1736448038.315 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:40:38.315Z,1736448038.315 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:40:38.315Z,1736448038.315 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:40:38.724Z,1736448038.724 [Default:CheckIn:D] Stopped 2025-01-09T18:40:38.724Z,1736448038.724 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:40:39.133Z,1736448039.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.157560 min 2025-01-09T18:40:39.133Z,1736448039.133 [Default:CheckIn:E] Stopped 2025-01-09T18:40:39.133Z,1736448039.133 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:40:39.133Z,1736448039.133 [Default:CheckIn] Stopped 2025-01-09T18:40:39.133Z,1736448039.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:40:39.134Z,1736448039.134 [Default:CheckIn](INFO): Running loop #15 2025-01-09T18:40:39.134Z,1736448039.134 [Default:CheckIn] Running Loop=15 2025-01-09T18:40:39.134Z,1736448039.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:40:39.134Z,1736448039.134 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:40:41.149Z,1736448041.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184040.00,A,3648.13208,N,12147.20918,W,0.019,252.33,090125,,,D*70 2025-01-09T18:40:41.151Z,1736448041.151 [NAL9602](INFO): GPS fix at 20250109T184040: (36.802201, -121.786820) 2025-01-09T18:40:41.170Z,1736448041.170 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:40:41.170Z,1736448041.170 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:40:49.385Z,1736448049.385 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250109T173712/Courier0036.lzma 2025-01-09T18:40:50.387Z,1736448050.387 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0036.lzma.bak 2025-01-09T18:40:50.387Z,1736448050.387 [DataOverHttps](INFO): SBD MOMSN=23890302 2025-01-09T18:41:07.839Z,1736448067.839 [NAL9602](INFO): SBD MO Status=2, MOMSN=61586, MT Status=2, MTMSN=0 2025-01-09T18:41:07.840Z,1736448067.840 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T18:41:08.776Z,1736448068.776 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250109T173712/Express0037.lzma 2025-01-09T18:41:09.779Z,1736448069.779 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0037.lzma.bak 2025-01-09T18:41:09.779Z,1736448069.779 [DataOverHttps](INFO): SBD MOMSN=23890309 2025-01-09T18:41:11.109Z,1736448071.109 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:41:11.109Z,1736448071.109 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:41:11.109Z,1736448071.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:41:25.614Z,1736448085.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=61586, MT Status=2, MTMSN=0 2025-01-09T18:41:25.615Z,1736448085.615 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T18:41:34.907Z,1736448094.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=61586, MT Status=0, MTMSN=0 2025-01-09T18:41:34.907Z,1736448094.907 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:42:05.610Z,1736448125.610 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:46:11.748Z,1736448371.748 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:46:11.748Z,1736448371.748 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:46:11.748Z,1736448371.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:46:11.749Z,1736448371.749 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:46:12.148Z,1736448372.148 [Default:CheckIn:D] Stopped 2025-01-09T18:46:12.148Z,1736448372.148 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.714632 min 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn:E] Stopped 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn] Stopped 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn](INFO): Running loop #16 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn] Running Loop=16 2025-01-09T18:46:12.564Z,1736448372.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:46:12.565Z,1736448372.565 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:46:14.563Z,1736448374.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184613.00,A,3648.15045,N,12147.22058,W,1.263,225.18,090125,,,D*75 2025-01-09T18:46:14.565Z,1736448374.565 [NAL9602](INFO): GPS fix at 20250109T184613: (36.802507, -121.787010) 2025-01-09T18:46:14.576Z,1736448374.576 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:46:14.576Z,1736448374.576 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:46:24.740Z,1736448384.740 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0039.lzma 2025-01-09T18:46:25.743Z,1736448385.743 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0039.lzma.bak 2025-01-09T18:46:25.743Z,1736448385.743 [DataOverHttps](INFO): SBD MOMSN=23890313 2025-01-09T18:46:30.775Z,1736448390.775 [NAL9602](INFO): SBD MO Status=0, MOMSN=61587, MT Status=0, MTMSN=0 2025-01-09T18:46:30.775Z,1736448390.775 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:46:41.884Z,1736448401.884 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20250109T173712/Express0040.lzma 2025-01-09T18:46:42.887Z,1736448402.887 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0040.lzma.bak 2025-01-09T18:46:42.887Z,1736448402.887 [DataOverHttps](INFO): SBD MOMSN=23890316 2025-01-09T18:46:44.157Z,1736448404.157 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:46:44.157Z,1736448404.157 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:46:44.157Z,1736448404.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:47:01.525Z,1736448421.525 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:51:44.567Z,1736448704.567 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:51:44.567Z,1736448704.567 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:51:44.567Z,1736448704.567 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:51:44.568Z,1736448704.568 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:51:44.977Z,1736448704.977 [Default:CheckIn:D] Stopped 2025-01-09T18:51:44.977Z,1736448704.977 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:51:45.393Z,1736448705.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.261768 min 2025-01-09T18:51:45.393Z,1736448705.393 [Default:CheckIn:E] Stopped 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn] Stopped 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn](INFO): Running loop #17 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn] Running Loop=17 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:51:45.394Z,1736448705.394 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:51:47.387Z,1736448707.387 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185146.00,A,3648.15018,N,12147.22359,W,0.019,245.52,090125,,,D*7F 2025-01-09T18:51:47.389Z,1736448707.389 [NAL9602](INFO): GPS fix at 20250109T185146: (36.802503, -121.787060) 2025-01-09T18:51:47.400Z,1736448707.400 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:51:47.400Z,1736448707.400 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:51:54.948Z,1736448714.948 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0042.lzma 2025-01-09T18:51:55.951Z,1736448715.951 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0042.lzma.bak 2025-01-09T18:51:55.951Z,1736448715.951 [DataOverHttps](INFO): SBD MOMSN=23890329 2025-01-09T18:52:06.779Z,1736448726.779 [NAL9602](INFO): SBD MO Status=0, MOMSN=61588, MT Status=0, MTMSN=0 2025-01-09T18:52:06.780Z,1736448726.780 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:52:11.812Z,1736448731.812 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250109T173712/Express0043.lzma 2025-01-09T18:52:12.639Z,1736448732.639 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0043.lzma.bak 2025-01-09T18:52:12.639Z,1736448732.639 [DataOverHttps](INFO): SBD MOMSN=23890336 2025-01-09T18:52:14.071Z,1736448734.071 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:52:14.071Z,1736448734.071 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:52:14.071Z,1736448734.071 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:52:37.538Z,1736448757.538 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T18:57:14.540Z,1736449034.540 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T18:57:14.540Z,1736449034.540 [Default:CheckIn:C.Wait] Stopped 2025-01-09T18:57:14.540Z,1736449034.540 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T18:57:14.541Z,1736449034.541 [Default:CheckIn:D] Running Loop=1 2025-01-09T18:57:14.954Z,1736449034.954 [Default:CheckIn:D] Stopped 2025-01-09T18:57:14.954Z,1736449034.954 [Default:CheckIn:E] Running Loop=1 2025-01-09T18:57:15.353Z,1736449035.353 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.761401 min 2025-01-09T18:57:15.353Z,1736449035.353 [Default:CheckIn:E] Stopped 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn] Stopped 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn](INFO): Running loop #18 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn] Running Loop=18 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T18:57:15.354Z,1736449035.354 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T18:57:17.359Z,1736449037.359 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185716.00,A,3648.18037,N,12147.27320,W,0.544,273.46,090125,,,D*7A 2025-01-09T18:57:17.362Z,1736449037.362 [NAL9602](INFO): GPS fix at 20250109T185716: (36.803006, -121.787887) 2025-01-09T18:57:17.388Z,1736449037.388 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T18:57:17.388Z,1736449037.388 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T18:57:25.016Z,1736449045.016 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0045.lzma 2025-01-09T18:57:26.019Z,1736449046.019 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0045.lzma.bak 2025-01-09T18:57:26.019Z,1736449046.019 [DataOverHttps](INFO): SBD MOMSN=23890344 2025-01-09T18:57:42.044Z,1736449062.044 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250109T173712/Express0046.lzma 2025-01-09T18:57:43.047Z,1736449063.047 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0046.lzma.bak 2025-01-09T18:57:43.047Z,1736449063.047 [DataOverHttps](INFO): SBD MOMSN=23890347 2025-01-09T18:57:44.440Z,1736449064.440 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T18:57:44.440Z,1736449064.440 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T18:57:44.440Z,1736449064.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T18:58:11.095Z,1736449091.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=61589, MT Status=0, MTMSN=0 2025-01-09T18:58:11.095Z,1736449091.095 [NAL9602](INFO): No messages in MT queue 2025-01-09T18:58:41.842Z,1736449121.842 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:02:44.870Z,1736449364.870 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:02:44.871Z,1736449364.871 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:02:44.871Z,1736449364.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:02:44.871Z,1736449364.871 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:02:45.263Z,1736449365.263 [Default:CheckIn:D] Stopped 2025-01-09T19:02:45.263Z,1736449365.263 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.266545 min 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn:E] Stopped 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn] Stopped 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn](INFO): Running loop #19 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn] Running Loop=19 2025-01-09T19:02:45.669Z,1736449365.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:02:45.670Z,1736449365.670 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:02:47.681Z,1736449367.681 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190246.00,A,3648.18551,N,12147.27734,W,0.564,319.95,090125,,,D*7B 2025-01-09T19:02:47.683Z,1736449367.683 [NAL9602](INFO): GPS fix at 20250109T190246: (36.803092, -121.787956) 2025-01-09T19:02:47.704Z,1736449367.704 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:02:47.704Z,1736449367.704 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:02:57.880Z,1736449377.880 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0048.lzma 2025-01-09T19:02:58.883Z,1736449378.883 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0048.lzma.bak 2025-01-09T19:02:58.883Z,1736449378.883 [DataOverHttps](INFO): SBD MOMSN=23890486 2025-01-09T19:03:14.536Z,1736449394.536 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250109T173712/Express0049.lzma 2025-01-09T19:03:15.539Z,1736449395.539 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0049.lzma.bak 2025-01-09T19:03:15.539Z,1736449395.539 [DataOverHttps](INFO): SBD MOMSN=23890489 2025-01-09T19:03:16.859Z,1736449396.859 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:03:16.859Z,1736449396.859 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:03:16.859Z,1736449396.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:03:18.053Z,1736449398.053 [NAL9602](INFO): SBD MO Status=2, MOMSN=61590, MT Status=2, MTMSN=0 2025-01-09T19:03:18.053Z,1736449398.053 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:03:37.847Z,1736449417.847 [NAL9602](INFO): SBD MO Status=2, MOMSN=61590, MT Status=2, MTMSN=0 2025-01-09T19:03:37.848Z,1736449417.848 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:03:50.775Z,1736449430.775 [NAL9602](INFO): SBD MO Status=2, MOMSN=61590, MT Status=2, MTMSN=0 2025-01-09T19:03:50.776Z,1736449430.776 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:07:20.267Z,1736449640.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=61590, MT Status=2, MTMSN=0 2025-01-09T19:07:20.267Z,1736449640.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:07:42.079Z,1736449662.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=61590, MT Status=2, MTMSN=0 2025-01-09T19:07:42.079Z,1736449662.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:07:50.162Z,1736449670.162 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-01-09T19:08:17.674Z,1736449697.674 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:08:17.674Z,1736449697.674 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:08:17.674Z,1736449697.674 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:08:17.674Z,1736449697.674 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:08:18.043Z,1736449698.043 [Default:CheckIn:D] Stopped 2025-01-09T19:08:18.043Z,1736449698.043 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.812874 min 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn:E] Stopped 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn] Stopped 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn](INFO): Running loop #20 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn] Running Loop=20 2025-01-09T19:08:18.457Z,1736449698.457 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:08:18.458Z,1736449698.458 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:08:20.459Z,1736449700.459 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190819.00,A,3648.18288,N,12147.27471,W,0.369,186.97,090125,,,A*72 2025-01-09T19:08:20.461Z,1736449700.461 [NAL9602](INFO): GPS fix at 20250109T190819: (36.803048, -121.787912) 2025-01-09T19:08:20.492Z,1736449700.492 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:08:20.492Z,1736449700.492 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:08:30.056Z,1736449710.056 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0051.lzma 2025-01-09T19:08:31.059Z,1736449711.059 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0051.lzma.bak 2025-01-09T19:08:31.059Z,1736449711.059 [DataOverHttps](INFO): SBD MOMSN=23890532 2025-01-09T19:08:46.852Z,1736449726.852 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20250109T173712/Express0052.lzma 2025-01-09T19:08:47.779Z,1736449727.779 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0052.lzma.bak 2025-01-09T19:08:47.779Z,1736449727.779 [DataOverHttps](INFO): SBD MOMSN=23890535 2025-01-09T19:08:49.184Z,1736449729.184 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:08:49.184Z,1736449729.184 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:08:49.184Z,1736449729.184 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:08:52.422Z,1736449732.422 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:13:49.868Z,1736450029.868 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:13:49.868Z,1736450029.868 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:13:49.868Z,1736450029.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:13:49.868Z,1736450029.868 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:13:50.276Z,1736450030.276 [Default:CheckIn:D] Stopped 2025-01-09T19:13:50.277Z,1736450030.277 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.350098 min 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn:E] Stopped 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn] Stopped 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn](INFO): Running loop #21 2025-01-09T19:13:50.688Z,1736450030.688 [Default:CheckIn] Running Loop=21 2025-01-09T19:13:50.689Z,1736450030.689 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:13:50.689Z,1736450030.689 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:13:52.687Z,1736450032.687 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191351.00,A,3648.17540,N,12147.27599,W,0.175,186.97,090125,,,A*70 2025-01-09T19:13:52.689Z,1736450032.689 [NAL9602](INFO): GPS fix at 20250109T191351: (36.802923, -121.787933) 2025-01-09T19:13:52.700Z,1736450032.700 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:13:52.700Z,1736450032.700 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:14:02.584Z,1736450042.584 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0054.lzma 2025-01-09T19:14:03.587Z,1736450043.587 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0054.lzma.bak 2025-01-09T19:14:03.587Z,1736450043.587 [DataOverHttps](INFO): SBD MOMSN=23890552 2025-01-09T19:14:21.032Z,1736450061.032 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250109T173712/Express0055.lzma 2025-01-09T19:14:22.035Z,1736450062.035 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0055.lzma.bak 2025-01-09T19:14:22.035Z,1736450062.035 [DataOverHttps](INFO): SBD MOMSN=23890555 2025-01-09T19:14:23.423Z,1736450063.423 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-01-09T19:14:23.505Z,1736450063.505 [NAL9602](ERROR): received: +CSQ:0 OK590, 2, 0, 0, 0 OK 2025-01-09T19:14:23.542Z,1736450063.542 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:14:23.542Z,1736450063.542 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:14:23.542Z,1736450063.542 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:16:33.903Z,1736450193.903 [NAL9602](INFO): SBD MO Status=0, MOMSN=61590, MT Status=0, MTMSN=0 2025-01-09T19:16:33.903Z,1736450193.903 [NAL9602](INFO): No messages in MT queue 2025-01-09T19:17:04.615Z,1736450224.615 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:19:24.012Z,1736450364.012 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:19:24.012Z,1736450364.012 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:19:24.012Z,1736450364.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:19:24.013Z,1736450364.013 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:19:24.403Z,1736450364.403 [Default:CheckIn:D] Stopped 2025-01-09T19:19:24.403Z,1736450364.403 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.918880 min 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn:E] Stopped 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn] Stopped 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn](INFO): Running loop #22 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn] Running Loop=22 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:19:24.808Z,1736450364.808 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:19:26.821Z,1736450366.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191926.00,A,3648.17671,N,12147.27740,W,0.778,0.00,090125,,,A*77 2025-01-09T19:19:26.823Z,1736450366.823 [NAL9602](INFO): GPS fix at 20250109T191926: (36.802945, -121.787957) 2025-01-09T19:19:26.834Z,1736450366.834 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:19:26.834Z,1736450366.834 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:19:33.804Z,1736450373.804 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0057.lzma 2025-01-09T19:19:34.807Z,1736450374.807 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0057.lzma.bak 2025-01-09T19:19:34.807Z,1736450374.807 [DataOverHttps](INFO): SBD MOMSN=23890563 2025-01-09T19:19:51.008Z,1736450391.008 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250109T173712/Express0058.lzma 2025-01-09T19:19:52.011Z,1736450392.011 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0058.lzma.bak 2025-01-09T19:19:52.011Z,1736450392.011 [DataOverHttps](INFO): SBD MOMSN=23890566 2025-01-09T19:19:53.115Z,1736450393.115 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:19:53.115Z,1736450393.115 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:19:53.115Z,1736450393.115 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:20:10.859Z,1736450410.859 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:20:10.859Z,1736450410.859 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:20:41.563Z,1736450441.563 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:20:41.563Z,1736450441.563 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:21:07.414Z,1736450467.414 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:21:07.415Z,1736450467.415 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:21:37.315Z,1736450497.315 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:21:37.315Z,1736450497.315 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:21:55.895Z,1736450515.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:21:55.896Z,1736450515.896 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:22:11.655Z,1736450531.655 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:22:11.656Z,1736450531.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:24:29.418Z,1736450669.418 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-01-09T19:24:53.818Z,1736450693.818 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:24:53.818Z,1736450693.818 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:24:53.818Z,1736450693.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:24:53.818Z,1736450693.818 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:24:54.188Z,1736450694.188 [Default:CheckIn:D] Stopped 2025-01-09T19:24:54.188Z,1736450694.188 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:24:54.588Z,1736450694.588 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.415299 min 2025-01-09T19:24:54.588Z,1736450694.588 [Default:CheckIn:E] Stopped 2025-01-09T19:24:54.588Z,1736450694.588 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:24:54.621Z,1736450694.621 [Default:CheckIn] Stopped 2025-01-09T19:24:54.621Z,1736450694.621 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:24:54.621Z,1736450694.621 [Default:CheckIn](INFO): Running loop #23 2025-01-09T19:24:54.621Z,1736450694.621 [Default:CheckIn] Running Loop=23 2025-01-09T19:24:54.621Z,1736450694.621 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:24:54.621Z,1736450694.621 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:24:56.560Z,1736450696.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192455.00,A,3648.17796,N,12147.27786,W,0.408,169.42,090125,,,A*73 2025-01-09T19:24:56.562Z,1736450696.562 [NAL9602](INFO): GPS fix at 20250109T192455: (36.802966, -121.787964) 2025-01-09T19:24:56.622Z,1736450696.622 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:24:56.622Z,1736450696.622 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:25:05.116Z,1736450705.116 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0060.lzma 2025-01-09T19:25:06.119Z,1736450706.119 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0060.lzma.bak 2025-01-09T19:25:06.119Z,1736450706.119 [DataOverHttps](INFO): SBD MOMSN=23890594 2025-01-09T19:25:21.884Z,1736450721.884 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250109T173712/Express0061.lzma 2025-01-09T19:25:22.887Z,1736450722.887 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0061.lzma.bak 2025-01-09T19:25:22.887Z,1736450722.887 [DataOverHttps](INFO): SBD MOMSN=23890597 2025-01-09T19:25:24.204Z,1736450724.204 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:25:24.204Z,1736450724.204 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:25:24.204Z,1736450724.204 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:25:28.638Z,1736450728.638 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:30:24.802Z,1736451024.802 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:30:24.802Z,1736451024.802 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:30:24.802Z,1736451024.802 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:30:24.802Z,1736451024.802 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:30:25.200Z,1736451025.200 [Default:CheckIn:D] Stopped 2025-01-09T19:30:25.200Z,1736451025.200 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:30:25.639Z,1736451025.639 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.932153 min 2025-01-09T19:30:25.639Z,1736451025.639 [Default:CheckIn:E] Stopped 2025-01-09T19:30:25.639Z,1736451025.639 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:30:25.639Z,1736451025.639 [Default:CheckIn] Stopped 2025-01-09T19:30:25.639Z,1736451025.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:30:25.640Z,1736451025.640 [Default:CheckIn](INFO): Running loop #24 2025-01-09T19:30:25.640Z,1736451025.640 [Default:CheckIn] Running Loop=24 2025-01-09T19:30:25.640Z,1736451025.640 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:30:25.640Z,1736451025.640 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:30:27.607Z,1736451027.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193026.00,A,3648.17724,N,12147.27984,W,0.544,49.52,090125,,,A*4C 2025-01-09T19:30:27.609Z,1736451027.609 [NAL9602](INFO): GPS fix at 20250109T193026: (36.802954, -121.787997) 2025-01-09T19:30:27.640Z,1736451027.640 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:30:27.640Z,1736451027.640 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:30:35.444Z,1736451035.444 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0063.lzma 2025-01-09T19:30:36.447Z,1736451036.447 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0063.lzma.bak 2025-01-09T19:30:36.447Z,1736451036.447 [DataOverHttps](INFO): SBD MOMSN=23890605 2025-01-09T19:30:55.172Z,1736451055.172 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250109T173712/Express0064.lzma 2025-01-09T19:30:56.175Z,1736451056.175 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0064.lzma.bak 2025-01-09T19:30:56.175Z,1736451056.175 [DataOverHttps](INFO): SBD MOMSN=23890608 2025-01-09T19:30:57.588Z,1736451057.588 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:30:57.588Z,1736451057.588 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:30:57.597Z,1736451057.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:30:58.378Z,1736451058.378 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-01-09T19:30:58.461Z,1736451058.461 [NAL9602](ERROR): received: +CSQ:0 OK591, 2, 0, 0, 0 OK 2025-01-09T19:31:52.115Z,1736451112.115 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2109,HPR45,EXT,TILT 2025-01-09T19:35:25.019Z,1736451325.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:35:25.020Z,1736451325.020 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:35:29.461Z,1736451329.461 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-01-09T19:35:58.160Z,1736451358.160 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:35:58.160Z,1736451358.160 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:35:58.160Z,1736451358.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:35:58.160Z,1736451358.160 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:35:58.570Z,1736451358.570 [Default:CheckIn:D] Stopped 2025-01-09T19:35:58.570Z,1736451358.570 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:35:58.971Z,1736451358.971 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.488322 min 2025-01-09T19:35:58.971Z,1736451358.971 [Default:CheckIn:E] Stopped 2025-01-09T19:35:58.971Z,1736451358.971 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:35:58.971Z,1736451358.971 [Default:CheckIn] Stopped 2025-01-09T19:35:58.972Z,1736451358.972 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:35:58.972Z,1736451358.972 [Default:CheckIn](INFO): Running loop #25 2025-01-09T19:35:58.972Z,1736451358.972 [Default:CheckIn] Running Loop=25 2025-01-09T19:35:58.972Z,1736451358.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:35:58.972Z,1736451358.972 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:36:00.975Z,1736451360.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193600.00,A,3648.17871,N,12147.27618,W,0.583,89.36,090125,,,A*4E 2025-01-09T19:36:00.977Z,1736451360.977 [NAL9602](INFO): GPS fix at 20250109T193600: (36.802979, -121.787936) 2025-01-09T19:36:01.004Z,1736451361.004 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:36:01.004Z,1736451361.004 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:36:07.860Z,1736451367.860 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0066.lzma 2025-01-09T19:36:08.863Z,1736451368.863 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0066.lzma.bak 2025-01-09T19:36:08.863Z,1736451368.863 [DataOverHttps](INFO): SBD MOMSN=23890624 2025-01-09T19:36:27.433Z,1736451387.433 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250109T173712/Express0067.lzma 2025-01-09T19:36:28.435Z,1736451388.435 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0067.lzma.bak 2025-01-09T19:36:28.435Z,1736451388.435 [DataOverHttps](INFO): SBD MOMSN=23890627 2025-01-09T19:36:29.738Z,1736451389.738 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:36:29.738Z,1736451389.738 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:36:29.738Z,1736451389.738 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:36:33.754Z,1736451393.754 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:41:30.320Z,1736451690.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:41:30.320Z,1736451690.320 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:41:30.320Z,1736451690.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:41:30.320Z,1736451690.320 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:41:30.729Z,1736451690.729 [Default:CheckIn:D] Stopped 2025-01-09T19:41:30.729Z,1736451690.729 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:41:31.112Z,1736451691.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.024300 min 2025-01-09T19:41:31.112Z,1736451691.112 [Default:CheckIn:E] Stopped 2025-01-09T19:41:31.112Z,1736451691.112 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:41:31.112Z,1736451691.112 [Default:CheckIn] Stopped 2025-01-09T19:41:31.112Z,1736451691.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:41:31.113Z,1736451691.113 [Default:CheckIn](INFO): Running loop #26 2025-01-09T19:41:31.113Z,1736451691.113 [Default:CheckIn] Running Loop=26 2025-01-09T19:41:31.113Z,1736451691.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:41:31.113Z,1736451691.113 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:41:33.120Z,1736451693.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194132.00,A,3648.17891,N,12147.27586,W,0.389,171.44,090125,,,A*7A 2025-01-09T19:41:33.123Z,1736451693.123 [NAL9602](INFO): GPS fix at 20250109T194132: (36.802982, -121.787931) 2025-01-09T19:41:33.134Z,1736451693.134 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:41:33.134Z,1736451693.134 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:41:40.684Z,1736451700.684 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0069.lzma 2025-01-09T19:41:41.687Z,1736451701.687 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0069.lzma.bak 2025-01-09T19:41:41.687Z,1736451701.687 [DataOverHttps](INFO): SBD MOMSN=23890631 2025-01-09T19:41:57.500Z,1736451717.500 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250109T173712/Express0070.lzma 2025-01-09T19:41:58.503Z,1736451718.503 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0070.lzma.bak 2025-01-09T19:41:58.503Z,1736451718.503 [DataOverHttps](INFO): SBD MOMSN=23890634 2025-01-09T19:41:59.797Z,1736451719.797 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:41:59.798Z,1736451719.798 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:41:59.798Z,1736451719.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:42:03.822Z,1736451723.822 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-01-09T19:42:03.905Z,1736451723.905 [NAL9602](ERROR): received: +SBDI: 2, 61591, 2, 0, 0, 0 OK 2025-01-09T19:42:23.214Z,1736451743.214 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-01-09T19:42:23.214Z,1736451743.214 [DropWeight] Hardware Fault, FailCount= 1 2025-01-09T19:42:23.214Z,1736451743.214 [DropWeight](ERROR): Hardware Fault 2025-01-09T19:42:23.281Z,1736451743.281 [CommandExec](FAULT): Scheduling is paused 2025-01-09T19:42:23.282Z,1736451743.282 [CBIT](INFO): Critical error at 20250109T194223 2025-01-09T19:42:23.284Z,1736451743.284 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-01-09T19:42:23.285Z,1736451743.285 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-01-09T19:42:23.796Z,1736451743.796 [CBIT](INFO): Critical error at 20250109T194223 2025-01-09T19:43:35.123Z,1736451815.123 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:43:48.455Z,1736451828.455 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:44:01.795Z,1736451841.795 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:44:15.120Z,1736451855.120 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:44:28.461Z,1736451868.461 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:44:41.388Z,1736451881.388 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:44:54.734Z,1736451894.734 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:45:08.059Z,1736451908.059 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:45:22.200Z,1736451922.200 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:45:35.935Z,1736451935.935 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:45:49.270Z,1736451949.270 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:46:39.367Z,1736451999.367 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:46:39.367Z,1736451999.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:46:39.770Z,1736451999.770 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-01-09T19:47:00.389Z,1736452020.389 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:47:00.390Z,1736452020.390 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:47:00.390Z,1736452020.390 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:47:00.390Z,1736452020.390 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:47:00.808Z,1736452020.808 [Default:CheckIn:D] Stopped 2025-01-09T19:47:00.808Z,1736452020.808 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:47:01.209Z,1736452021.209 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.525618 min 2025-01-09T19:47:01.209Z,1736452021.209 [Default:CheckIn:E] Stopped 2025-01-09T19:47:01.209Z,1736452021.209 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:47:01.209Z,1736452021.209 [Default:CheckIn] Stopped 2025-01-09T19:47:01.210Z,1736452021.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:47:01.210Z,1736452021.210 [Default:CheckIn](INFO): Running loop #27 2025-01-09T19:47:01.210Z,1736452021.210 [Default:CheckIn] Running Loop=27 2025-01-09T19:47:01.210Z,1736452021.210 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:47:01.210Z,1736452021.210 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:47:03.218Z,1736452023.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194702.00,A,3648.18151,N,12147.27357,W,0.292,8.50,090125,,,A*7E 2025-01-09T19:47:03.221Z,1736452023.221 [NAL9602](INFO): GPS fix at 20250109T194702: (36.803025, -121.787893) 2025-01-09T19:47:03.248Z,1736452023.248 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:47:03.248Z,1736452023.248 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:47:10.368Z,1736452030.368 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20250109T173712/Courier0072.lzma 2025-01-09T19:47:11.371Z,1736452031.371 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0072.lzma.bak 2025-01-09T19:47:11.371Z,1736452031.371 [DataOverHttps](INFO): SBD MOMSN=23890651 2025-01-09T19:47:27.509Z,1736452047.509 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20250109T173712/Express0073.lzma 2025-01-09T19:47:28.511Z,1736452048.511 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0073.lzma.bak 2025-01-09T19:47:28.511Z,1736452048.511 [DataOverHttps](INFO): SBD MOMSN=23890656 2025-01-09T19:47:29.902Z,1736452049.902 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:47:29.902Z,1736452049.902 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:47:29.902Z,1736452049.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:47:35.541Z,1736452055.541 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:52:30.497Z,1736452350.497 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:52:30.497Z,1736452350.497 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:52:30.497Z,1736452350.497 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:52:30.498Z,1736452350.498 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:52:30.912Z,1736452350.912 [Default:CheckIn:D] Stopped 2025-01-09T19:52:30.912Z,1736452350.912 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:52:31.296Z,1736452351.296 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.027360 min 2025-01-09T19:52:31.296Z,1736452351.296 [Default:CheckIn:E] Stopped 2025-01-09T19:52:31.296Z,1736452351.296 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:52:31.296Z,1736452351.296 [Default:CheckIn] Stopped 2025-01-09T19:52:31.296Z,1736452351.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:52:31.297Z,1736452351.297 [Default:CheckIn](INFO): Running loop #28 2025-01-09T19:52:31.297Z,1736452351.297 [Default:CheckIn] Running Loop=28 2025-01-09T19:52:31.297Z,1736452351.297 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:52:31.297Z,1736452351.297 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:52:33.311Z,1736452353.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195232.00,A,3648.17617,N,12147.27662,W,0.078,338.07,090125,,,A*74 2025-01-09T19:52:33.314Z,1736452353.314 [NAL9602](INFO): GPS fix at 20250109T195232: (36.802936, -121.787944) 2025-01-09T19:52:33.325Z,1736452353.325 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:52:33.325Z,1736452353.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:52:41.340Z,1736452361.340 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250109T173712/Courier0075.lzma 2025-01-09T19:52:42.343Z,1736452362.343 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0075.lzma.bak 2025-01-09T19:52:42.343Z,1736452362.343 [DataOverHttps](INFO): SBD MOMSN=23890662 2025-01-09T19:53:01.676Z,1736452381.676 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250109T173712/Express0076.lzma 2025-01-09T19:53:02.679Z,1736452382.679 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0076.lzma.bak 2025-01-09T19:53:02.679Z,1736452382.679 [DataOverHttps](INFO): SBD MOMSN=23890666 2025-01-09T19:53:04.076Z,1736452384.076 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:53:04.076Z,1736452384.076 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:53:04.076Z,1736452384.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:53:14.555Z,1736452394.555 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:53:28.293Z,1736452408.293 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:53:34.763Z,1736452414.763 [NAL9602](INFO): SBD MO Status=2, MOMSN=61591, MT Status=2, MTMSN=0 2025-01-09T19:53:34.763Z,1736452414.763 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T19:53:42.027Z,1736452422.027 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:53:49.769Z,1736452429.769 [NAL9602](INFO): SBD MO Status=0, MOMSN=61591, MT Status=0, MTMSN=0 2025-01-09T19:53:49.770Z,1736452429.770 [NAL9602](INFO): No messages in MT queue 2025-01-09T19:53:55.359Z,1736452435.359 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:54:09.097Z,1736452449.097 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:54:20.409Z,1736452460.409 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T19:54:23.236Z,1736452463.236 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:54:36.973Z,1736452476.973 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:54:51.515Z,1736452491.515 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:55:05.655Z,1736452505.655 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:55:19.391Z,1736452519.391 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T19:57:23.481Z,1736452643.481 [CBIT](INFO): Clearing failed state for component DropWeight 2025-01-09T19:57:23.481Z,1736452643.481 [DropWeight] No Fault, FailCount= 1 2025-01-09T19:58:04.666Z,1736452684.666 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T19:58:04.666Z,1736452684.666 [Default:CheckIn:C.Wait] Stopped 2025-01-09T19:58:04.666Z,1736452684.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T19:58:04.667Z,1736452684.667 [Default:CheckIn:D] Running Loop=1 2025-01-09T19:58:05.060Z,1736452685.060 [Default:CheckIn:D] Stopped 2025-01-09T19:58:05.060Z,1736452685.060 [Default:CheckIn:E] Running Loop=1 2025-01-09T19:58:05.464Z,1736452685.464 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.596484 min 2025-01-09T19:58:05.464Z,1736452685.464 [Default:CheckIn:E] Stopped 2025-01-09T19:58:05.464Z,1736452685.464 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T19:58:05.464Z,1736452685.464 [Default:CheckIn] Stopped 2025-01-09T19:58:05.464Z,1736452685.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T19:58:05.465Z,1736452685.465 [Default:CheckIn](INFO): Running loop #29 2025-01-09T19:58:05.465Z,1736452685.465 [Default:CheckIn] Running Loop=29 2025-01-09T19:58:05.465Z,1736452685.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T19:58:05.465Z,1736452685.465 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T19:58:07.473Z,1736452687.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195806.00,A,3648.17411,N,12147.27519,W,0.175,338.07,090125,,,A*7E 2025-01-09T19:58:07.475Z,1736452687.475 [NAL9602](INFO): GPS fix at 20250109T195806: (36.802902, -121.787920) 2025-01-09T19:58:07.487Z,1736452687.487 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T19:58:07.487Z,1736452687.487 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T19:58:14.748Z,1736452694.748 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0078.lzma 2025-01-09T19:58:15.751Z,1736452695.751 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0078.lzma.bak 2025-01-09T19:58:15.751Z,1736452695.751 [DataOverHttps](INFO): SBD MOMSN=23890684 2025-01-09T19:58:27.764Z,1736452707.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=61592, MT Status=0, MTMSN=0 2025-01-09T19:58:27.764Z,1736452707.764 [NAL9602](INFO): No messages in MT queue 2025-01-09T19:58:34.296Z,1736452714.296 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250109T173712/Express0079.lzma 2025-01-09T19:58:35.299Z,1736452715.299 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0079.lzma.bak 2025-01-09T19:58:35.299Z,1736452715.299 [DataOverHttps](INFO): SBD MOMSN=23890687 2025-01-09T19:58:36.715Z,1736452716.715 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T19:58:36.715Z,1736452716.715 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T19:58:36.715Z,1736452716.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T19:58:58.467Z,1736452738.467 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T20:02:51.571Z,1736452971.571 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:03:05.306Z,1736452985.306 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:03:19.049Z,1736452999.049 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:03:33.175Z,1736453013.175 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:03:37.236Z,1736453017.236 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T20:03:37.236Z,1736453017.236 [Default:CheckIn:C.Wait] Stopped 2025-01-09T20:03:37.236Z,1736453017.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T20:03:37.237Z,1736453017.237 [Default:CheckIn:D] Running Loop=1 2025-01-09T20:03:37.633Z,1736453017.633 [Default:CheckIn:D] Stopped 2025-01-09T20:03:37.633Z,1736453017.633 [Default:CheckIn:E] Running Loop=1 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.139388 min 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn:E] Stopped 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn] Stopped 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn](INFO): Running loop #30 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn] Running Loop=30 2025-01-09T20:03:38.041Z,1736453018.041 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T20:03:38.042Z,1736453018.042 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T20:03:40.049Z,1736453020.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200339.00,A,3648.17756,N,12147.27696,W,0.214,258.68,090125,,,A*78 2025-01-09T20:03:40.051Z,1736453020.051 [NAL9602](INFO): GPS fix at 20250109T200339: (36.802959, -121.787949) 2025-01-09T20:03:40.062Z,1736453020.062 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T20:03:40.062Z,1736453020.062 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T20:03:46.911Z,1736453026.911 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:03:47.184Z,1736453027.184 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250109T173712/Courier0081.lzma 2025-01-09T20:03:48.187Z,1736453028.187 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0081.lzma.bak 2025-01-09T20:03:48.187Z,1736453028.187 [DataOverHttps](INFO): SBD MOMSN=23890703 2025-01-09T20:04:00.243Z,1736453040.243 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:04:04.048Z,1736453044.048 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20250109T173712/Express0082.lzma 2025-01-09T20:04:05.051Z,1736453045.051 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0082.lzma.bak 2025-01-09T20:04:05.051Z,1736453045.051 [DataOverHttps](INFO): SBD MOMSN=23890706 2025-01-09T20:04:06.321Z,1736453046.321 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T20:04:06.322Z,1736453046.322 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T20:04:06.322Z,1736453046.322 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T20:04:14.788Z,1736453054.788 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:04:29.332Z,1736453069.332 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:04:43.068Z,1736453083.068 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:04:45.518Z,1736453085.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=61593, MT Status=2, MTMSN=0 2025-01-09T20:04:45.518Z,1736453085.518 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T20:04:57.207Z,1736453097.207 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:05:23.895Z,1736453123.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=61593, MT Status=2, MTMSN=0 2025-01-09T20:05:23.895Z,1736453123.895 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T20:05:40.860Z,1736453140.860 [NAL9602](INFO): SBD MO Status=2, MOMSN=61593, MT Status=2, MTMSN=0 2025-01-09T20:05:40.860Z,1736453140.860 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T20:05:58.220Z,1736453158.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=61593, MT Status=2, MTMSN=0 2025-01-09T20:05:58.220Z,1736453158.220 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T20:06:43.471Z,1736453203.471 [NAL9602](INFO): SBD MO Status=2, MOMSN=61593, MT Status=2, MTMSN=0 2025-01-09T20:06:43.472Z,1736453203.472 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-01-09T20:06:58.015Z,1736453218.015 [NAL9602](INFO): SBD MO Status=0, MOMSN=61593, MT Status=0, MTMSN=0 2025-01-09T20:06:58.015Z,1736453218.015 [NAL9602](INFO): No messages in MT queue 2025-01-09T20:07:28.714Z,1736453248.714 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T20:09:06.908Z,1736453346.908 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T20:09:06.908Z,1736453346.908 [Default:CheckIn:C.Wait] Stopped 2025-01-09T20:09:06.908Z,1736453346.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T20:09:06.909Z,1736453346.909 [Default:CheckIn:D] Running Loop=1 2025-01-09T20:09:07.315Z,1736453347.315 [Default:CheckIn:D] Stopped 2025-01-09T20:09:07.315Z,1736453347.315 [Default:CheckIn:E] Running Loop=1 2025-01-09T20:09:07.716Z,1736453347.716 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.634082 min 2025-01-09T20:09:07.716Z,1736453347.716 [Default:CheckIn:E] Stopped 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn] Stopped 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn](INFO): Running loop #31 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn] Running Loop=31 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T20:09:07.717Z,1736453347.717 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T20:09:09.727Z,1736453349.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200909.00,A,3648.18577,N,12147.26983,W,0.175,210.88,090125,,,A*73 2025-01-09T20:09:09.730Z,1736453349.730 [NAL9602](INFO): GPS fix at 20250109T200909: (36.803096, -121.787830) 2025-01-09T20:09:09.741Z,1736453349.741 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T20:09:09.741Z,1736453349.741 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T20:09:17.144Z,1736453357.144 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250109T173712/Courier0084.lzma 2025-01-09T20:09:18.147Z,1736453358.147 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0084.lzma.bak 2025-01-09T20:09:18.147Z,1736453358.147 [DataOverHttps](INFO): SBD MOMSN=23890729 2025-01-09T20:09:33.980Z,1736453373.980 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250109T173712/Express0085.lzma 2025-01-09T20:09:34.774Z,1736453374.774 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-01-09T20:09:34.778Z,1736453374.778 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 37, 38, 39, 50, 51, 54. 2025-01-09T20:09:34.983Z,1736453374.983 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0085.lzma.bak 2025-01-09T20:09:34.983Z,1736453374.983 [DataOverHttps](INFO): SBD MOMSN=23890732 2025-01-09T20:09:36.405Z,1736453376.405 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T20:09:36.405Z,1736453376.405 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T20:09:36.405Z,1736453376.405 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T20:14:12.350Z,1736453652.350 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-01-09T20:14:37.000Z,1736453677.000 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T20:14:37.001Z,1736453677.001 [Default:CheckIn:C.Wait] Stopped 2025-01-09T20:14:37.001Z,1736453677.001 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T20:14:37.001Z,1736453677.001 [Default:CheckIn:D] Running Loop=1 2025-01-09T20:14:37.408Z,1736453677.408 [Default:CheckIn:D] Stopped 2025-01-09T20:14:37.409Z,1736453677.409 [Default:CheckIn:E] Running Loop=1 2025-01-09T20:14:37.813Z,1736453677.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.135628 min 2025-01-09T20:14:37.813Z,1736453677.813 [Default:CheckIn:E] Stopped 2025-01-09T20:14:37.813Z,1736453677.813 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T20:14:37.814Z,1736453677.814 [Default:CheckIn] Stopped 2025-01-09T20:14:37.814Z,1736453677.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T20:14:37.814Z,1736453677.814 [Default:CheckIn](INFO): Running loop #32 2025-01-09T20:14:37.814Z,1736453677.814 [Default:CheckIn] Running Loop=32 2025-01-09T20:14:37.814Z,1736453677.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T20:14:37.814Z,1736453677.814 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T20:14:39.824Z,1736453679.824 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201439.00,A,3648.18872,N,12147.26177,W,0.214,221.31,090125,,,A*73 2025-01-09T20:14:39.826Z,1736453679.826 [NAL9602](INFO): GPS fix at 20250109T201439: (36.803145, -121.787696) 2025-01-09T20:14:39.854Z,1736453679.854 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T20:14:39.854Z,1736453679.854 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T20:14:47.040Z,1736453687.040 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250109T173712/Courier0087.lzma 2025-01-09T20:14:48.043Z,1736453688.043 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0087.lzma.bak 2025-01-09T20:14:48.043Z,1736453688.043 [DataOverHttps](INFO): SBD MOMSN=23890743 2025-01-09T20:15:06.457Z,1736453706.457 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20250109T173712/Express0088.lzma 2025-01-09T20:15:07.459Z,1736453707.459 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0088.lzma.bak 2025-01-09T20:15:07.459Z,1736453707.459 [DataOverHttps](INFO): SBD MOMSN=23890746 2025-01-09T20:15:08.572Z,1736453708.572 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T20:15:08.572Z,1736453708.572 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T20:15:08.572Z,1736453708.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T20:15:12.610Z,1736453712.610 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T20:20:09.171Z,1736454009.171 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-01-09T20:20:09.171Z,1736454009.171 [Default:CheckIn:C.Wait] Stopped 2025-01-09T20:20:09.171Z,1736454009.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T20:20:09.171Z,1736454009.171 [Default:CheckIn:D] Running Loop=1 2025-01-09T20:20:09.572Z,1736454009.572 [Default:CheckIn:D] Stopped 2025-01-09T20:20:09.572Z,1736454009.572 [Default:CheckIn:E] Running Loop=1 2025-01-09T20:20:09.976Z,1736454009.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.671696 min 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn:E] Stopped 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn] Stopped 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn](INFO): Running loop #33 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn] Running Loop=33 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-01-09T20:20:09.977Z,1736454009.977 [Default:CheckIn:Read_GPS] Running Loop=1 2025-01-09T20:20:42.724Z,1736454042.724 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-01-09T20:20:42.801Z,1736454042.801 [NAL9602](ERROR): received: +CSQ:0 OK593, 0, 0, 0, 0 OK 2025-01-09T20:22:33.878Z,1736454153.878 [CBIT](IMPORTANT): Beginning ground fault scan 2025-01-09T20:22:36.734Z,1736454156.734 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2025-01-09T20:22:44.789Z,1736454164.789 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002113 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.001927 CHAN A3 (5V): -0.001418 CHAN B0 (3.3V): -0.000090 CHAN B1 (3.15aV): 0.000133 CHAN B2 (3.15bV): -0.000288 CHAN B3 (GND): -0.000257 OPEN: -0.000424 Full Scale: +/- 1 mA 2025-01-09T20:24:35.577Z,1736454275.577 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:24:49.728Z,1736454289.728 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:25:03.111Z,1736454303.111 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:25:10.059Z,1736454310.059 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-01-09T20:20:09.0Z 2025-01-09T20:25:10.059Z,1736454310.059 [Default:CheckIn:Read_GPS] Stopped 2025-01-09T20:25:10.060Z,1736454310.060 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-01-09T20:25:14.026Z,1736454314.026 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-01-09T20:25:16.443Z,1736454316.443 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:25:17.860Z,1736454317.860 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250109T173712/Courier0090.lzma 2025-01-09T20:25:18.864Z,1736454318.864 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Courier0090.lzma.bak 2025-01-09T20:25:18.865Z,1736454318.865 [DataOverHttps](INFO): SBD MOMSN=23890786 2025-01-09T20:25:29.775Z,1736454329.775 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:25:43.511Z,1736454343.511 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:25:44.730Z,1736454344.730 [NAL9602](INFO): Not Powering down - fast GPS 2025-01-09T20:25:56.843Z,1736454356.843 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:26:06.557Z,1736454366.557 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20250109T173712/Express0091.lzma 2025-01-09T20:26:07.559Z,1736454367.559 [DataOverHttps](INFO): Moved sent file to Logs/20250109T173712/Express0091.lzma.bak 2025-01-09T20:26:07.559Z,1736454367.559 [DataOverHttps](INFO): SBD MOMSN=23890790 2025-01-09T20:26:08.991Z,1736454368.991 [Default:CheckIn:Read_Iridium] Stopped 2025-01-09T20:26:08.992Z,1736454368.992 [Default:CheckIn:C.Wait] Running Loop=1 2025-01-09T20:26:08.992Z,1736454368.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-01-09T20:26:10.176Z,1736454370.176 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:26:23.911Z,1736454383.911 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:26:36.840Z,1736454396.840 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:26:50.178Z,1736454410.178 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-01-09T20:27:24.917Z,1736454444.917 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-01-09T20:27:24.917Z,1736454444.917 [DropWeight] Hardware Fault, FailCount= 1 2025-01-09T20:27:24.918Z,1736454444.918 [DropWeight](ERROR): Hardware Fault 2025-01-09T20:27:24.941Z,1736454444.941 [CBIT](INFO): Critical error at 20250109T202724 2025-01-09T20:27:24.943Z,1736454444.943 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-01-09T20:27:24.944Z,1736454444.944 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-01-09T20:27:25.349Z,1736454445.349 [CBIT](INFO): Critical error at 20250109T202724 2025-01-09T20:28:10.582Z,1736454490.582 [CommandExec](IMPORTANT): got command 2025-01-09T20:28:10.582Z,1736454490.582 [CommandExec](FAULT): Incomplete syntax. Try: help 2025-01-09T20:28:12.247Z,1736454492.247 [CommandExec](IMPORTANT): got command failComponent 2025-01-09T20:28:12.247Z,1736454492.247 [CommandExec](IMPORTANT): Failed components: 2025-01-09T20:28:12.249Z,1736454492.249 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2025-01-09T20:28:22.485Z,1736454502.485 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2025-01-09T20:28:22.485Z,1736454502.485 [CommandExec](IMPORTANT): Onboard.Pressure 9.160066 psi 2025-01-09T20:28:29.453Z,1736454509.453 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2025-01-09T20:28:29.454Z,1736454509.454 [CommandExec](IMPORTANT): Onboard.Humidity 15.162984 % 2025-01-09T20:28:32.381Z,1736454512.381 [CommandExec](IMPORTANT): got command quit 2025-01-09T20:28:33.385Z,1736454513.385 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:33.385Z,1736454513.385 [CommandExec](INFO): Uninitializing the command executive. 2025-01-09T20:28:33.385Z,1736454513.385 [CommandExec](INFO): Uninitializing the command scheduler. 2025-01-09T20:28:33.385Z,1736454513.385 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:33.473Z,1736454513.473 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-01-09T20:28:33.473Z,1736454513.473 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-01-09T20:28:33.473Z,1736454513.473 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:33.474Z,1736454513.474 [NavChartDb](INFO): Join timeout helper Thread ID is 8281 2025-01-09T20:28:33.741Z,1736454513.741 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:33.741Z,1736454513.741 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:33.761Z,1736454513.761 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-01-09T20:28:33.761Z,1736454513.761 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:33.761Z,1736454513.761 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8282 2025-01-09T20:28:34.249Z,1736454514.249 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:34.249Z,1736454514.249 [WetLabsBB2FL](INFO): Powering down 2025-01-09T20:28:34.250Z,1736454514.250 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:34.261Z,1736454514.261 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-01-09T20:28:34.261Z,1736454514.261 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:34.261Z,1736454514.261 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8283 2025-01-09T20:28:35.257Z,1736454515.257 [CTD_Seabird](INFO): Powering down 2025-01-09T20:28:35.269Z,1736454515.269 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:35.269Z,1736454515.269 [CTD_Seabird](INFO): Powering down 2025-01-09T20:28:35.281Z,1736454515.281 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:35.289Z,1736454515.289 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-01-09T20:28:35.289Z,1736454515.289 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:35.289Z,1736454515.289 [Radio_Surface](INFO): Join timeout helper Thread ID is 8284 2025-01-09T20:28:35.533Z,1736454515.533 [Radio_Surface](INFO): Powering down 2025-01-09T20:28:35.534Z,1736454515.534 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:35.534Z,1736454515.534 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:35.545Z,1736454515.545 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-01-09T20:28:35.545Z,1736454515.545 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:35.545Z,1736454515.545 [Onboard](INFO): Join timeout helper Thread ID is 8285 2025-01-09T20:28:35.985Z,1736454515.985 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-01-09T20:28:36.706Z,1736454516.706 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:36.707Z,1736454516.707 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:36.714Z,1736454516.714 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-01-09T20:28:36.714Z,1736454516.714 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:36.714Z,1736454516.714 [DataOverHttps](INFO): Join timeout helper Thread ID is 8286 2025-01-09T20:28:36.989Z,1736454516.989 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:36.989Z,1736454516.989 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.001Z,1736454517.001 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-01-09T20:28:37.002Z,1736454517.002 [logger ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.002Z,1736454517.002 [logger](INFO): Join timeout helper Thread ID is 8287 2025-01-09T20:28:37.017Z,1736454517.017 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:37.017Z,1736454517.017 [logger ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.022Z,1736454517.022 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-01-09T20:28:37.022Z,1736454517.022 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.022Z,1736454517.022 [CommandLine](INFO): Join timeout helper Thread ID is 8288 2025-01-09T20:28:37.025Z,1736454517.025 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:37.025Z,1736454517.025 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.041Z,1736454517.041 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-01-09T20:28:37.042Z,1736454517.042 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.042Z,1736454517.042 [CommandExec](INFO): Join timeout helper Thread ID is 8289 2025-01-09T20:28:37.043Z,1736454517.043 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-01-09T20:28:37.043Z,1736454517.043 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.044Z,1736454517.044 [controlThread](INFO): Join timeout helper Thread ID is 8290 2025-01-09T20:28:37.217Z,1736454517.217 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-01-09T20:28:37.217Z,1736454517.217 [controlThread](DEBUG): Uninitializing ControlThread 2025-01-09T20:28:37.218Z,1736454517.218 [AHRS_M2](INFO): Powering down 2025-01-09T20:28:37.290Z,1736454517.290 [NAL9602](INFO): Powering down 2025-01-09T20:28:37.291Z,1736454517.291 [Sonardyne_Nano](INFO): Powering down 2025-01-09T20:28:37.410Z,1736454517.410 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-01-09T20:28:37.411Z,1736454517.411 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-01-09T20:28:37.412Z,1736454517.412 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-01-09T20:28:37.412Z,1736454517.412 [MissionManager](INFO): Uninitializing Mission Default 2025-01-09T20:28:37.412Z,1736454517.412 [Default] Stopped 2025-01-09T20:28:37.412Z,1736454517.412 [Default](DEBUG): Aggregate::uninitialize Default 2025-01-09T20:28:37.412Z,1736454517.412 [Default:B.GoToSurface] Stopped 2025-01-09T20:28:37.412Z,1736454517.412 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-01-09T20:28:37.413Z,1736454517.413 [Default:CheckIn] Stopped 2025-01-09T20:28:37.413Z,1736454517.413 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-01-09T20:28:37.413Z,1736454517.413 [Default:CheckIn:C.Wait] Stopped 2025-01-09T20:28:37.413Z,1736454517.413 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-01-09T20:28:37.416Z,1736454517.416 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-01-09T20:28:37.416Z,1736454517.416 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-01-09T20:28:37.416Z,1736454517.416 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-01-09T20:28:37.416Z,1736454517.416 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-01-09T20:28:37.417Z,1736454517.417 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-01-09T20:28:37.417Z,1736454517.417 [BuoyancyServo](INFO): Powering down 2025-01-09T20:28:37.429Z,1736454517.429 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-01-09T20:28:37.429Z,1736454517.429 [ElevatorServo](INFO): Powering down 2025-01-09T20:28:37.430Z,1736454517.430 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-01-09T20:28:37.430Z,1736454517.430 [MassServo](INFO): Powering down 2025-01-09T20:28:37.431Z,1736454517.431 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-01-09T20:28:37.431Z,1736454517.431 [RudderServo](INFO): Powering down 2025-01-09T20:28:37.432Z,1736454517.432 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-01-09T20:28:37.432Z,1736454517.432 [ThrusterHE](INFO): Powering down 2025-01-09T20:28:37.432Z,1736454517.432 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-01-09T20:28:37.433Z,1736454517.433 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-01-09T20:28:37.433Z,1736454517.433 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-01-09T20:28:37.433Z,1736454517.433 [CBIT](DEBUG): Powering off loads. 2025-01-09T20:28:37.445Z,1736454517.445 [CBIT](DEBUG): Disabling WDT. 2025-01-09T20:28:37.457Z,1736454517.457 [CBIT](DEBUG): Opening all GF detection circuits. 2025-01-09T20:28:37.457Z,1736454517.457 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.507Z,1736454517.507 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.510Z,1736454517.510 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.516Z,1736454517.516 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.590Z,1736454517.590 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.595Z,1736454517.595 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.637Z,1736454517.637 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-01-09T20:28:37.742Z,1736454517.742 [logger ThreadHandler](INFO): Thread cancelled.