2025-09-26T18:52:55.937Z,1758912775.937 [CommandExec](IMPORTANT): got command restart logs
2025-09-26T18:53:05.957Z,1758912785.957 [DataOverHttps](INFO): Moved sent file to Logs/20250925T225043/Express0125.lzma.bak
2025-09-26T18:53:05.957Z,1758912785.957 [DataOverHttps](INFO): SBD MOMSN=26079147
2025-09-26T18:53:39.814Z,1758912819.814 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250925T225043/Courier0127.lzma
2025-09-26T18:53:50.857Z,1758912830.857 [DataOverHttps](INFO): Moved sent file to Logs/20250925T225043/Courier0127.lzma.bak
2025-09-26T18:53:50.857Z,1758912830.857 [DataOverHttps](INFO): SBD MOMSN=26079151
2025-09-26T18:54:17.815Z,1758912857.815 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250925T225043/Express0128.lzma
2025-09-26T18:54:28.857Z,1758912868.857 [DataOverHttps](INFO): Moved sent file to Logs/20250925T225043/Express0128.lzma.bak
2025-09-26T18:54:28.857Z,1758912868.857 [DataOverHttps](INFO): SBD MOMSN=26079154
2025-09-26T18:54:30.420Z,1758912870.420 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T18:54:30.420Z,1758912870.420 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T18:54:30.420Z,1758912870.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T18:59:31.129Z,1758913171.129 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T18:59:31.129Z,1758913171.129 [Default:CheckIn:C.Wait] Stopped
2025-09-26T18:59:31.129Z,1758913171.129 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T18:59:31.129Z,1758913171.129 [Default:CheckIn:D] Running Loop=1
2025-09-26T18:59:31.537Z,1758913171.537 [Default:CheckIn:D] Stopped
2025-09-26T18:59:31.538Z,1758913171.538 [Default:CheckIn:E] Running Loop=1
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  27.762592 min 
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn:E] Stopped
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn] Stopped
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn](INFO): Running loop #5
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn] Running Loop=5
2025-09-26T18:59:31.937Z,1758913171.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T18:59:31.938Z,1758913171.938 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T18:59:33.946Z,1758913173.946 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185933.00,A,3648.09854,N,12147.82937,W,16.970,82.90,260925,,,A*76
2025-09-26T18:59:33.949Z,1758913173.949 [NAL9602](INFO): GPS fix at 20250926T185933: (36.801642, -121.797156)
2025-09-26T18:59:33.960Z,1758913173.960 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T18:59:33.960Z,1758913173.960 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T18:59:42.587Z,1758913182.587 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250926T185255/Courier0003.lzma
2025-09-26T18:59:53.645Z,1758913193.645 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0003.lzma.bak
2025-09-26T18:59:53.645Z,1758913193.645 [DataOverHttps](INFO): SBD MOMSN=26079157
2025-09-26T18:59:55.421Z,1758913195.421 [NAL9602](INFO): SBD MO Status=1, MOMSN=13921, MT Status=0, MTMSN=0
2025-09-26T18:59:55.422Z,1758913195.422 [NAL9602](INFO): No messages in MT queue
2025-09-26T19:00:20.627Z,1758913220.627 [DataOverHttps](INFO): Sending 498 bytes from file Logs/20250926T185255/Express0004.lzma
2025-09-26T19:00:26.125Z,1758913226.125 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:00:31.664Z,1758913231.664 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0004.lzma.bak
2025-09-26T19:00:31.665Z,1758913231.665 [DataOverHttps](INFO): SBD MOMSN=26079163
2025-09-26T19:00:33.030Z,1758913233.030 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:00:33.030Z,1758913233.030 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:00:33.030Z,1758913233.030 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:05:33.718Z,1758913533.718 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T19:05:33.718Z,1758913533.718 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:05:33.718Z,1758913533.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:05:33.718Z,1758913533.718 [Default:CheckIn:D] Running Loop=1
2025-09-26T19:05:34.142Z,1758913534.142 [Default:CheckIn:D] Stopped
2025-09-26T19:05:34.142Z,1758913534.142 [Default:CheckIn:E] Running Loop=1
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  33.805994 min 
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn:E] Stopped
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn] Stopped
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn](INFO): Running loop #6
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn] Running Loop=6
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T19:05:34.542Z,1758913534.542 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T19:05:36.537Z,1758913536.537 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190535.00,A,3648.24010,N,12147.14002,W,3.616,186.33,260925,,,A*78
2025-09-26T19:05:36.540Z,1758913536.540 [NAL9602](INFO): GPS fix at 20250926T190535: (36.804002, -121.785667)
2025-09-26T19:05:36.550Z,1758913536.550 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T19:05:36.550Z,1758913536.550 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T19:05:46.162Z,1758913546.162 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250926T185255/Courier0006.lzma
2025-09-26T19:05:57.205Z,1758913557.205 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0006.lzma.bak
2025-09-26T19:05:57.205Z,1758913557.205 [DataOverHttps](INFO): SBD MOMSN=26079167
2025-09-26T19:06:00.169Z,1758913560.169 [NAL9602](INFO): SBD MO Status=1, MOMSN=13922, MT Status=0, MTMSN=0
2025-09-26T19:06:00.169Z,1758913560.169 [NAL9602](INFO): No messages in MT queue
2025-09-26T19:06:03.077Z,1758913563.077 [MassServo](FAULT): Invalid EZ Servo response:"9\r"
2025-09-26T19:06:03.077Z,1758913563.077 [MassServo] Communications Fault, FailCount= 1
2025-09-26T19:06:03.077Z,1758913563.077 [MassServo](ERROR): Communications Fault
2025-09-26T19:06:03.105Z,1758913563.105 [CBIT](ERROR): Communications Fault in component: MassServo
2025-09-26T19:06:03.449Z,1758913563.449 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-26T19:06:03.449Z,1758913563.449 [MassServo](INFO): Powering down
2025-09-26T19:06:04.247Z,1758913564.247 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-26T19:06:04.369Z,1758913564.369 [MassServo](DEBUG): Initializing MassServo.
2025-09-26T19:06:04.383Z,1758913564.383 [CBIT](INFO): Clearing failed state for component MassServo
2025-09-26T19:06:04.383Z,1758913564.383 [MassServo] No Fault, FailCount= 1
2025-09-26T19:06:24.435Z,1758913584.435 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20250926T185255/Express0007.lzma
2025-09-26T19:06:30.885Z,1758913590.885 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:06:35.478Z,1758913595.478 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0007.lzma.bak
2025-09-26T19:06:35.478Z,1758913595.478 [DataOverHttps](INFO): SBD MOMSN=26079170
2025-09-26T19:06:36.957Z,1758913596.957 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:06:36.957Z,1758913596.957 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:06:36.957Z,1758913596.957 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:11:37.596Z,1758913897.596 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T19:11:37.596Z,1758913897.596 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:11:37.596Z,1758913897.596 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:11:37.596Z,1758913897.596 [Default:CheckIn:D] Running Loop=1
2025-09-26T19:11:37.972Z,1758913897.972 [Default:CheckIn:D] Stopped
2025-09-26T19:11:37.972Z,1758913897.972 [Default:CheckIn:E] Running Loop=1
2025-09-26T19:11:38.377Z,1758913898.377 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  39.869836 min 
2025-09-26T19:11:38.377Z,1758913898.377 [Default:CheckIn:E] Stopped
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn] Stopped
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn](INFO): Running loop #7
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn] Running Loop=7
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T19:11:38.378Z,1758913898.378 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T19:11:40.396Z,1758913900.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191139.00,A,3648.15217,N,12147.21378,W,0.058,196.82,260925,,,D*7F
2025-09-26T19:11:40.398Z,1758913900.398 [NAL9602](INFO): GPS fix at 20250926T191139: (36.802536, -121.786896)
2025-09-26T19:11:40.409Z,1758913900.409 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T19:11:40.409Z,1758913900.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T19:11:51.311Z,1758913911.311 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250926T185255/Courier0009.lzma
2025-09-26T19:11:54.405Z,1758913914.405 [NAL9602](INFO): SBD MO Status=1, MOMSN=13923, MT Status=0, MTMSN=0
2025-09-26T19:11:54.406Z,1758913914.406 [NAL9602](INFO): No messages in MT queue
2025-09-26T19:12:02.353Z,1758913922.353 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0009.lzma.bak
2025-09-26T19:12:02.353Z,1758913922.353 [DataOverHttps](INFO): SBD MOMSN=26079175
2025-09-26T19:12:24.912Z,1758913944.912 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:12:35.475Z,1758913955.475 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250926T185255/Express0010.lzma
2025-09-26T19:12:46.526Z,1758913966.526 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0010.lzma.bak
2025-09-26T19:12:46.526Z,1758913966.526 [DataOverHttps](INFO): SBD MOMSN=26079180
2025-09-26T19:12:48.046Z,1758913968.046 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:12:48.046Z,1758913968.046 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:12:48.046Z,1758913968.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:13:47.863Z,1758914027.863 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-26T19:13:49.904Z,1758914029.904 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-26T19:13:49.904Z,1758914029.904 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-26T19:13:49.907Z,1758914029.907 [BPC1](INFO): Received data from all battery sticks.
2025-09-26T19:17:48.687Z,1758914268.687 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T19:17:48.687Z,1758914268.687 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:17:48.687Z,1758914268.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:17:48.688Z,1758914268.688 [Default:CheckIn:D] Running Loop=1
2025-09-26T19:17:49.093Z,1758914269.093 [Default:CheckIn:D] Stopped
2025-09-26T19:17:49.093Z,1758914269.093 [Default:CheckIn:E] Running Loop=1
2025-09-26T19:17:49.497Z,1758914269.497 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  46.055188 min 
2025-09-26T19:17:49.497Z,1758914269.497 [Default:CheckIn:E] Stopped
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn] Stopped
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn](INFO): Running loop #8
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn] Running Loop=8
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T19:17:49.498Z,1758914269.498 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T19:17:51.504Z,1758914271.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191750.00,A,3648.14030,N,12147.22927,W,0.058,214.49,260925,,,A*78
2025-09-26T19:17:51.506Z,1758914271.506 [NAL9602](INFO): GPS fix at 20250926T191750: (36.802338, -121.787154)
2025-09-26T19:17:51.517Z,1758914271.517 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T19:17:51.517Z,1758914271.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T19:17:58.503Z,1758914278.503 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20250926T185255/Courier0012.lzma
2025-09-26T19:18:09.411Z,1758914289.411 [NAL9602](INFO): SBD MO Status=1, MOMSN=13924, MT Status=0, MTMSN=0
2025-09-26T19:18:09.411Z,1758914289.411 [NAL9602](INFO): No messages in MT queue
2025-09-26T19:18:09.545Z,1758914289.545 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0012.lzma.bak
2025-09-26T19:18:09.545Z,1758914289.545 [DataOverHttps](INFO): SBD MOMSN=26079184
2025-09-26T19:18:36.880Z,1758914316.880 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250926T185255/Express0013.lzma
2025-09-26T19:18:40.120Z,1758914320.120 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:18:47.921Z,1758914327.921 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0013.lzma.bak
2025-09-26T19:18:47.921Z,1758914327.921 [DataOverHttps](INFO): SBD MOMSN=26079187
2025-09-26T19:18:50.275Z,1758914330.275 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:18:50.275Z,1758914330.275 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:18:50.275Z,1758914330.275 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:23:50.973Z,1758914630.973 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T19:23:50.974Z,1758914630.974 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:23:50.974Z,1758914630.974 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:23:50.996Z,1758914630.996 [Default:CheckIn:D] Running Loop=1
2025-09-26T19:23:51.378Z,1758914631.378 [Default:CheckIn:D] Stopped
2025-09-26T19:23:51.378Z,1758914631.378 [Default:CheckIn:E] Running Loop=1
2025-09-26T19:23:51.772Z,1758914631.772 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  52.093262 min 
2025-09-26T19:23:51.772Z,1758914631.772 [Default:CheckIn:E] Stopped
2025-09-26T19:23:51.772Z,1758914631.772 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T19:23:51.772Z,1758914631.772 [Default:CheckIn] Stopped
2025-09-26T19:23:51.772Z,1758914631.772 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:23:51.773Z,1758914631.773 [Default:CheckIn](INFO): Running loop #9
2025-09-26T19:23:51.773Z,1758914631.773 [Default:CheckIn] Running Loop=9
2025-09-26T19:23:51.773Z,1758914631.773 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T19:23:51.773Z,1758914631.773 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T19:23:53.782Z,1758914633.782 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192353.00,A,3648.17321,N,12147.27843,W,0.058,17.48,260925,,,D*4F
2025-09-26T19:23:53.784Z,1758914633.784 [NAL9602](INFO): GPS fix at 20250926T192353: (36.802887, -121.787974)
2025-09-26T19:23:53.795Z,1758914633.795 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T19:23:53.796Z,1758914633.796 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T19:24:11.950Z,1758914651.950 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250926T185255/Courier0015.lzma
2025-09-26T19:24:23.009Z,1758914663.009 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0015.lzma.bak
2025-09-26T19:24:23.009Z,1758914663.009 [DataOverHttps](INFO): SBD MOMSN=26079192
2025-09-26T19:24:54.483Z,1758914694.483 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250926T185255/Express0016.lzma
2025-09-26T19:24:57.303Z,1758914697.303 [NAL9602](INFO): SBD MO Status=2, MOMSN=13925, MT Status=2, MTMSN=0
2025-09-26T19:24:57.303Z,1758914697.303 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:25:05.529Z,1758914705.529 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0016.lzma.bak
2025-09-26T19:25:05.529Z,1758914705.529 [DataOverHttps](INFO): SBD MOMSN=26079195
2025-09-26T19:25:07.074Z,1758914707.074 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:25:07.076Z,1758914707.076 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:25:07.076Z,1758914707.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:28:05.388Z,1758914885.388 [NAL9602](INFO): SBD MO Status=2, MOMSN=13925, MT Status=2, MTMSN=0
2025-09-26T19:28:05.388Z,1758914885.388 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:28:40.138Z,1758914920.138 [NAL9602](INFO): SBD MO Status=2, MOMSN=13925, MT Status=2, MTMSN=0
2025-09-26T19:28:40.138Z,1758914920.138 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:28:56.309Z,1758914936.309 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-26T19:29:27.041Z,1758914967.041 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:30:07.458Z,1758915007.458 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T19:30:07.458Z,1758915007.458 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:30:07.458Z,1758915007.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:30:07.459Z,1758915007.459 [Default:CheckIn:D] Running Loop=1
2025-09-26T19:30:07.874Z,1758915007.874 [Default:CheckIn:D] Stopped
2025-09-26T19:30:07.874Z,1758915007.874 [Default:CheckIn:E] Running Loop=1
2025-09-26T19:30:08.282Z,1758915008.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  58.368201 min 
2025-09-26T19:30:08.282Z,1758915008.282 [Default:CheckIn:E] Stopped
2025-09-26T19:30:08.282Z,1758915008.282 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T19:30:08.282Z,1758915008.282 [Default:CheckIn] Stopped
2025-09-26T19:30:08.292Z,1758915008.292 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:30:08.293Z,1758915008.293 [Default:CheckIn](INFO): Running loop #10
2025-09-26T19:30:08.293Z,1758915008.293 [Default:CheckIn] Running Loop=10
2025-09-26T19:30:08.293Z,1758915008.293 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T19:30:08.294Z,1758915008.294 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T19:30:10.283Z,1758915010.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193009.00,A,3648.17537,N,12147.28160,W,0.292,348.01,260925,,,D*74
2025-09-26T19:30:10.294Z,1758915010.294 [NAL9602](INFO): GPS fix at 20250926T193009: (36.802923, -121.788027)
2025-09-26T19:30:10.335Z,1758915010.335 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T19:30:10.335Z,1758915010.335 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T19:30:20.195Z,1758915020.195 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250926T185255/Courier0018.lzma
2025-09-26T19:30:31.241Z,1758915031.241 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0018.lzma.bak
2025-09-26T19:30:31.241Z,1758915031.241 [DataOverHttps](INFO): SBD MOMSN=26079198
2025-09-26T19:30:40.872Z,1758915040.872 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-26T19:30:40.955Z,1758915040.955 [NAL9602](ERROR): received: 
+CSQ:0

OK925, 2, 0, 0, 0

OK
2025-09-26T19:31:01.494Z,1758915061.494 [NAL9602](INFO): SBD MO Status=1, MOMSN=13925, MT Status=0, MTMSN=0
2025-09-26T19:31:01.494Z,1758915061.494 [NAL9602](INFO): No messages in MT queue
2025-09-26T19:31:05.391Z,1758915065.391 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20250926T185255/Express0019.lzma
2025-09-26T19:31:16.465Z,1758915076.465 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0019.lzma.bak
2025-09-26T19:31:16.465Z,1758915076.465 [DataOverHttps](INFO): SBD MOMSN=26079201
2025-09-26T19:31:17.672Z,1758915077.672 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:31:17.672Z,1758915077.672 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:31:17.672Z,1758915077.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:31:32.219Z,1758915092.219 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:36:18.348Z,1758915378.348 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-26T19:36:18.348Z,1758915378.348 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:36:18.348Z,1758915378.348 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:36:18.348Z,1758915378.348 [Default:CheckIn:D] Running Loop=1
2025-09-26T19:36:18.746Z,1758915378.746 [Default:CheckIn:D] Stopped
2025-09-26T19:36:18.746Z,1758915378.746 [Default:CheckIn:E] Running Loop=1
2025-09-26T19:36:19.147Z,1758915379.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  64.549402 min 
2025-09-26T19:36:19.147Z,1758915379.147 [Default:CheckIn:E] Stopped
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn] Stopped
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn](INFO): Running loop #11
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn] Running Loop=11
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-26T19:36:19.148Z,1758915379.148 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-26T19:36:21.169Z,1758915381.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193620.00,A,3648.16781,N,12147.28137,W,1.322,177.52,260925,,,D*76
2025-09-26T19:36:21.172Z,1758915381.172 [NAL9602](INFO): GPS fix at 20250926T193620: (36.802797, -121.788023)
2025-09-26T19:36:21.192Z,1758915381.192 [Default:CheckIn:Read_GPS] Stopped
2025-09-26T19:36:21.192Z,1758915381.192 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-26T19:36:30.823Z,1758915390.823 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250926T185255/Courier0021.lzma
2025-09-26T19:36:41.162Z,1758915401.162 [NAL9602](INFO): SBD MO Status=2, MOMSN=13926, MT Status=2, MTMSN=0
2025-09-26T19:36:41.162Z,1758915401.162 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:36:41.865Z,1758915401.865 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Courier0021.lzma.bak
2025-09-26T19:36:41.865Z,1758915401.865 [DataOverHttps](INFO): SBD MOMSN=26079207
2025-09-26T19:37:13.219Z,1758915433.219 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250926T185255/Express0022.lzma
2025-09-26T19:37:22.809Z,1758915442.809 [NAL9602](INFO): SBD MO Status=2, MOMSN=13926, MT Status=2, MTMSN=0
2025-09-26T19:37:22.809Z,1758915442.809 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:37:24.261Z,1758915444.261 [DataOverHttps](INFO): Moved sent file to Logs/20250926T185255/Express0022.lzma.bak
2025-09-26T19:37:24.261Z,1758915444.261 [DataOverHttps](INFO): SBD MOMSN=26079210
2025-09-26T19:37:25.664Z,1758915445.664 [Default:CheckIn:Read_Iridium] Stopped
2025-09-26T19:37:25.665Z,1758915445.665 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-26T19:37:25.665Z,1758915445.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-26T19:38:01.601Z,1758915481.601 [NAL9602](INFO): SBD MO Status=2, MOMSN=13926, MT Status=2, MTMSN=0
2025-09-26T19:38:01.601Z,1758915481.601 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:38:26.245Z,1758915506.245 [NAL9602](INFO): SBD MO Status=2, MOMSN=13926, MT Status=2, MTMSN=0
2025-09-26T19:38:26.245Z,1758915506.245 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-26T19:38:58.573Z,1758915538.573 [NAL9602](INFO): SBD MO Status=1, MOMSN=13926, MT Status=0, MTMSN=0
2025-09-26T19:38:58.573Z,1758915538.573 [NAL9602](INFO): No messages in MT queue
2025-09-26T19:39:29.300Z,1758915569.300 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-26T19:41:55.984Z,1758915715.984 [CommandExec](IMPORTANT): got command quit
2025-09-26T19:41:56.995Z,1758915716.995 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:56.995Z,1758915716.995 [CommandExec](INFO): Uninitializing the command executive.
2025-09-26T19:41:56.995Z,1758915716.995 [CommandExec](INFO): Uninitializing the command scheduler.
2025-09-26T19:41:56.996Z,1758915716.996 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:57.107Z,1758915717.107 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-09-26T19:41:57.107Z,1758915717.107 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-09-26T19:41:57.108Z,1758915717.108 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:57.108Z,1758915717.108 [NavChartDb](INFO): Join timeout helper Thread ID is 4521
2025-09-26T19:41:57.399Z,1758915717.399 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:57.400Z,1758915717.400 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:57.401Z,1758915717.401 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-09-26T19:41:57.401Z,1758915717.401 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:57.401Z,1758915717.401 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4522
2025-09-26T19:41:57.519Z,1758915717.519 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:57.520Z,1758915717.520 [WetLabsBB2FL](INFO): Powering down
2025-09-26T19:41:57.521Z,1758915717.521 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:57.535Z,1758915717.535 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-09-26T19:41:57.535Z,1758915717.535 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:57.535Z,1758915717.535 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4523
2025-09-26T19:41:58.431Z,1758915718.431 [CTD_Seabird](INFO): Powering down
2025-09-26T19:41:58.443Z,1758915718.443 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:58.443Z,1758915718.443 [CTD_Seabird](INFO): Powering down
2025-09-26T19:41:58.455Z,1758915718.455 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:58.459Z,1758915718.459 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-09-26T19:41:58.459Z,1758915718.459 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:58.459Z,1758915718.459 [Radio_Surface](INFO): Join timeout helper Thread ID is 4524
2025-09-26T19:41:58.675Z,1758915718.675 [Radio_Surface](INFO): Powering down
2025-09-26T19:41:58.677Z,1758915718.677 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:58.677Z,1758915718.677 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:58.683Z,1758915718.683 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-09-26T19:41:58.683Z,1758915718.683 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:58.684Z,1758915718.684 [Onboard](INFO): Join timeout helper Thread ID is 4525
2025-09-26T19:41:58.803Z,1758915718.803 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:58.804Z,1758915718.804 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:58.808Z,1758915718.808 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-09-26T19:41:58.808Z,1758915718.808 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:58.808Z,1758915718.808 [DataOverHttps](INFO): Join timeout helper Thread ID is 4526
2025-09-26T19:41:59.479Z,1758915719.479 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:59.479Z,1758915719.479 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.499Z,1758915719.499 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-09-26T19:41:59.500Z,1758915719.500 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.500Z,1758915719.500 [DAT](INFO): Join timeout helper Thread ID is 4528
2025-09-26T19:41:59.623Z,1758915719.623 [DAT](INFO): Powering down
2025-09-26T19:41:59.695Z,1758915719.695 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:59.696Z,1758915719.696 [DAT](INFO): Powering down
2025-09-26T19:41:59.697Z,1758915719.697 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.700Z,1758915719.700 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-09-26T19:41:59.700Z,1758915719.700 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.700Z,1758915719.700 [BackseatComponent](INFO): Join timeout helper Thread ID is 4529
2025-09-26T19:41:59.759Z,1758915719.759 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:59.759Z,1758915719.759 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.764Z,1758915719.764 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-09-26T19:41:59.764Z,1758915719.764 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.764Z,1758915719.764 [logger](INFO): Join timeout helper Thread ID is 4530
2025-09-26T19:41:59.787Z,1758915719.787 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:59.787Z,1758915719.787 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.804Z,1758915719.804 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-09-26T19:41:59.804Z,1758915719.804 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.805Z,1758915719.805 [CommandLine](INFO): Join timeout helper Thread ID is 4531
2025-09-26T19:41:59.903Z,1758915719.903 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:41:59.903Z,1758915719.903 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.907Z,1758915719.907 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-09-26T19:41:59.908Z,1758915719.908 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.908Z,1758915719.908 [CommandExec](INFO): Join timeout helper Thread ID is 4532
2025-09-26T19:41:59.909Z,1758915719.909 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-09-26T19:41:59.909Z,1758915719.909 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:41:59.910Z,1758915719.910 [controlThread](INFO): Join timeout helper Thread ID is 4533
2025-09-26T19:42:00.047Z,1758915720.047 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-26T19:42:00.047Z,1758915720.047 [controlThread](DEBUG): Uninitializing ControlThread
2025-09-26T19:42:00.048Z,1758915720.048 [AHRS_M2](INFO): Powering down
2025-09-26T19:42:00.127Z,1758915720.127 [NAL9602](INFO): Powering down
2025-09-26T19:42:00.128Z,1758915720.128 [Sonardyne_Nano](INFO): Powering down
2025-09-26T19:42:00.335Z,1758915720.335 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-09-26T19:42:00.336Z,1758915720.336 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-09-26T19:42:00.336Z,1758915720.336 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-09-26T19:42:00.337Z,1758915720.337 [MissionManager](INFO): Uninitializing Mission Default
2025-09-26T19:42:00.337Z,1758915720.337 [Default] Stopped
2025-09-26T19:42:00.337Z,1758915720.337 [Default](DEBUG): Aggregate::uninitialize Default
2025-09-26T19:42:00.337Z,1758915720.337 [Default:B.GoToSurface] Stopped
2025-09-26T19:42:00.337Z,1758915720.337 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-26T19:42:00.337Z,1758915720.337 [Default:CheckIn] Stopped
2025-09-26T19:42:00.337Z,1758915720.337 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-26T19:42:00.337Z,1758915720.337 [Default:CheckIn:C.Wait] Stopped
2025-09-26T19:42:00.338Z,1758915720.338 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-26T19:42:00.341Z,1758915720.341 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-09-26T19:42:00.341Z,1758915720.341 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-09-26T19:42:00.341Z,1758915720.341 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-09-26T19:42:00.342Z,1758915720.342 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-09-26T19:42:00.342Z,1758915720.342 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-09-26T19:42:00.342Z,1758915720.342 [BuoyancyServo](INFO): Powering down
2025-09-26T19:42:00.355Z,1758915720.355 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-09-26T19:42:00.355Z,1758915720.355 [ElevatorServo](INFO): Powering down
2025-09-26T19:42:00.356Z,1758915720.356 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-26T19:42:00.356Z,1758915720.356 [MassServo](INFO): Powering down
2025-09-26T19:42:00.357Z,1758915720.357 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-09-26T19:42:00.357Z,1758915720.357 [RudderServo](INFO): Powering down
2025-09-26T19:42:00.358Z,1758915720.358 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-09-26T19:42:00.358Z,1758915720.358 [ThrusterHE](INFO): Powering down
2025-09-26T19:42:00.359Z,1758915720.359 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-09-26T19:42:00.359Z,1758915720.359 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-09-26T19:42:00.359Z,1758915720.359 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-09-26T19:42:00.359Z,1758915720.359 [CBIT](DEBUG): Powering off loads.
2025-09-26T19:42:00.371Z,1758915720.371 [CBIT](DEBUG): Disabling WDT.
2025-09-26T19:42:00.383Z,1758915720.383 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-26T19:42:00.383Z,1758915720.383 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-26T19:42:00.384Z,1758915720.384 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.417Z,1758915720.417 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.420Z,1758915720.420 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.426Z,1758915720.426 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.433Z,1758915720.433 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.473Z,1758915720.473 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.530Z,1758915720.530 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-26T19:42:00.530Z,1758915720.530 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-26T19:42:00.679Z,1758915720.679 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.684Z,1758915720.684 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.715Z,1758915720.715 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-26T19:42:00.789Z,1758915720.789 [logger ThreadHandler](INFO): Thread cancelled.