2024-09-17T18:03:35.133Z,1726596215.133 [CommandExec](IMPORTANT): got command restart logs
2024-09-17T18:07:45.278Z,1726596465.278 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:07:45.278Z,1726596465.278 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:07:45.278Z,1726596465.278 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:07:45.278Z,1726596465.278 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:07:45.687Z,1726596465.687 [Default:CheckIn:D] Stopped
2024-09-17T18:07:45.687Z,1726596465.687 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:07:46.087Z,1726596466.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  33.385999 min 
2024-09-17T18:07:46.087Z,1726596466.087 [Default:CheckIn:E] Stopped
2024-09-17T18:07:46.087Z,1726596466.087 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:07:46.087Z,1726596466.087 [Default:CheckIn] Stopped
2024-09-17T18:07:46.087Z,1726596466.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:07:46.087Z,1726596466.087 [Default:CheckIn](INFO): Running loop #6
2024-09-17T18:07:46.088Z,1726596466.088 [Default:CheckIn] Running Loop=6
2024-09-17T18:07:46.088Z,1726596466.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:07:46.088Z,1726596466.088 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:07:48.107Z,1726596468.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180747.00,A,3645.66343,N,12201.53915,W,0.350,297.08,170924,,,A*71
2024-09-17T18:07:48.109Z,1726596468.109 [NAL9602](INFO): GPS fix at 20240917T180747: (36.761057, -122.025653)
2024-09-17T18:07:48.119Z,1726596468.119 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:07:48.120Z,1726596468.120 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:07:56.851Z,1726596476.851 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240917T154137/Courier0058.lzma
2024-09-17T18:07:57.854Z,1726596477.854 [DataOverHttps](INFO): Moved sent file to Logs/20240917T154137/Courier0058.lzma.bak
2024-09-17T18:07:57.854Z,1726596477.854 [DataOverHttps](INFO): SBD MOMSN=23197180
2024-09-17T18:08:18.397Z,1726596498.397 [NAL9602](INFO): SBD MO Status=0, MOMSN=22023, MT Status=0, MTMSN=0
2024-09-17T18:08:18.397Z,1726596498.397 [NAL9602](INFO): No messages in MT queue
2024-09-17T18:08:25.672Z,1726596505.672 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240917T180335/Courier0000.lzma
2024-09-17T18:08:26.674Z,1726596506.674 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0000.lzma.bak
2024-09-17T18:08:26.674Z,1726596506.674 [DataOverHttps](INFO): SBD MOMSN=23197182
2024-09-17T18:08:47.480Z,1726596527.480 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240917T154137/Express0059.lzma
2024-09-17T18:08:48.482Z,1726596528.482 [DataOverHttps](INFO): Moved sent file to Logs/20240917T154137/Express0059.lzma.bak
2024-09-17T18:08:48.482Z,1726596528.482 [DataOverHttps](INFO): SBD MOMSN=23197187
2024-09-17T18:08:49.099Z,1726596529.099 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T18:09:04.304Z,1726596544.304 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20240917T180335/Express0001.lzma
2024-09-17T18:09:05.306Z,1726596545.306 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0001.lzma.bak
2024-09-17T18:09:05.306Z,1726596545.306 [DataOverHttps](INFO): SBD MOMSN=23197190
2024-09-17T18:09:25.967Z,1726596565.967 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240917T180335/Express0004.lzma
2024-09-17T18:09:26.970Z,1726596566.970 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0004.lzma.bak
2024-09-17T18:09:26.970Z,1726596566.970 [DataOverHttps](INFO): SBD MOMSN=23197194
2024-09-17T18:09:28.716Z,1726596568.716 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:09:28.717Z,1726596568.717 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:09:28.717Z,1726596568.717 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:14:29.277Z,1726596869.277 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:14:29.277Z,1726596869.277 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:14:29.277Z,1726596869.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:14:29.278Z,1726596869.278 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:14:29.687Z,1726596869.687 [Default:CheckIn:D] Stopped
2024-09-17T18:14:29.687Z,1726596869.687 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  40.119336 min 
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn:E] Stopped
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn] Stopped
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn](INFO): Running loop #7
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn] Running Loop=7
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:14:30.093Z,1726596870.093 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:14:32.102Z,1726596872.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181431.00,A,3645.60426,N,12200.53697,W,23.093,94.87,170924,,,D*7A
2024-09-17T18:14:32.113Z,1726596872.113 [NAL9602](INFO): GPS fix at 20240917T181431: (36.760071, -122.008949)
2024-09-17T18:14:32.124Z,1726596872.124 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:14:32.124Z,1726596872.124 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:14:41.332Z,1726596881.332 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0006.lzma
2024-09-17T18:14:42.334Z,1726596882.334 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0006.lzma.bak
2024-09-17T18:14:42.334Z,1726596882.334 [DataOverHttps](INFO): SBD MOMSN=23197216
2024-09-17T18:14:59.480Z,1726596899.480 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240917T180335/Express0007.lzma
2024-09-17T18:15:00.482Z,1726596900.482 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0007.lzma.bak
2024-09-17T18:15:00.482Z,1726596900.482 [DataOverHttps](INFO): SBD MOMSN=23197219
2024-09-17T18:15:02.418Z,1726596902.418 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:15:02.419Z,1726596902.419 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:15:02.419Z,1726596902.419 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:15:48.857Z,1726596948.857 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:15:48.857Z,1726596948.857 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:16:21.183Z,1726596981.183 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:16:21.183Z,1726596981.183 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:16:38.150Z,1726596998.150 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:16:38.151Z,1726596998.151 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:16:53.498Z,1726597013.498 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:16:53.498Z,1726597013.498 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:17:25.822Z,1726597045.822 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:17:25.822Z,1726597045.822 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:18:32.886Z,1726597112.886 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:18:32.886Z,1726597112.886 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:19:34.297Z,1726597174.297 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T18:20:02.987Z,1726597202.987 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:20:02.987Z,1726597202.987 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:20:02.987Z,1726597202.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:20:02.987Z,1726597202.987 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:20:03.386Z,1726597203.386 [Default:CheckIn:D] Stopped
2024-09-17T18:20:03.386Z,1726597203.386 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:20:03.795Z,1726597203.795 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  45.680981 min 
2024-09-17T18:20:03.795Z,1726597203.795 [Default:CheckIn:E] Stopped
2024-09-17T18:20:03.795Z,1726597203.795 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:20:03.795Z,1726597203.795 [Default:CheckIn] Stopped
2024-09-17T18:20:03.796Z,1726597203.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:20:03.796Z,1726597203.796 [Default:CheckIn](INFO): Running loop #8
2024-09-17T18:20:03.796Z,1726597203.796 [Default:CheckIn] Running Loop=8
2024-09-17T18:20:03.796Z,1726597203.796 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:20:03.796Z,1726597203.796 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:20:05.812Z,1726597205.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182005.00,A,3645.38503,N,12157.49416,W,23.812,97.71,170924,,,D*7B
2024-09-17T18:20:05.814Z,1726597205.814 [NAL9602](INFO): GPS fix at 20240917T182005: (36.756417, -121.958236)
2024-09-17T18:20:05.825Z,1726597205.825 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:20:05.825Z,1726597205.825 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:20:15.767Z,1726597215.767 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0009.lzma
2024-09-17T18:20:16.782Z,1726597216.782 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0009.lzma.bak
2024-09-17T18:20:16.782Z,1726597216.782 [DataOverHttps](INFO): SBD MOMSN=23197251
2024-09-17T18:20:38.513Z,1726597238.513 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20240917T180335/Express0010.lzma
2024-09-17T18:20:38.533Z,1726597238.533 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T18:20:39.510Z,1726597239.510 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0010.lzma.bak
2024-09-17T18:20:39.510Z,1726597239.510 [DataOverHttps](INFO): SBD MOMSN=23197254
2024-09-17T18:20:41.373Z,1726597241.373 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:20:41.373Z,1726597241.373 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:20:41.373Z,1726597241.373 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:25:41.946Z,1726597541.946 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:25:41.947Z,1726597541.947 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:25:41.947Z,1726597541.947 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:25:41.947Z,1726597541.947 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:25:42.348Z,1726597542.348 [Default:CheckIn:D] Stopped
2024-09-17T18:25:42.364Z,1726597542.364 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:25:42.751Z,1726597542.751 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  51.330343 min 
2024-09-17T18:25:42.751Z,1726597542.751 [Default:CheckIn:E] Stopped
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn] Stopped
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn](INFO): Running loop #9
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn] Running Loop=9
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:25:42.752Z,1726597542.752 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:25:44.758Z,1726597544.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182544.00,A,3644.95055,N,12154.33458,W,27.525,100.32,170924,,,D*4A
2024-09-17T18:25:44.761Z,1726597544.761 [NAL9602](INFO): GPS fix at 20240917T182544: (36.749176, -121.905576)
2024-09-17T18:25:44.772Z,1726597544.772 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:25:44.772Z,1726597544.772 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:25:52.815Z,1726597552.815 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0012.lzma
2024-09-17T18:25:53.818Z,1726597553.818 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0012.lzma.bak
2024-09-17T18:25:53.818Z,1726597553.818 [DataOverHttps](INFO): SBD MOMSN=23197271
2024-09-17T18:26:10.632Z,1726597570.632 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240917T180335/Express0013.lzma
2024-09-17T18:26:11.634Z,1726597571.634 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0013.lzma.bak
2024-09-17T18:26:11.634Z,1726597571.634 [DataOverHttps](INFO): SBD MOMSN=23197274
2024-09-17T18:26:13.476Z,1726597573.476 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:26:13.476Z,1726597573.476 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:26:13.476Z,1726597573.476 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:26:15.465Z,1726597575.465 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T18:26:15.548Z,1726597575.548 [NAL9602](ERROR): received: 
+CSQ:0

OK024, 2, 0, 0, 0

OK
2024-09-17T18:26:36.875Z,1726597596.875 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:26:36.875Z,1726597596.875 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:26:58.694Z,1726597618.694 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:26:58.694Z,1726597618.694 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:29:09.998Z,1726597749.998 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:29:09.998Z,1726597749.998 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:29:29.383Z,1726597769.383 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:29:29.383Z,1726597769.383 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:30:21.095Z,1726597821.095 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:30:21.095Z,1726597821.095 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:30:50.190Z,1726597850.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=22024, MT Status=2, MTMSN=0
2024-09-17T18:30:50.191Z,1726597850.191 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:30:50.585Z,1726597850.585 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T18:31:14.040Z,1726597874.040 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:31:14.040Z,1726597874.040 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:31:14.040Z,1726597874.040 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:31:14.040Z,1726597874.040 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:31:14.431Z,1726597874.431 [Default:CheckIn:D] Stopped
2024-09-17T18:31:14.431Z,1726597874.431 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  56.865068 min 
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn:E] Stopped
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn] Stopped
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn](INFO): Running loop #10
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn] Running Loop=10
2024-09-17T18:31:14.843Z,1726597874.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:31:14.844Z,1726597874.844 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:31:16.856Z,1726597876.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183116.00,A,3644.91745,N,12152.78213,W,0.097,346.63,170924,,,D*77
2024-09-17T18:31:16.858Z,1726597876.858 [NAL9602](INFO): GPS fix at 20240917T183116: (36.748624, -121.879702)
2024-09-17T18:31:16.870Z,1726597876.870 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:31:16.870Z,1726597876.870 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:31:24.059Z,1726597884.059 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0015.lzma
2024-09-17T18:31:25.062Z,1726597885.062 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0015.lzma.bak
2024-09-17T18:31:25.062Z,1726597885.062 [DataOverHttps](INFO): SBD MOMSN=23197323
2024-09-17T18:31:49.601Z,1726597909.601 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T18:32:14.036Z,1726597934.036 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20240917T180335/Express0016.lzma
2024-09-17T18:32:15.038Z,1726597935.038 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0016.lzma.bak
2024-09-17T18:32:15.038Z,1726597935.038 [DataOverHttps](INFO): SBD MOMSN=23197364
2024-09-17T18:32:17.124Z,1726597937.124 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:32:17.124Z,1726597937.124 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:32:17.124Z,1726597937.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:37:17.650Z,1726598237.650 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:37:17.650Z,1726598237.650 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:37:17.650Z,1726598237.650 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:37:17.651Z,1726598237.651 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:37:18.065Z,1726598238.065 [Default:CheckIn:D] Stopped
2024-09-17T18:37:18.065Z,1726598238.065 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:37:18.458Z,1726598238.458 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  62.925631 min 
2024-09-17T18:37:18.458Z,1726598238.458 [Default:CheckIn:E] Stopped
2024-09-17T18:37:18.458Z,1726598238.458 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:37:18.458Z,1726598238.458 [Default:CheckIn] Stopped
2024-09-17T18:37:18.458Z,1726598238.458 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:37:18.459Z,1726598238.459 [Default:CheckIn](INFO): Running loop #11
2024-09-17T18:37:18.459Z,1726598238.459 [Default:CheckIn] Running Loop=11
2024-09-17T18:37:18.459Z,1726598238.459 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:37:18.459Z,1726598238.459 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:37:20.479Z,1726598240.479 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183719.00,A,3645.17134,N,12152.56508,W,20.080,25.39,170924,,,D*7D
2024-09-17T18:37:20.481Z,1726598240.481 [NAL9602](INFO): GPS fix at 20240917T183719: (36.752856, -121.876085)
2024-09-17T18:37:20.511Z,1726598240.511 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:37:20.511Z,1726598240.511 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:37:30.227Z,1726598250.227 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0018.lzma
2024-09-17T18:37:31.230Z,1726598251.230 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0018.lzma.bak
2024-09-17T18:37:31.230Z,1726598251.230 [DataOverHttps](INFO): SBD MOMSN=23197371
2024-09-17T18:37:49.899Z,1726598269.899 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240917T180335/Express0019.lzma
2024-09-17T18:37:50.902Z,1726598270.902 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0019.lzma.bak
2024-09-17T18:37:50.902Z,1726598270.902 [DataOverHttps](INFO): SBD MOMSN=23197374
2024-09-17T18:37:52.808Z,1726598272.808 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:37:52.813Z,1726598272.813 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:37:52.814Z,1726598272.814 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:38:17.866Z,1726598297.866 [NAL9602](INFO): SBD MO Status=0, MOMSN=22024, MT Status=0, MTMSN=0
2024-09-17T18:38:17.866Z,1726598297.866 [NAL9602](INFO): No messages in MT queue
2024-09-17T18:38:48.564Z,1726598328.564 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T18:41:58.058Z,1726598518.058 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-09-17T18:42:53.403Z,1726598573.403 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:42:53.403Z,1726598573.403 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:42:53.403Z,1726598573.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:42:53.404Z,1726598573.404 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:42:53.815Z,1726598573.815 [Default:CheckIn:D] Stopped
2024-09-17T18:42:53.815Z,1726598573.815 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:42:54.207Z,1726598574.207 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  68.521468 min 
2024-09-17T18:42:54.207Z,1726598574.207 [Default:CheckIn:E] Stopped
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn] Stopped
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn](INFO): Running loop #12
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn] Running Loop=12
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:42:54.208Z,1726598574.208 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:42:56.218Z,1726598576.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184255.00,A,3645.58679,N,12152.48714,W,0.486,315.51,170924,,,D*7C
2024-09-17T18:42:56.220Z,1726598576.220 [NAL9602](INFO): GPS fix at 20240917T184255: (36.759780, -121.874786)
2024-09-17T18:42:56.248Z,1726598576.248 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:42:56.248Z,1726598576.248 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:43:07.619Z,1726598587.619 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0021.lzma
2024-09-17T18:43:08.622Z,1726598588.622 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0021.lzma.bak
2024-09-17T18:43:08.622Z,1726598588.622 [DataOverHttps](INFO): SBD MOMSN=23197380
2024-09-17T18:43:15.614Z,1726598595.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=22025, MT Status=2, MTMSN=0
2024-09-17T18:43:15.614Z,1726598595.614 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:43:29.448Z,1726598609.448 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20240917T180335/Express0022.lzma
2024-09-17T18:43:30.450Z,1726598610.450 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0022.lzma.bak
2024-09-17T18:43:30.450Z,1726598610.450 [DataOverHttps](INFO): SBD MOMSN=23197388
2024-09-17T18:43:32.199Z,1726598612.199 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:43:32.199Z,1726598612.199 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:43:32.199Z,1726598612.199 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:45:09.142Z,1726598709.142 [NAL9602](INFO): SBD MO Status=2, MOMSN=22025, MT Status=2, MTMSN=0
2024-09-17T18:45:09.142Z,1726598709.142 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:45:38.223Z,1726598738.223 [NAL9602](INFO): SBD MO Status=0, MOMSN=22025, MT Status=0, MTMSN=0
2024-09-17T18:45:38.223Z,1726598738.223 [NAL9602](INFO): No messages in MT queue
2024-09-17T18:46:08.929Z,1726598768.929 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T18:47:43.862Z,1726598863.862 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:47:57.598Z,1726598877.598 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:48:11.738Z,1726598891.738 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:48:25.476Z,1726598905.476 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:48:32.758Z,1726598912.758 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:48:32.758Z,1726598912.758 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:48:32.758Z,1726598912.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:48:32.758Z,1726598912.758 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:48:33.167Z,1726598913.167 [Default:CheckIn:D] Stopped
2024-09-17T18:48:33.167Z,1726598913.167 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:48:33.571Z,1726598913.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  74.177336 min 
2024-09-17T18:48:33.571Z,1726598913.571 [Default:CheckIn:E] Stopped
2024-09-17T18:48:33.571Z,1726598913.571 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:48:33.572Z,1726598913.572 [Default:CheckIn] Stopped
2024-09-17T18:48:33.572Z,1726598913.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:48:33.572Z,1726598913.572 [Default:CheckIn](INFO): Running loop #13
2024-09-17T18:48:33.572Z,1726598913.572 [Default:CheckIn] Running Loop=13
2024-09-17T18:48:33.572Z,1726598913.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:48:33.572Z,1726598913.572 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:48:35.578Z,1726598915.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184834.00,A,3645.62246,N,12152.42118,W,0.914,27.75,170924,,,D*42
2024-09-17T18:48:35.580Z,1726598915.580 [NAL9602](INFO): GPS fix at 20240917T184834: (36.760374, -121.873686)
2024-09-17T18:48:35.612Z,1726598915.612 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:48:35.612Z,1726598915.612 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:48:39.628Z,1726598919.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:48:43.559Z,1726598923.559 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0024.lzma
2024-09-17T18:48:44.562Z,1726598924.562 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0024.lzma.bak
2024-09-17T18:48:44.562Z,1726598924.562 [DataOverHttps](INFO): SBD MOMSN=23197443
2024-09-17T18:48:53.389Z,1726598933.389 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:49:01.548Z,1726598941.548 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240917T180335/Express0025.lzma
2024-09-17T18:49:02.550Z,1726598942.550 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0025.lzma.bak
2024-09-17T18:49:02.550Z,1726598942.550 [DataOverHttps](INFO): SBD MOMSN=23197446
2024-09-17T18:49:04.273Z,1726598944.273 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:49:04.273Z,1726598944.273 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:49:04.273Z,1726598944.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:49:07.490Z,1726598947.490 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:49:21.227Z,1726598961.227 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:49:35.394Z,1726598975.394 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:49:49.132Z,1726598989.132 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:50:02.867Z,1726599002.867 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:52:14.977Z,1726599134.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=22026, MT Status=2, MTMSN=0
2024-09-17T18:52:14.977Z,1726599134.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:52:46.494Z,1726599166.494 [NAL9602](INFO): SBD MO Status=2, MOMSN=22026, MT Status=2, MTMSN=0
2024-09-17T18:52:46.494Z,1726599166.494 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T18:53:39.822Z,1726599219.822 [NAL9602](INFO): SBD MO Status=0, MOMSN=22026, MT Status=0, MTMSN=0
2024-09-17T18:53:39.822Z,1726599219.822 [NAL9602](INFO): No messages in MT queue
2024-09-17T18:54:04.883Z,1726599244.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:54:04.884Z,1726599244.884 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:54:04.904Z,1726599244.904 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:54:04.905Z,1726599244.905 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:54:05.283Z,1726599245.283 [Default:CheckIn:D] Stopped
2024-09-17T18:54:05.283Z,1726599245.283 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  79.712606 min 
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn:E] Stopped
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn] Stopped
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn](INFO): Running loop #14
2024-09-17T18:54:05.691Z,1726599245.691 [Default:CheckIn] Running Loop=14
2024-09-17T18:54:05.692Z,1726599245.692 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:54:05.692Z,1726599245.692 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:54:07.720Z,1726599247.720 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185406.00,A,3645.82480,N,12152.06457,W,22.549,55.46,170924,,,D*73
2024-09-17T18:54:07.722Z,1726599247.722 [NAL9602](INFO): GPS fix at 20240917T185406: (36.763747, -121.867743)
2024-09-17T18:54:07.734Z,1726599247.734 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:54:07.734Z,1726599247.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:54:15.799Z,1726599255.799 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0027.lzma
2024-09-17T18:54:16.802Z,1726599256.802 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0027.lzma.bak
2024-09-17T18:54:16.802Z,1726599256.802 [DataOverHttps](INFO): SBD MOMSN=23197476
2024-09-17T18:54:35.977Z,1726599275.977 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-09-17T18:54:38.836Z,1726599278.836 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240917T180335/Express0028.lzma
2024-09-17T18:54:39.830Z,1726599279.830 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0028.lzma.bak
2024-09-17T18:54:39.830Z,1726599279.830 [DataOverHttps](INFO): SBD MOMSN=23197479
2024-09-17T18:54:40.433Z,1726599280.433 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T18:54:41.660Z,1726599281.660 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T18:54:41.660Z,1726599281.660 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T18:54:41.661Z,1726599281.661 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T18:57:35.347Z,1726599455.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:57:49.486Z,1726599469.486 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:58:03.225Z,1726599483.225 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:58:17.400Z,1726599497.400 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:58:31.099Z,1726599511.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:58:45.238Z,1726599525.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:58:58.977Z,1726599538.977 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:59:13.114Z,1726599553.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:59:26.850Z,1726599566.850 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:59:40.990Z,1726599580.990 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T18:59:42.219Z,1726599582.219 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T18:59:42.219Z,1726599582.219 [Default:CheckIn:C.Wait] Stopped
2024-09-17T18:59:42.219Z,1726599582.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T18:59:42.220Z,1726599582.220 [Default:CheckIn:D] Running Loop=1
2024-09-17T18:59:42.627Z,1726599582.627 [Default:CheckIn:D] Stopped
2024-09-17T18:59:42.627Z,1726599582.627 [Default:CheckIn:E] Running Loop=1
2024-09-17T18:59:43.023Z,1726599583.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  85.334993 min 
2024-09-17T18:59:43.023Z,1726599583.023 [Default:CheckIn:E] Stopped
2024-09-17T18:59:43.023Z,1726599583.023 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T18:59:43.023Z,1726599583.023 [Default:CheckIn] Stopped
2024-09-17T18:59:43.023Z,1726599583.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T18:59:43.023Z,1726599583.023 [Default:CheckIn](INFO): Running loop #15
2024-09-17T18:59:43.024Z,1726599583.024 [Default:CheckIn] Running Loop=15
2024-09-17T18:59:43.024Z,1726599583.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T18:59:43.024Z,1726599583.024 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T18:59:45.034Z,1726599585.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185944.00,A,3647.23114,N,12149.62351,W,28.536,54.48,170924,,,D*7D
2024-09-17T18:59:45.037Z,1726599585.037 [NAL9602](INFO): GPS fix at 20240917T185944: (36.787186, -121.827059)
2024-09-17T18:59:45.047Z,1726599585.047 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T18:59:45.048Z,1726599585.048 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T18:59:52.435Z,1726599592.435 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0030.lzma
2024-09-17T18:59:53.438Z,1726599593.438 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0030.lzma.bak
2024-09-17T18:59:53.438Z,1726599593.438 [DataOverHttps](INFO): SBD MOMSN=23197534
2024-09-17T18:59:54.728Z,1726599594.728 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:00:09.300Z,1726599609.300 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240917T180335/Express0031.lzma
2024-09-17T19:00:10.302Z,1726599610.302 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0031.lzma.bak
2024-09-17T19:00:10.303Z,1726599610.303 [DataOverHttps](INFO): SBD MOMSN=23197537
2024-09-17T19:00:12.116Z,1726599612.116 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:00:12.116Z,1726599612.116 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:00:12.117Z,1726599612.117 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:03:12.315Z,1726599792.315 [NAL9602](INFO): SBD MO Status=2, MOMSN=22027, MT Status=2, MTMSN=0
2024-09-17T19:03:12.315Z,1726599792.315 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:03:20.770Z,1726599800.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=22027, MT Status=0, MTMSN=0
2024-09-17T19:03:20.770Z,1726599800.770 [NAL9602](INFO): No messages in MT queue
2024-09-17T19:03:51.473Z,1726599831.473 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:05:12.686Z,1726599912.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:05:12.686Z,1726599912.686 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:05:12.686Z,1726599912.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:05:12.687Z,1726599912.687 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:05:13.091Z,1726599913.091 [Default:CheckIn:D] Stopped
2024-09-17T19:05:13.091Z,1726599913.091 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:05:13.501Z,1726599913.501 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  90.842741 min 
2024-09-17T19:05:13.501Z,1726599913.501 [Default:CheckIn:E] Stopped
2024-09-17T19:05:13.501Z,1726599913.501 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:05:13.501Z,1726599913.501 [Default:CheckIn] Stopped
2024-09-17T19:05:13.501Z,1726599913.501 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:05:13.501Z,1726599913.501 [Default:CheckIn](INFO): Running loop #16
2024-09-17T19:05:13.502Z,1726599913.502 [Default:CheckIn] Running Loop=16
2024-09-17T19:05:13.502Z,1726599913.502 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:05:13.502Z,1726599913.502 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:05:15.510Z,1726599915.510 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190514.00,A,3648.46208,N,12147.29144,W,8.553,54.56,170924,,,D*4B
2024-09-17T19:05:15.513Z,1726599915.513 [NAL9602](INFO): GPS fix at 20240917T190514: (36.807701, -121.788191)
2024-09-17T19:05:15.544Z,1726599915.544 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:05:15.544Z,1726599915.544 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:05:23.048Z,1726599923.048 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0033.lzma
2024-09-17T19:05:24.050Z,1726599924.050 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0033.lzma.bak
2024-09-17T19:05:24.050Z,1726599924.050 [DataOverHttps](INFO): SBD MOMSN=23197554
2024-09-17T19:05:36.922Z,1726599936.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=22028, MT Status=2, MTMSN=0
2024-09-17T19:05:36.923Z,1726599936.923 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:05:39.840Z,1726599939.840 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240917T180335/Express0034.lzma
2024-09-17T19:05:40.842Z,1726599940.842 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0034.lzma.bak
2024-09-17T19:05:40.842Z,1726599940.842 [DataOverHttps](INFO): SBD MOMSN=23197560
2024-09-17T19:05:42.603Z,1726599942.603 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:05:42.603Z,1726599942.603 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:05:42.603Z,1726599942.603 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:05:46.614Z,1726599946.614 [NAL9602](INFO): SBD MO Status=0, MOMSN=22028, MT Status=0, MTMSN=0
2024-09-17T19:05:46.615Z,1726599946.615 [NAL9602](INFO): No messages in MT queue
2024-09-17T19:06:17.325Z,1726599977.325 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:07:24.401Z,1726600044.401 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-09-17T19:07:29.630Z,1726600049.630 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:07:43.771Z,1726600063.771 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:07:57.506Z,1726600077.506 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:08:11.649Z,1726600091.649 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:08:25.789Z,1726600105.789 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:08:39.522Z,1726600119.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:08:53.662Z,1726600133.662 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:09:07.398Z,1726600147.398 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:09:21.538Z,1726600161.538 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:09:35.274Z,1726600175.274 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:09:49.414Z,1726600189.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T19:10:43.174Z,1726600243.174 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:10:43.174Z,1726600243.174 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:10:43.174Z,1726600243.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:10:43.175Z,1726600243.175 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:10:43.563Z,1726600243.563 [Default:CheckIn:D] Stopped
2024-09-17T19:10:43.563Z,1726600243.563 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  96.350594 min 
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn:E] Stopped
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn] Stopped
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn](INFO): Running loop #17
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn] Running Loop=17
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:10:43.977Z,1726600243.977 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:10:45.979Z,1726600245.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191045.00,A,3648.15040,N,12147.21695,W,1.263,238.96,170924,,,D*79
2024-09-17T19:10:45.981Z,1726600245.981 [NAL9602](INFO): GPS fix at 20240917T191045: (36.802507, -121.786949)
2024-09-17T19:10:45.992Z,1726600245.992 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:10:45.992Z,1726600245.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:10:53.107Z,1726600253.107 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0036.lzma
2024-09-17T19:10:54.110Z,1726600254.110 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0036.lzma.bak
2024-09-17T19:10:54.110Z,1726600254.110 [DataOverHttps](INFO): SBD MOMSN=23197583
2024-09-17T19:11:11.848Z,1726600271.848 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240917T180335/Express0037.lzma
2024-09-17T19:11:12.850Z,1726600272.850 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0037.lzma.bak
2024-09-17T19:11:12.850Z,1726600272.850 [DataOverHttps](INFO): SBD MOMSN=23197586
2024-09-17T19:11:14.679Z,1726600274.679 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:11:14.679Z,1726600274.679 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:11:14.679Z,1726600274.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:11:19.517Z,1726600279.517 [NAL9602](INFO): SBD MO Status=2, MOMSN=22029, MT Status=2, MTMSN=0
2024-09-17T19:11:19.517Z,1726600279.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:11:40.522Z,1726600300.522 [NAL9602](INFO): SBD MO Status=2, MOMSN=22029, MT Status=2, MTMSN=0
2024-09-17T19:11:40.522Z,1726600300.522 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:11:55.074Z,1726600315.074 [NAL9602](INFO): SBD MO Status=2, MOMSN=22029, MT Status=2, MTMSN=0
2024-09-17T19:11:55.074Z,1726600315.074 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:12:15.667Z,1726600335.667 [NAL9602](INFO): SBD MO Status=0, MOMSN=22029, MT Status=0, MTMSN=0
2024-09-17T19:12:15.667Z,1726600335.667 [NAL9602](INFO): No messages in MT queue
2024-09-17T19:12:46.373Z,1726600366.373 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:14:52.025Z,1726600492.025 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 4.
2024-09-17T19:14:52.028Z,1726600492.028 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-09-17T19:16:15.294Z,1726600575.294 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:16:15.294Z,1726600575.294 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:16:15.294Z,1726600575.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:16:15.295Z,1726600575.295 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:16:15.694Z,1726600575.694 [Default:CheckIn:D] Stopped
2024-09-17T19:16:15.694Z,1726600575.694 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:16:16.109Z,1726600576.109 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  101.886125 min 
2024-09-17T19:16:16.109Z,1726600576.109 [Default:CheckIn:E] Stopped
2024-09-17T19:16:16.109Z,1726600576.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:16:16.109Z,1726600576.109 [Default:CheckIn] Stopped
2024-09-17T19:16:16.109Z,1726600576.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:16:16.110Z,1726600576.110 [Default:CheckIn](INFO): Running loop #18
2024-09-17T19:16:16.110Z,1726600576.110 [Default:CheckIn] Running Loop=18
2024-09-17T19:16:16.110Z,1726600576.110 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:16:16.110Z,1726600576.110 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:16:18.109Z,1726600578.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191617.00,A,3648.14939,N,12147.22200,W,0.058,235.43,170924,,,D*7B
2024-09-17T19:16:18.111Z,1726600578.111 [NAL9602](INFO): GPS fix at 20240917T191617: (36.802490, -121.787033)
2024-09-17T19:16:18.143Z,1726600578.143 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:16:18.143Z,1726600578.143 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:16:25.932Z,1726600585.932 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20240917T180335/Courier0039.lzma
2024-09-17T19:16:26.934Z,1726600586.934 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0039.lzma.bak
2024-09-17T19:16:26.934Z,1726600586.934 [DataOverHttps](INFO): SBD MOMSN=23197629
2024-09-17T19:16:42.632Z,1726600602.632 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240917T180335/Express0040.lzma
2024-09-17T19:16:43.634Z,1726600603.634 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0040.lzma.bak
2024-09-17T19:16:43.634Z,1726600603.634 [DataOverHttps](INFO): SBD MOMSN=23197632
2024-09-17T19:16:45.597Z,1726600605.597 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:16:45.597Z,1726600605.597 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:16:45.597Z,1726600605.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:18:16.077Z,1726600696.077 [NAL9602](INFO): SBD MO Status=2, MOMSN=22030, MT Status=2, MTMSN=0
2024-09-17T19:18:16.077Z,1726600696.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:20:23.338Z,1726600823.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=22030, MT Status=2, MTMSN=0
2024-09-17T19:20:23.338Z,1726600823.338 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:21:06.166Z,1726600866.166 [NAL9602](INFO): SBD MO Status=2, MOMSN=22030, MT Status=2, MTMSN=0
2024-09-17T19:21:06.167Z,1726600866.167 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:21:19.493Z,1726600879.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=22030, MT Status=2, MTMSN=0
2024-09-17T19:21:19.493Z,1726600879.493 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-09-17T19:21:20.305Z,1726600880.305 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T19:21:46.172Z,1726600906.172 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:21:46.172Z,1726600906.172 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:21:46.172Z,1726600906.172 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:21:46.173Z,1726600906.173 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:21:46.571Z,1726600906.571 [Default:CheckIn:D] Stopped
2024-09-17T19:21:46.571Z,1726600906.571 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:21:46.983Z,1726600906.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  107.400732 min 
2024-09-17T19:21:46.983Z,1726600906.983 [Default:CheckIn:E] Stopped
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn] Stopped
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn](INFO): Running loop #19
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn] Running Loop=19
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:21:46.984Z,1726600906.984 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:21:48.991Z,1726600908.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192148.00,A,3648.14960,N,12147.22317,W,0.136,345.38,170924,,,D*7D
2024-09-17T19:21:48.993Z,1726600908.993 [NAL9602](INFO): GPS fix at 20240917T192148: (36.802493, -121.787053)
2024-09-17T19:21:49.004Z,1726600909.004 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:21:49.005Z,1726600909.005 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:21:56.688Z,1726600916.688 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0042.lzma
2024-09-17T19:21:57.690Z,1726600917.690 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0042.lzma.bak
2024-09-17T19:21:57.690Z,1726600917.690 [DataOverHttps](INFO): SBD MOMSN=23197700
2024-09-17T19:22:13.372Z,1726600933.372 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240917T180335/Express0043.lzma
2024-09-17T19:22:14.374Z,1726600934.374 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0043.lzma.bak
2024-09-17T19:22:14.374Z,1726600934.374 [DataOverHttps](INFO): SBD MOMSN=23197703
2024-09-17T19:22:16.068Z,1726600936.068 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:22:16.068Z,1726600936.068 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:22:16.068Z,1726600936.068 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:22:21.709Z,1726600941.709 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:27:16.650Z,1726601236.650 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:27:16.650Z,1726601236.650 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:27:16.650Z,1726601236.650 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:27:16.650Z,1726601236.650 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:27:17.066Z,1726601237.066 [Default:CheckIn:D] Stopped
2024-09-17T19:27:17.066Z,1726601237.066 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:27:17.452Z,1726601237.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  112.908984 min 
2024-09-17T19:27:17.452Z,1726601237.452 [Default:CheckIn:E] Stopped
2024-09-17T19:27:17.452Z,1726601237.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:27:17.452Z,1726601237.452 [Default:CheckIn] Stopped
2024-09-17T19:27:17.452Z,1726601237.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:27:17.453Z,1726601237.453 [Default:CheckIn](INFO): Running loop #20
2024-09-17T19:27:17.453Z,1726601237.453 [Default:CheckIn] Running Loop=20
2024-09-17T19:27:17.453Z,1726601237.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:27:17.453Z,1726601237.453 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:27:19.458Z,1726601239.458 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192718.00,A,3648.17270,N,12147.27435,W,0.194,346.62,170924,,,A*74
2024-09-17T19:27:19.460Z,1726601239.460 [NAL9602](INFO): GPS fix at 20240917T192718: (36.802878, -121.787906)
2024-09-17T19:27:19.486Z,1726601239.486 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:27:19.486Z,1726601239.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:27:27.035Z,1726601247.035 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0045.lzma
2024-09-17T19:27:28.038Z,1726601248.038 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0045.lzma.bak
2024-09-17T19:27:28.038Z,1726601248.038 [DataOverHttps](INFO): SBD MOMSN=23197732
2024-09-17T19:27:43.688Z,1726601263.688 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240917T180335/Express0046.lzma
2024-09-17T19:27:44.690Z,1726601264.690 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0046.lzma.bak
2024-09-17T19:27:44.690Z,1726601264.690 [DataOverHttps](INFO): SBD MOMSN=23197745
2024-09-17T19:27:46.541Z,1726601266.541 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:27:46.541Z,1726601266.541 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:27:46.541Z,1726601266.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:27:50.161Z,1726601270.161 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T19:27:50.241Z,1726601270.241 [NAL9602](ERROR): received: 
+SBDI: 2, 22030, 2, 0, 0, 0

OK
2024-09-17T19:28:59.662Z,1726601339.662 [NAL9602](INFO): SBD MO Status=0, MOMSN=22030, MT Status=0, MTMSN=0
2024-09-17T19:28:59.662Z,1726601339.662 [NAL9602](INFO): No messages in MT queue
2024-09-17T19:29:30.353Z,1726601370.353 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:31:59.835Z,1726601519.835 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-09-17T19:32:47.111Z,1726601567.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:32:47.111Z,1726601567.111 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:32:47.111Z,1726601567.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:32:47.111Z,1726601567.111 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:32:47.522Z,1726601567.522 [Default:CheckIn:D] Stopped
2024-09-17T19:32:47.522Z,1726601567.522 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  118.416585 min 
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn:E] Stopped
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn] Stopped
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn](INFO): Running loop #21
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn] Running Loop=21
2024-09-17T19:32:47.923Z,1726601567.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:32:47.924Z,1726601567.924 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:32:49.938Z,1726601569.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193249.00,A,3648.16876,N,12147.28293,W,1.147,14.01,170924,,,A*42
2024-09-17T19:32:49.941Z,1726601569.941 [NAL9602](INFO): GPS fix at 20240917T193249: (36.802813, -121.788049)
2024-09-17T19:32:49.952Z,1726601569.952 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:32:49.952Z,1726601569.952 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:32:57.547Z,1726601577.547 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0048.lzma
2024-09-17T19:32:58.550Z,1726601578.550 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0048.lzma.bak
2024-09-17T19:32:58.550Z,1726601578.550 [DataOverHttps](INFO): SBD MOMSN=23197770
2024-09-17T19:33:14.276Z,1726601594.276 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240917T180335/Express0049.lzma
2024-09-17T19:33:15.278Z,1726601595.278 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0049.lzma.bak
2024-09-17T19:33:15.278Z,1726601595.278 [DataOverHttps](INFO): SBD MOMSN=23197773
2024-09-17T19:33:17.035Z,1726601597.035 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:33:17.035Z,1726601597.035 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:33:17.035Z,1726601597.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:37:31.873Z,1726601851.873 [DataOverHttps](IMPORTANT): SBD MTMSN=20240917T193730
2024-09-17T19:37:39.188Z,1726601859.188 [DataOverHttps](INFO): Received command: strobe off
2024-09-17T19:37:39.190Z,1726601859.190 [CommandExec](IMPORTANT): got command strobe off
2024-09-17T19:37:39.190Z,1726601859.190 [CommandExec](IMPORTANT): Deactivating strobe
2024-09-17T19:37:52.524Z,1726601872.524 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T19:38:17.591Z,1726601897.591 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:38:17.591Z,1726601897.591 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:38:17.592Z,1726601897.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:38:17.593Z,1726601897.593 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:38:17.986Z,1726601897.986 [Default:CheckIn:D] Stopped
2024-09-17T19:38:17.986Z,1726601897.986 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:38:18.393Z,1726601898.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  123.924325 min 
2024-09-17T19:38:18.393Z,1726601898.393 [Default:CheckIn:E] Stopped
2024-09-17T19:38:18.393Z,1726601898.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:38:18.393Z,1726601898.393 [Default:CheckIn] Stopped
2024-09-17T19:38:18.394Z,1726601898.394 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:38:18.394Z,1726601898.394 [Default:CheckIn](INFO): Running loop #22
2024-09-17T19:38:18.394Z,1726601898.394 [Default:CheckIn] Running Loop=22
2024-09-17T19:38:18.394Z,1726601898.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:38:18.394Z,1726601898.394 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:38:20.402Z,1726601900.402 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193819.00,A,3648.17042,N,12147.27812,W,0.836,14.01,170924,,,A*41
2024-09-17T19:38:20.404Z,1726601900.404 [NAL9602](INFO): GPS fix at 20240917T193819: (36.802840, -121.787969)
2024-09-17T19:38:20.415Z,1726601900.415 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:38:20.415Z,1726601900.415 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:38:29.267Z,1726601909.267 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0051.lzma
2024-09-17T19:38:30.270Z,1726601910.270 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0051.lzma.bak
2024-09-17T19:38:30.270Z,1726601910.270 [DataOverHttps](INFO): SBD MOMSN=23197796
2024-09-17T19:38:46.008Z,1726601926.008 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20240917T180335/Express0052.lzma
2024-09-17T19:38:47.010Z,1726601927.010 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0052.lzma.bak
2024-09-17T19:38:47.010Z,1726601927.010 [DataOverHttps](INFO): SBD MOMSN=23197799
2024-09-17T19:38:52.317Z,1726601932.317 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:39:02.747Z,1726601942.747 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240917T180335/Express0055.lzma
2024-09-17T19:39:03.750Z,1726601943.750 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0055.lzma.bak
2024-09-17T19:39:03.750Z,1726601943.750 [DataOverHttps](INFO): SBD MOMSN=23197806
2024-09-17T19:39:06.481Z,1726601946.481 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:39:06.481Z,1726601946.481 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:39:06.481Z,1726601946.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:44:07.042Z,1726602247.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:44:07.042Z,1726602247.042 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:44:07.042Z,1726602247.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:44:07.043Z,1726602247.043 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:44:07.451Z,1726602247.451 [Default:CheckIn:D] Stopped
2024-09-17T19:44:07.451Z,1726602247.451 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:44:07.862Z,1726602247.862 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  129.748739 min 
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn:E] Stopped
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn] Stopped
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn](INFO): Running loop #23
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn] Running Loop=23
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:44:07.863Z,1726602247.863 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:44:09.867Z,1726602249.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194409.00,A,3648.17218,N,12147.28095,W,0.194,344.96,170924,,,A*77
2024-09-17T19:44:09.869Z,1726602249.869 [NAL9602](INFO): GPS fix at 20240917T194409: (36.802870, -121.788016)
2024-09-17T19:44:09.901Z,1726602249.901 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:44:09.901Z,1726602249.901 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:44:17.099Z,1726602257.099 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0057.lzma
2024-09-17T19:44:18.102Z,1726602258.102 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0057.lzma.bak
2024-09-17T19:44:18.102Z,1726602258.102 [DataOverHttps](INFO): SBD MOMSN=23197833
2024-09-17T19:44:33.764Z,1726602273.764 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240917T180335/Express0058.lzma
2024-09-17T19:44:34.766Z,1726602274.766 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0058.lzma.bak
2024-09-17T19:44:34.766Z,1726602274.766 [DataOverHttps](INFO): SBD MOMSN=23197836
2024-09-17T19:44:36.543Z,1726602276.543 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:44:36.544Z,1726602276.544 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:44:36.544Z,1726602276.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:44:40.573Z,1726602280.573 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T19:44:40.648Z,1726602280.648 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T19:49:12.455Z,1726602552.455 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T19:49:37.117Z,1726602577.117 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:49:37.117Z,1726602577.117 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:49:37.117Z,1726602577.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:49:37.117Z,1726602577.117 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:49:37.518Z,1726602577.518 [Default:CheckIn:D] Stopped
2024-09-17T19:49:37.519Z,1726602577.519 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:49:37.919Z,1726602577.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  135.249862 min 
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn:E] Stopped
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn] Stopped
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn](INFO): Running loop #24
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn] Running Loop=24
2024-09-17T19:49:37.920Z,1726602577.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:49:37.921Z,1726602577.921 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:49:39.934Z,1726602579.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194939.00,A,3648.18070,N,12147.27888,W,0.739,308.79,170924,,,A*79
2024-09-17T19:49:39.946Z,1726602579.946 [NAL9602](INFO): GPS fix at 20240917T194939: (36.803012, -121.787981)
2024-09-17T19:49:39.957Z,1726602579.957 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:49:39.957Z,1726602579.957 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:49:47.831Z,1726602587.831 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0060.lzma
2024-09-17T19:49:48.834Z,1726602588.834 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0060.lzma.bak
2024-09-17T19:49:48.834Z,1726602588.834 [DataOverHttps](INFO): SBD MOMSN=23197876
2024-09-17T19:50:06.120Z,1726602606.120 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240917T180335/Express0061.lzma
2024-09-17T19:50:07.122Z,1726602607.122 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0061.lzma.bak
2024-09-17T19:50:07.122Z,1726602607.122 [DataOverHttps](INFO): SBD MOMSN=23197879
2024-09-17T19:50:09.037Z,1726602609.037 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:50:09.037Z,1726602609.037 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:50:09.037Z,1726602609.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:50:12.661Z,1726602612.661 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T19:55:09.622Z,1726602909.622 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T19:55:09.622Z,1726602909.622 [Default:CheckIn:C.Wait] Stopped
2024-09-17T19:55:09.622Z,1726602909.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T19:55:09.623Z,1726602909.623 [Default:CheckIn:D] Running Loop=1
2024-09-17T19:55:10.012Z,1726602910.012 [Default:CheckIn:D] Stopped
2024-09-17T19:55:10.012Z,1726602910.012 [Default:CheckIn:E] Running Loop=1
2024-09-17T19:55:10.416Z,1726602910.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  140.791406 min 
2024-09-17T19:55:10.416Z,1726602910.416 [Default:CheckIn:E] Stopped
2024-09-17T19:55:10.416Z,1726602910.416 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T19:55:10.417Z,1726602910.417 [Default:CheckIn] Stopped
2024-09-17T19:55:10.417Z,1726602910.417 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T19:55:10.417Z,1726602910.417 [Default:CheckIn](INFO): Running loop #25
2024-09-17T19:55:10.417Z,1726602910.417 [Default:CheckIn] Running Loop=25
2024-09-17T19:55:10.417Z,1726602910.417 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T19:55:10.417Z,1726602910.417 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T19:55:12.423Z,1726602912.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195511.00,A,3648.18095,N,12147.27321,W,0.991,308.79,170924,,,A*71
2024-09-17T19:55:12.425Z,1726602912.425 [NAL9602](INFO): GPS fix at 20240917T195511: (36.803016, -121.787887)
2024-09-17T19:55:12.457Z,1726602912.457 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T19:55:12.457Z,1726602912.457 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T19:55:19.703Z,1726602919.703 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0063.lzma
2024-09-17T19:55:20.706Z,1726602920.706 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0063.lzma.bak
2024-09-17T19:55:20.706Z,1726602920.706 [DataOverHttps](INFO): SBD MOMSN=23197907
2024-09-17T19:55:36.396Z,1726602936.396 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240917T180335/Express0064.lzma
2024-09-17T19:55:37.398Z,1726602937.398 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0064.lzma.bak
2024-09-17T19:55:37.398Z,1726602937.398 [DataOverHttps](INFO): SBD MOMSN=23197910
2024-09-17T19:55:39.124Z,1726602939.124 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T19:55:39.124Z,1726602939.124 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T19:55:39.124Z,1726602939.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T19:55:43.153Z,1726602943.153 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T19:55:43.236Z,1726602943.236 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T20:00:14.249Z,1726603214.249 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T20:00:39.706Z,1726603239.706 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:00:39.706Z,1726603239.706 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:00:39.706Z,1726603239.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:00:39.706Z,1726603239.706 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:00:40.115Z,1726603240.115 [Default:CheckIn:D] Stopped
2024-09-17T20:00:40.115Z,1726603240.115 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:00:40.519Z,1726603240.519 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  146.293132 min 
2024-09-17T20:00:40.519Z,1726603240.519 [Default:CheckIn:E] Stopped
2024-09-17T20:00:40.519Z,1726603240.519 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:00:40.519Z,1726603240.519 [Default:CheckIn] Stopped
2024-09-17T20:00:40.520Z,1726603240.520 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:00:40.520Z,1726603240.520 [Default:CheckIn](INFO): Running loop #26
2024-09-17T20:00:40.520Z,1726603240.520 [Default:CheckIn] Running Loop=26
2024-09-17T20:00:40.520Z,1726603240.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:00:40.520Z,1726603240.520 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:00:42.530Z,1726603242.530 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200041.00,A,3648.17235,N,12147.27565,W,1.263,219.46,170924,,,A*75
2024-09-17T20:00:42.533Z,1726603242.533 [NAL9602](INFO): GPS fix at 20240917T200041: (36.802872, -121.787927)
2024-09-17T20:00:42.543Z,1726603242.543 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:00:42.543Z,1726603242.543 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:00:50.223Z,1726603250.223 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0066.lzma
2024-09-17T20:00:51.226Z,1726603251.226 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0066.lzma.bak
2024-09-17T20:00:51.226Z,1726603251.226 [DataOverHttps](INFO): SBD MOMSN=23197913
2024-09-17T20:01:06.917Z,1726603266.917 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20240917T180335/Express0067.lzma
2024-09-17T20:01:07.918Z,1726603267.918 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0067.lzma.bak
2024-09-17T20:01:07.918Z,1726603267.918 [DataOverHttps](INFO): SBD MOMSN=23197916
2024-09-17T20:01:09.615Z,1726603269.615 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T20:01:09.615Z,1726603269.615 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T20:01:09.615Z,1726603269.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T20:01:14.441Z,1726603274.441 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T20:06:10.182Z,1726603570.182 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:06:10.182Z,1726603570.182 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:06:10.183Z,1726603570.183 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:06:10.183Z,1726603570.183 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:06:10.583Z,1726603570.583 [Default:CheckIn:D] Stopped
2024-09-17T20:06:10.583Z,1726603570.583 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:06:10.995Z,1726603570.995 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  151.800944 min 
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn:E] Stopped
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn] Stopped
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn](INFO): Running loop #27
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn] Running Loop=27
2024-09-17T20:06:10.996Z,1726603570.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:06:10.997Z,1726603570.997 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:06:13.002Z,1726603573.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200612.00,A,3648.17183,N,12147.27001,W,0.836,0.00,170924,,,A*7F
2024-09-17T20:06:13.005Z,1726603573.005 [NAL9602](INFO): GPS fix at 20240917T200612: (36.802864, -121.787834)
2024-09-17T20:06:13.016Z,1726603573.016 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:06:13.016Z,1726603573.016 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:06:20.559Z,1726603580.559 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0069.lzma
2024-09-17T20:06:21.562Z,1726603581.562 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0069.lzma.bak
2024-09-17T20:06:21.563Z,1726603581.563 [DataOverHttps](INFO): SBD MOMSN=23197920
2024-09-17T20:06:38.192Z,1726603598.192 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240917T180335/Express0070.lzma
2024-09-17T20:06:39.194Z,1726603599.194 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0070.lzma.bak
2024-09-17T20:06:39.194Z,1726603599.194 [DataOverHttps](INFO): SBD MOMSN=23197923
2024-09-17T20:06:40.913Z,1726603600.913 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T20:06:40.913Z,1726603600.913 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T20:06:40.913Z,1726603600.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T20:06:43.709Z,1726603603.709 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T20:06:43.792Z,1726603603.792 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T20:11:15.198Z,1726603875.198 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T20:11:41.458Z,1726603901.458 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:11:41.458Z,1726603901.458 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:11:41.458Z,1726603901.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:11:41.459Z,1726603901.459 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:11:41.868Z,1726603901.868 [Default:CheckIn:D] Stopped
2024-09-17T20:11:41.868Z,1726603901.868 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  157.322347 min 
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn:E] Stopped
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn] Stopped
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn](INFO): Running loop #28
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn] Running Loop=28
2024-09-17T20:11:42.275Z,1726603902.275 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:11:42.276Z,1726603902.276 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:11:44.283Z,1726603904.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201143.00,A,3648.16466,N,12147.26939,W,0.097,0.00,170924,,,A*72
2024-09-17T20:11:44.285Z,1726603904.285 [NAL9602](INFO): GPS fix at 20240917T201143: (36.802744, -121.787823)
2024-09-17T20:11:44.311Z,1726603904.311 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:11:44.311Z,1726603904.311 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:11:53.143Z,1726603913.143 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0072.lzma
2024-09-17T20:11:54.146Z,1726603914.146 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0072.lzma.bak
2024-09-17T20:11:54.146Z,1726603914.146 [DataOverHttps](INFO): SBD MOMSN=23197929
2024-09-17T20:12:09.880Z,1726603929.880 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240917T180335/Express0073.lzma
2024-09-17T20:12:10.882Z,1726603930.882 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0073.lzma.bak
2024-09-17T20:12:10.882Z,1726603930.882 [DataOverHttps](INFO): SBD MOMSN=23197935
2024-09-17T20:12:12.591Z,1726603932.591 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T20:12:12.591Z,1726603932.591 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T20:12:12.591Z,1726603932.591 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T20:12:16.597Z,1726603936.597 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T20:16:37.580Z,1726604197.580 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:16:51.718Z,1726604211.718 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:17:05.454Z,1726604225.454 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:17:13.147Z,1726604233.147 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:17:13.147Z,1726604233.147 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:17:13.147Z,1726604233.147 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:17:13.147Z,1726604233.147 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:17:13.549Z,1726604233.549 [Default:CheckIn:D] Stopped
2024-09-17T20:17:13.549Z,1726604233.549 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:17:13.963Z,1726604233.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  162.850358 min 
2024-09-17T20:17:13.963Z,1726604233.963 [Default:CheckIn:E] Stopped
2024-09-17T20:17:13.963Z,1726604233.963 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:17:13.963Z,1726604233.963 [Default:CheckIn] Stopped
2024-09-17T20:17:13.964Z,1726604233.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:17:13.964Z,1726604233.964 [Default:CheckIn](INFO): Running loop #29
2024-09-17T20:17:13.964Z,1726604233.964 [Default:CheckIn] Running Loop=29
2024-09-17T20:17:13.964Z,1726604233.964 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:17:13.964Z,1726604233.964 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:17:15.963Z,1726604235.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201715.00,A,3648.17384,N,12147.27953,W,1.050,0.00,170924,,,A*7A
2024-09-17T20:17:15.965Z,1726604235.965 [NAL9602](INFO): GPS fix at 20240917T201715: (36.802897, -121.787992)
2024-09-17T20:17:15.977Z,1726604235.977 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:17:15.977Z,1726604235.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:17:19.595Z,1726604239.595 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:17:23.656Z,1726604243.656 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0075.lzma
2024-09-17T20:17:24.658Z,1726604244.658 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0075.lzma.bak
2024-09-17T20:17:24.658Z,1726604244.658 [DataOverHttps](INFO): SBD MOMSN=23197941
2024-09-17T20:17:33.336Z,1726604253.336 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:17:40.380Z,1726604260.380 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240917T180335/Express0076.lzma
2024-09-17T20:17:41.382Z,1726604261.382 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0076.lzma.bak
2024-09-17T20:17:41.382Z,1726604261.382 [DataOverHttps](INFO): SBD MOMSN=23197944
2024-09-17T20:17:43.451Z,1726604263.451 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T20:17:43.451Z,1726604263.451 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T20:17:43.451Z,1726604263.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T20:17:46.669Z,1726604266.669 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T20:17:46.749Z,1726604266.749 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T20:17:47.470Z,1726604267.470 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:18:01.207Z,1726604281.207 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:18:15.346Z,1726604295.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:18:29.083Z,1726604309.083 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:18:43.224Z,1726604323.224 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:18:56.959Z,1726604336.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:22:18.629Z,1726604538.629 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T20:22:44.030Z,1726604564.030 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:22:44.030Z,1726604564.030 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:22:44.031Z,1726604564.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:22:44.031Z,1726604564.031 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:22:44.423Z,1726604564.423 [Default:CheckIn:D] Stopped
2024-09-17T20:22:44.423Z,1726604564.423 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:22:44.831Z,1726604564.831 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  168.364925 min 
2024-09-17T20:22:44.831Z,1726604564.831 [Default:CheckIn:E] Stopped
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn] Stopped
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn](INFO): Running loop #30
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn] Running Loop=30
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:22:44.832Z,1726604564.832 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:22:46.840Z,1726604566.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202246.00,A,3648.14595,N,12147.27391,W,0.719,0.00,170924,,,A*70
2024-09-17T20:22:46.842Z,1726604566.842 [NAL9602](INFO): GPS fix at 20240917T202246: (36.802433, -121.787898)
2024-09-17T20:22:46.853Z,1726604566.853 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:22:46.854Z,1726604566.854 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:22:53.868Z,1726604573.868 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240917T180335/Courier0078.lzma
2024-09-17T20:22:54.870Z,1726604574.870 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0078.lzma.bak
2024-09-17T20:22:54.870Z,1726604574.870 [DataOverHttps](INFO): SBD MOMSN=23197948
2024-09-17T20:23:10.704Z,1726604590.704 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240917T180335/Express0079.lzma
2024-09-17T20:23:11.706Z,1726604591.706 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0079.lzma.bak
2024-09-17T20:23:11.706Z,1726604591.706 [DataOverHttps](INFO): SBD MOMSN=23197951
2024-09-17T20:23:13.657Z,1726604593.657 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T20:23:13.657Z,1726604593.657 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T20:23:13.657Z,1726604593.657 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T20:23:19.293Z,1726604599.293 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T20:26:28.598Z,1726604788.598 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:26:42.334Z,1726604802.334 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:26:56.475Z,1726604816.475 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:27:10.211Z,1726604830.211 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:27:24.350Z,1726604844.350 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:27:38.087Z,1726604858.087 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:27:52.226Z,1726604872.226 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:28:05.963Z,1726604885.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:28:14.464Z,1726604894.464 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:28:14.464Z,1726604894.464 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:28:14.464Z,1726604894.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:28:14.464Z,1726604894.464 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:28:14.880Z,1726604894.880 [Default:CheckIn:D] Stopped
2024-09-17T20:28:14.880Z,1726604894.880 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:28:15.268Z,1726604895.268 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  173.872542 min 
2024-09-17T20:28:15.268Z,1726604895.268 [Default:CheckIn:E] Stopped
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn] Stopped
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn](INFO): Running loop #31
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn] Running Loop=31
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:28:15.269Z,1726604895.269 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:28:17.280Z,1726604897.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202816.00,A,3648.18090,N,12147.28214,W,0.739,0.00,170924,,,A*72
2024-09-17T20:28:17.282Z,1726604897.282 [NAL9602](INFO): GPS fix at 20240917T202816: (36.803015, -121.788036)
2024-09-17T20:28:17.293Z,1726604897.293 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:28:17.293Z,1726604897.293 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:28:20.102Z,1726604900.102 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:28:25.019Z,1726604905.019 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240917T180335/Courier0081.lzma
2024-09-17T20:28:26.022Z,1726604906.022 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0081.lzma.bak
2024-09-17T20:28:26.022Z,1726604906.022 [DataOverHttps](INFO): SBD MOMSN=23197993
2024-09-17T20:28:33.838Z,1726604913.838 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:28:42.699Z,1726604922.699 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240917T180335/Express0082.lzma
2024-09-17T20:28:43.702Z,1726604923.702 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0082.lzma.bak
2024-09-17T20:28:43.703Z,1726604923.703 [DataOverHttps](INFO): SBD MOMSN=23197998
2024-09-17T20:28:45.586Z,1726604925.586 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T20:28:45.586Z,1726604925.586 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T20:28:45.586Z,1726604925.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T20:28:47.978Z,1726604927.978 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:28:48.006Z,1726604928.006 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T20:28:48.080Z,1726604928.080 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T20:29:16.420Z,1726604956.420 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-09-17T20:29:44.533Z,1726604984.533 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.830978
2024-09-17T20:30:26.717Z,1726605026.717 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:31:01.856Z,1726605061.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:31:36.996Z,1726605096.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:32:12.140Z,1726605132.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:32:47.280Z,1726605167.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:33:19.482Z,1726605199.482 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T20:33:22.420Z,1726605202.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:33:46.153Z,1726605226.153 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T20:33:46.153Z,1726605226.153 [Default:CheckIn:C.Wait] Stopped
2024-09-17T20:33:46.154Z,1726605226.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T20:33:46.154Z,1726605226.154 [Default:CheckIn:D] Running Loop=1
2024-09-17T20:33:46.555Z,1726605226.555 [Default:CheckIn:D] Stopped
2024-09-17T20:33:46.555Z,1726605226.555 [Default:CheckIn:E] Running Loop=1
2024-09-17T20:33:46.959Z,1726605226.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  179.400472 min 
2024-09-17T20:33:46.959Z,1726605226.959 [Default:CheckIn:E] Stopped
2024-09-17T20:33:46.959Z,1726605226.959 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T20:33:46.959Z,1726605226.959 [Default:CheckIn] Stopped
2024-09-17T20:33:46.959Z,1726605226.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T20:33:46.960Z,1726605226.960 [Default:CheckIn](INFO): Running loop #32
2024-09-17T20:33:46.960Z,1726605226.960 [Default:CheckIn] Running Loop=32
2024-09-17T20:33:46.960Z,1726605226.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T20:33:46.960Z,1726605226.960 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T20:33:57.560Z,1726605237.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:34:32.716Z,1726605272.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:35:07.856Z,1726605307.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:35:42.996Z,1726605342.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:36:18.152Z,1726605378.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:36:22.482Z,1726605382.482 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:36:36.218Z,1726605396.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:36:50.358Z,1726605410.358 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:36:53.292Z,1726605413.292 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:37:04.094Z,1726605424.094 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:37:18.234Z,1726605438.234 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:37:28.436Z,1726605448.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:37:31.970Z,1726605451.970 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:37:46.111Z,1726605466.111 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:37:59.847Z,1726605479.847 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:38:03.576Z,1726605483.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:38:13.987Z,1726605493.987 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:38:27.728Z,1726605507.728 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:38:38.716Z,1726605518.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:38:41.862Z,1726605521.862 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T20:38:47.136Z,1726605527.136 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-17T20:33:46.0Z
2024-09-17T20:38:47.136Z,1726605527.136 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T20:38:47.137Z,1726605527.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T20:39:13.868Z,1726605553.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:39:16.613Z,1726605556.613 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2024-09-17T20:39:16.688Z,1726605556.688 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T20:39:49.008Z,1726605589.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:40:24.168Z,1726605624.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:40:40.272Z,1726605640.272 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-09-17T20:40:59.308Z,1726605659.308 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:41:34.456Z,1726605694.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:42:02.360Z,1726605722.360 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2024-09-17T20:42:09.596Z,1726605729.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:42:16.887Z,1726605736.887 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-09-17T20:42:44.748Z,1726605764.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:43:19.892Z,1726605799.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:43:42.970Z,1726605822.970 [BPC1](ERROR): BPC1B: No match for serial number 0001 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-09-17T20:43:55.056Z,1726605835.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:44:30.196Z,1726605870.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:45:05.344Z,1726605905.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:45:40.484Z,1726605940.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:45:52.925Z,1726605952.925 [BPC1](ERROR): Battery stick #3 (s/n: 00BF) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2024-09-17T20:45:52.926Z,1726605952.926 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4.
2024-09-17T20:45:52.928Z,1726605952.928 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-09-17T20:46:15.631Z,1726605975.631 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:46:50.768Z,1726606010.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:47:25.908Z,1726606045.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:48:01.052Z,1726606081.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:48:36.192Z,1726606116.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:49:11.333Z,1726606151.333 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:49:46.472Z,1726606186.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:50:21.632Z,1726606221.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:50:56.772Z,1726606256.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:51:31.924Z,1726606291.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:52:07.076Z,1726606327.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:52:42.216Z,1726606362.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:53:17.356Z,1726606397.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:53:52.498Z,1726606432.498 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:54:27.648Z,1726606467.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:55:02.788Z,1726606502.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:55:37.964Z,1726606537.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:56:13.124Z,1726606573.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:56:48.284Z,1726606608.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:57:23.424Z,1726606643.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:57:58.596Z,1726606678.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:58:33.745Z,1726606713.745 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:59:08.884Z,1726606748.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T20:59:44.024Z,1726606784.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:00:19.180Z,1726606819.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:00:54.344Z,1726606854.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:01:29.484Z,1726606889.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:02:04.624Z,1726606924.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:02:39.780Z,1726606959.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:03:14.928Z,1726606994.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:03:50.076Z,1726607030.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:04:23.901Z,1726607063.901 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003732
2024-09-17T21:04:32.829Z,1726607072.829 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240917T180335/Courier0084.lzma
2024-09-17T21:04:33.826Z,1726607073.826 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0084.lzma.bak
2024-09-17T21:04:33.826Z,1726607073.826 [DataOverHttps](INFO): SBD MOMSN=23198212
2024-09-17T21:04:43.965Z,1726607083.965 [CommandExec](IMPORTANT): got command failComponent
2024-09-17T21:04:43.965Z,1726607083.965 [CommandExec](IMPORTANT): Failed components:
2024-09-17T21:04:43.965Z,1726607083.965 [CommandExec](IMPORTANT): No failed Components.
2024-09-17T21:04:49.573Z,1726607089.573 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240917T180335/Express0085.lzma
2024-09-17T21:04:50.574Z,1726607090.574 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0085.lzma.bak
2024-09-17T21:04:50.574Z,1726607090.574 [DataOverHttps](INFO): SBD MOMSN=23198214
2024-09-17T21:04:52.681Z,1726607092.681 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T21:04:52.681Z,1726607092.681 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T21:04:52.681Z,1726607092.681 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T21:04:56.350Z,1726607096.350 [CommandExec](IMPORTANT): got command get platform_rudder_angle
2024-09-17T21:04:56.350Z,1726607096.350 [CommandExec](IMPORTANT): platform_rudder_angle 0.052475 rad
2024-09-17T21:04:58.697Z,1726607098.697 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T21:05:34.761Z,1726607134.761 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2024-09-17T21:05:34.781Z,1726607134.781 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2024-09-17T21:05:35.099Z,1726607135.099 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-17T21:05:49.661Z,1726607149.661 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2024-09-17T21:06:29.038Z,1726607189.038 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2024-09-17T21:06:56.001Z,1726607216.001 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2024-09-17T21:07:23.424Z,1726607243.424 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree
2024-09-17T21:07:23.428Z,1726607243.428 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2024-09-17T21:07:23.779Z,1726607243.779 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-09-17T21:07:45.778Z,1726607265.778 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree
2024-09-17T21:09:31.192Z,1726607371.192 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree
2024-09-17T21:09:51.741Z,1726607391.741 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2024-09-17T21:09:53.236Z,1726607393.236 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T21:09:53.236Z,1726607393.236 [Default:CheckIn:C.Wait] Stopped
2024-09-17T21:09:53.236Z,1726607393.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T21:09:53.237Z,1726607393.237 [Default:CheckIn:D] Running Loop=1
2024-09-17T21:09:53.631Z,1726607393.631 [Default:CheckIn:D] Stopped
2024-09-17T21:09:53.631Z,1726607393.631 [Default:CheckIn:E] Running Loop=1
2024-09-17T21:09:54.035Z,1726607394.035 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  215.518408 min 
2024-09-17T21:09:54.035Z,1726607394.035 [Default:CheckIn:E] Stopped
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn] Stopped
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn](INFO): Running loop #33
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn] Running Loop=33
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T21:09:54.036Z,1726607394.036 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T21:09:55.654Z,1726607395.654 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-09-17T21:09:55.654Z,1726607395.654 [NAL9602] Data Fault, FailCount= 1
2024-09-17T21:09:55.654Z,1726607395.654 [NAL9602](ERROR): Data Fault
2024-09-17T21:09:55.716Z,1726607395.716 [CBIT](ERROR): Data Fault in component: NAL9602
2024-09-17T21:09:56.041Z,1726607396.041 [NAL9602](INFO): Powering down
2024-09-17T21:09:56.900Z,1726607396.900 [CBIT](INFO): Clearing failed state for component NAL9602
2024-09-17T21:09:56.900Z,1726607396.900 [NAL9602] No Fault, FailCount= 1
2024-09-17T21:10:11.490Z,1726607411.490 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2024-09-17T21:10:26.341Z,1726607426.341 [NAL9602](INFO): Powering up NAL9602
2024-09-17T21:10:37.257Z,1726607437.257 [NAL9602](INFO): NAL9602 initialized
2024-09-17T21:14:54.224Z,1726607694.224 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-17T21:09:54.0Z
2024-09-17T21:14:54.224Z,1726607694.224 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T21:14:54.224Z,1726607694.224 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T21:15:02.671Z,1726607702.671 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20240917T180335/Courier0087.lzma
2024-09-17T21:15:03.674Z,1726607703.674 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0087.lzma.bak
2024-09-17T21:15:03.674Z,1726607703.674 [DataOverHttps](INFO): SBD MOMSN=23198232
2024-09-17T21:15:24.428Z,1726607724.428 [DataOverHttps](INFO): Sending 403 bytes from file Logs/20240917T180335/Express0088.lzma
2024-09-17T21:15:25.430Z,1726607725.430 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0088.lzma.bak
2024-09-17T21:15:25.430Z,1726607725.430 [DataOverHttps](INFO): SBD MOMSN=23198234
2024-09-17T21:15:27.672Z,1726607727.672 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T21:15:27.672Z,1726607727.672 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T21:15:27.672Z,1726607727.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T21:15:40.169Z,1726607740.169 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T21:16:10.869Z,1726607770.869 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T21:20:28.232Z,1726608028.232 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T21:20:28.232Z,1726608028.232 [Default:CheckIn:C.Wait] Stopped
2024-09-17T21:20:28.232Z,1726608028.232 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T21:20:28.233Z,1726608028.233 [Default:CheckIn:D] Running Loop=1
2024-09-17T21:20:28.635Z,1726608028.635 [Default:CheckIn:D] Stopped
2024-09-17T21:20:28.635Z,1726608028.635 [Default:CheckIn:E] Running Loop=1
2024-09-17T21:20:29.051Z,1726608029.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  226.101807 min 
2024-09-17T21:20:29.051Z,1726608029.051 [Default:CheckIn:E] Stopped
2024-09-17T21:20:29.051Z,1726608029.051 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T21:20:29.051Z,1726608029.051 [Default:CheckIn] Stopped
2024-09-17T21:20:29.051Z,1726608029.051 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T21:20:29.052Z,1726608029.052 [Default:CheckIn](INFO): Running loop #34
2024-09-17T21:20:29.052Z,1726608029.052 [Default:CheckIn] Running Loop=34
2024-09-17T21:20:29.053Z,1726608029.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T21:20:29.053Z,1726608029.053 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T21:25:29.229Z,1726608329.229 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-17T21:20:29.1Z
2024-09-17T21:25:29.229Z,1726608329.229 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T21:25:29.229Z,1726608329.229 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T21:25:33.245Z,1726608333.245 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T21:25:37.000Z,1726608337.000 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240917T180335/Courier0090.lzma
2024-09-17T21:25:38.002Z,1726608338.002 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0090.lzma.bak
2024-09-17T21:25:38.002Z,1726608338.002 [DataOverHttps](INFO): SBD MOMSN=23198268
2024-09-17T21:25:53.736Z,1726608353.736 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240917T180335/Express0091.lzma
2024-09-17T21:25:54.738Z,1726608354.738 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0091.lzma.bak
2024-09-17T21:25:54.738Z,1726608354.738 [DataOverHttps](INFO): SBD MOMSN=23198270
2024-09-17T21:25:56.698Z,1726608356.698 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T21:25:56.698Z,1726608356.698 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T21:25:56.698Z,1726608356.698 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T21:26:03.957Z,1726608363.957 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T21:30:57.267Z,1726608657.267 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T21:30:57.267Z,1726608657.267 [Default:CheckIn:C.Wait] Stopped
2024-09-17T21:30:57.267Z,1726608657.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T21:30:57.267Z,1726608657.267 [Default:CheckIn:D] Running Loop=1
2024-09-17T21:30:57.682Z,1726608657.682 [Default:CheckIn:D] Stopped
2024-09-17T21:30:57.683Z,1726608657.683 [Default:CheckIn:E] Running Loop=1
2024-09-17T21:30:58.080Z,1726608658.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  236.585921 min 
2024-09-17T21:30:58.080Z,1726608658.080 [Default:CheckIn:E] Stopped
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn] Stopped
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn](INFO): Running loop #35
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn] Running Loop=35
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T21:30:58.081Z,1726608658.081 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T21:30:59.685Z,1726608659.685 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-09-17T21:30:59.685Z,1726608659.685 [NAL9602] Data Fault, FailCount= 2
2024-09-17T21:30:59.685Z,1726608659.685 [NAL9602](ERROR): Data Fault
2024-09-17T21:30:59.754Z,1726608659.754 [CBIT](ERROR): Data Fault in component: NAL9602
2024-09-17T21:31:00.085Z,1726608660.085 [NAL9602](INFO): Powering down
2024-09-17T21:31:00.925Z,1726608660.925 [CBIT](INFO): Clearing failed state for component NAL9602
2024-09-17T21:31:00.925Z,1726608660.925 [NAL9602] No Fault, FailCount= 2
2024-09-17T21:31:30.394Z,1726608690.394 [NAL9602](INFO): Powering up NAL9602
2024-09-17T21:31:41.294Z,1726608701.294 [NAL9602](INFO): NAL9602 initialized
2024-09-17T21:35:58.436Z,1726608958.436 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-17T21:30:58.1Z
2024-09-17T21:35:58.436Z,1726608958.436 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T21:35:58.436Z,1726608958.436 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T21:36:09.467Z,1726608969.467 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240917T180335/Courier0093.lzma
2024-09-17T21:36:10.471Z,1726608970.471 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0093.lzma.bak
2024-09-17T21:36:10.471Z,1726608970.471 [DataOverHttps](INFO): SBD MOMSN=23198280
2024-09-17T21:36:28.300Z,1726608988.300 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20240917T180335/Express0094.lzma
2024-09-17T21:36:29.307Z,1726608989.307 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0094.lzma.bak
2024-09-17T21:36:29.308Z,1726608989.308 [DataOverHttps](INFO): SBD MOMSN=23198283
2024-09-17T21:36:33.032Z,1726608993.032 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T21:36:33.032Z,1726608993.032 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T21:36:33.032Z,1726608993.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T21:36:44.153Z,1726609004.153 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T21:37:14.865Z,1726609034.865 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T21:41:33.541Z,1726609293.541 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-17T21:41:33.541Z,1726609293.541 [Default:CheckIn:C.Wait] Stopped
2024-09-17T21:41:33.541Z,1726609293.541 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T21:41:33.541Z,1726609293.541 [Default:CheckIn:D] Running Loop=1
2024-09-17T21:41:33.946Z,1726609293.946 [Default:CheckIn:D] Stopped
2024-09-17T21:41:33.946Z,1726609293.946 [Default:CheckIn:E] Running Loop=1
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  247.190316 min 
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn:E] Stopped
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn] Stopped
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn](INFO): Running loop #36
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn] Running Loop=36
2024-09-17T21:41:34.361Z,1726609294.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-17T21:41:34.362Z,1726609294.362 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-17T21:42:07.057Z,1726609327.057 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-09-17T21:42:07.136Z,1726609327.136 [NAL9602](ERROR): received: 
+CSQ:0

OK030, 0, 0, 0, 0

OK
2024-09-17T21:43:33.213Z,1726609413.213 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-17T21:43:44.079Z,1726609424.079 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.034557
CHAN A1 (24V): 0.017011
CHAN A2 (12V): -0.014913
CHAN A3 (5V): -0.024385
CHAN B0 (3.3V): -0.010529
CHAN B1 (3.15aV): -0.005453
CHAN B2 (3.15bV): -0.001833
CHAN B3 (GND): -0.010560
OPEN: 0.005739
Full Scale: +/- 1 mA
2024-09-17T21:46:08.196Z,1726609568.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:46:15.225Z,1726609575.225 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239418
2024-09-17T21:46:34.588Z,1726609594.588 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-17T21:41:34.4Z
2024-09-17T21:46:34.588Z,1726609594.588 [Default:CheckIn:Read_GPS] Stopped
2024-09-17T21:46:34.588Z,1726609594.588 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-17T21:46:38.209Z,1726609598.209 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-17T21:47:07.388Z,1726609627.388 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:47:18.504Z,1726609638.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:47:21.535Z,1726609641.535 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:47:35.270Z,1726609655.270 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:47:49.411Z,1726609669.411 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:47:53.644Z,1726609673.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:48:28.788Z,1726609708.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:49:03.932Z,1726609743.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:49:39.076Z,1726609779.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:50:14.216Z,1726609814.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:50:49.368Z,1726609849.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:51:24.508Z,1726609884.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:51:59.652Z,1726609919.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:52:34.792Z,1726609954.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:53:09.932Z,1726609989.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:53:45.093Z,1726610025.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:54:20.232Z,1726610060.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:54:55.372Z,1726610095.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:55:20.820Z,1726610120.820 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:55:30.512Z,1726610130.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:55:34.973Z,1726610134.973 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:55:48.723Z,1726610148.723 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:56:02.871Z,1726610162.871 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:56:05.652Z,1726610165.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:56:16.608Z,1726610176.608 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:56:30.743Z,1726610190.743 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:56:40.808Z,1726610200.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:56:44.479Z,1726610204.479 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:56:58.619Z,1726610218.619 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:57:12.559Z,1726610232.559 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:57:15.948Z,1726610235.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:57:26.291Z,1726610246.291 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:57:40.110Z,1726610260.110 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-09-17T21:57:51.088Z,1726610271.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:58:26.238Z,1726610306.238 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:59:01.376Z,1726610341.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-09-17T21:59:16.121Z,1726610356.121 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003501
2024-09-17T21:59:23.699Z,1726610363.699 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240917T180335/Courier0096.lzma
2024-09-17T21:59:24.702Z,1726610364.702 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Courier0096.lzma.bak
2024-09-17T21:59:24.702Z,1726610364.702 [DataOverHttps](INFO): SBD MOMSN=23198381
2024-09-17T21:59:40.460Z,1726610380.460 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20240917T180335/Express0097.lzma
2024-09-17T21:59:41.478Z,1726610381.478 [DataOverHttps](INFO): Moved sent file to Logs/20240917T180335/Express0097.lzma.bak
2024-09-17T21:59:41.478Z,1726610381.478 [DataOverHttps](INFO): SBD MOMSN=23198383
2024-09-17T21:59:43.467Z,1726610383.467 [Default:CheckIn:Read_Iridium] Stopped
2024-09-17T21:59:43.468Z,1726610383.468 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-17T21:59:43.468Z,1726610383.468 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-17T21:59:47.493Z,1726610387.493 [NAL9602](INFO): Not Powering down - fast GPS
2024-09-17T22:02:11.729Z,1726610531.729 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-09-17T22:02:11.731Z,1726610531.731 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2024-09-17T22:02:14.556Z,1726610534.556 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:17.383Z,1726610537.383 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:20.211Z,1726610540.211 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:22.650Z,1726610542.650 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:25.463Z,1726610545.463 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:28.290Z,1726610548.290 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:30.715Z,1726610550.715 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:33.543Z,1726610553.543 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:34.431Z,1726610554.431 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-09-17T22:02:36.371Z,1726610556.371 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:03:03.946Z,1726610583.946 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:03:16.824Z,1726610596.824 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-09-17T22:03:41.785Z,1726610621.785 [CommandExec](IMPORTANT): got command gfscan
2024-09-17T22:03:41.919Z,1726610621.919 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-17T22:03:52.821Z,1726610632.821 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.036634
CHAN A1 (24V): 0.016713
CHAN A2 (12V): -0.016578
CHAN A3 (5V): -0.023862
CHAN B0 (3.3V): -0.010869
CHAN B1 (3.15aV): -0.005109
CHAN B2 (3.15bV): -0.001730
CHAN B3 (GND): -0.011151
OPEN: 0.005559
Full Scale: +/- 1 mA
2024-09-17T22:04:15.756Z,1726610655.756 [CommandExec](IMPORTANT): got command quit
2024-09-17T22:04:16.760Z,1726610656.760 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:16.760Z,1726610656.760 [CommandExec](INFO): Uninitializing the command executive.
2024-09-17T22:04:16.760Z,1726610656.760 [CommandExec](INFO): Uninitializing the command scheduler.
2024-09-17T22:04:16.760Z,1726610656.760 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:16.876Z,1726610656.876 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2024-09-17T22:04:16.876Z,1726610656.876 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-09-17T22:04:16.876Z,1726610656.876 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:16.877Z,1726610656.877 [NavChartDb](INFO): Join timeout helper Thread ID is 5074
2024-09-17T22:04:17.160Z,1726610657.160 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:17.160Z,1726610657.160 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:17.168Z,1726610657.168 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-09-17T22:04:17.168Z,1726610657.168 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:17.169Z,1726610657.169 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5075
2024-09-17T22:04:17.360Z,1726610657.360 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:17.360Z,1726610657.360 [WetLabsBB2FL](INFO): Powering down
2024-09-17T22:04:17.361Z,1726610657.361 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:17.372Z,1726610657.372 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-09-17T22:04:17.372Z,1726610657.372 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:17.373Z,1726610657.373 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5076
2024-09-17T22:04:18.088Z,1726610658.088 [CTD_Seabird](INFO): Powering down
2024-09-17T22:04:18.100Z,1726610658.100 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:18.100Z,1726610658.100 [CTD_Seabird](INFO): Powering down
2024-09-17T22:04:18.128Z,1726610658.128 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:18.140Z,1726610658.140 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-09-17T22:04:18.140Z,1726610658.140 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:18.141Z,1726610658.141 [Radio_Surface](INFO): Join timeout helper Thread ID is 5077
2024-09-17T22:04:18.316Z,1726610658.316 [Radio_Surface](INFO): Powering down
2024-09-17T22:04:18.317Z,1726610658.317 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:18.317Z,1726610658.317 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:18.324Z,1726610658.324 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-09-17T22:04:18.324Z,1726610658.324 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:18.325Z,1726610658.325 [Onboard](INFO): Join timeout helper Thread ID is 5078
2024-09-17T22:04:18.521Z,1726610658.521 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-09-17T22:04:23.044Z,1726610663.044 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:23.044Z,1726610663.044 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:23.065Z,1726610663.065 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-09-17T22:04:23.065Z,1726610663.065 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:23.065Z,1726610663.065 [DataOverHttps](INFO): Join timeout helper Thread ID is 5079
2024-09-17T22:04:23.540Z,1726610663.540 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:23.540Z,1726610663.540 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:23.549Z,1726610663.549 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-09-17T22:04:23.549Z,1726610663.549 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:23.549Z,1726610663.549 [DATMMP](INFO): Join timeout helper Thread ID is 5080
2024-09-17T22:04:23.748Z,1726610663.748 [DATMMP](INFO): Stop
2024-09-17T22:04:23.748Z,1726610663.748 [DATMMP](INFO): uninitialize
2024-09-17T22:04:23.748Z,1726610663.748 [DATMMP](INFO): Powering down
2024-09-17T22:04:23.817Z,1726610663.817 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:23.817Z,1726610663.817 [DATMMP](INFO): uninitialize
2024-09-17T22:04:23.817Z,1726610663.817 [DATMMP](INFO): Powering down
2024-09-17T22:04:23.818Z,1726610663.818 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:23.833Z,1726610663.833 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-09-17T22:04:23.833Z,1726610663.833 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:23.833Z,1726610663.833 [BackseatComponent](INFO): Join timeout helper Thread ID is 5081
2024-09-17T22:04:24.028Z,1726610664.028 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:24.028Z,1726610664.028 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.049Z,1726610664.049 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-09-17T22:04:24.049Z,1726610664.049 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.049Z,1726610664.049 [logger](INFO): Join timeout helper Thread ID is 5082
2024-09-17T22:04:24.060Z,1726610664.060 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:24.060Z,1726610664.060 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.069Z,1726610664.069 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-09-17T22:04:24.069Z,1726610664.069 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.069Z,1726610664.069 [CommandLine](INFO): Join timeout helper Thread ID is 5083
2024-09-17T22:04:24.148Z,1726610664.148 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:24.148Z,1726610664.148 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.169Z,1726610664.169 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-09-17T22:04:24.169Z,1726610664.169 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.169Z,1726610664.169 [CommandExec](INFO): Join timeout helper Thread ID is 5084
2024-09-17T22:04:24.170Z,1726610664.170 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-09-17T22:04:24.170Z,1726610664.170 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.171Z,1726610664.171 [controlThread](INFO): Join timeout helper Thread ID is 5085
2024-09-17T22:04:24.236Z,1726610664.236 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-17T22:04:24.236Z,1726610664.236 [controlThread](DEBUG): Uninitializing ControlThread
2024-09-17T22:04:24.237Z,1726610664.237 [AHRS_M2](INFO): Powering down
2024-09-17T22:04:24.309Z,1726610664.309 [NAL9602](INFO): Powering down
2024-09-17T22:04:24.310Z,1726610664.310 [Sonardyne_Nano](INFO): Powering down
2024-09-17T22:04:24.430Z,1726610664.430 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-09-17T22:04:24.431Z,1726610664.431 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-09-17T22:04:24.431Z,1726610664.431 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-09-17T22:04:24.432Z,1726610664.432 [MissionManager](INFO): Uninitializing Mission Default
2024-09-17T22:04:24.432Z,1726610664.432 [Default] Stopped
2024-09-17T22:04:24.432Z,1726610664.432 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-17T22:04:24.432Z,1726610664.432 [Default:B.GoToSurface] Stopped
2024-09-17T22:04:24.432Z,1726610664.432 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-17T22:04:24.432Z,1726610664.432 [Default:CheckIn] Stopped
2024-09-17T22:04:24.432Z,1726610664.432 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-17T22:04:24.432Z,1726610664.432 [Default:CheckIn:C.Wait] Stopped
2024-09-17T22:04:24.432Z,1726610664.432 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-17T22:04:24.435Z,1726610664.435 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-09-17T22:04:24.435Z,1726610664.435 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-09-17T22:04:24.436Z,1726610664.436 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-09-17T22:04:24.436Z,1726610664.436 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-09-17T22:04:24.437Z,1726610664.437 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-09-17T22:04:24.437Z,1726610664.437 [BuoyancyServo](INFO): Powering down
2024-09-17T22:04:24.448Z,1726610664.448 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-09-17T22:04:24.448Z,1726610664.448 [ElevatorServo](INFO): Powering down
2024-09-17T22:04:24.449Z,1726610664.449 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-09-17T22:04:24.449Z,1726610664.449 [MassServo](INFO): Powering down
2024-09-17T22:04:24.450Z,1726610664.450 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-09-17T22:04:24.450Z,1726610664.450 [RudderServo](INFO): Powering down
2024-09-17T22:04:24.451Z,1726610664.451 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-09-17T22:04:24.451Z,1726610664.451 [ThrusterHE](INFO): Powering down
2024-09-17T22:04:24.452Z,1726610664.452 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-09-17T22:04:24.452Z,1726610664.452 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-09-17T22:04:24.452Z,1726610664.453 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-09-17T22:04:24.453Z,1726610664.453 [CBIT](DEBUG): Powering off loads.
2024-09-17T22:04:24.464Z,1726610664.464 [CBIT](DEBUG): Disabling WDT.
2024-09-17T22:04:24.476Z,1726610664.476 [CBIT](DEBUG): Opening all GF detection circuits.
2024-09-17T22:04:24.477Z,1726610664.477 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.526Z,1726610664.526 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.529Z,1726610664.529 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.535Z,1726610664.535 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.541Z,1726610664.541 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.583Z,1726610664.583 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.638Z,1726610664.638 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.644Z,1726610664.644 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.667Z,1726610664.667 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-17T22:04:24.755Z,1726610664.755 [logger ThreadHandler](INFO): Thread cancelled.