2024-10-31T17:16:35.237Z,1730394995.237 [CommandExec](IMPORTANT): got command restart logs
2024-10-31T17:17:14.276Z,1730395034.276 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:17:14.277Z,1730395034.277 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:17:14.277Z,1730395034.277 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:17:14.277Z,1730395034.277 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:17:14.688Z,1730395034.688 [Default:CheckIn:D] Stopped
2024-10-31T17:17:14.688Z,1730395034.688 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  17.996330 min 
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn:E] Stopped
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn] Stopped
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn](INFO): Running loop #4
2024-10-31T17:17:15.074Z,1730395035.074 [Default:CheckIn] Running Loop=4
2024-10-31T17:17:15.075Z,1730395035.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:17:15.075Z,1730395035.075 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:17:17.180Z,1730395037.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171715.00,A,3647.64217,N,12151.08360,W,8.203,57.78,311024,,,D*47
2024-10-31T17:17:17.183Z,1730395037.183 [NAL9602](INFO): GPS fix at 20241031T171715: (36.794036, -121.851393)
2024-10-31T17:17:17.260Z,1730395037.260 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:17:17.261Z,1730395037.261 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:17:25.287Z,1730395045.287 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20241030T190539/Courier0096.lzma
2024-10-31T17:17:26.290Z,1730395046.290 [DataOverHttps](INFO): Moved sent file to Logs/20241030T190539/Courier0096.lzma.bak
2024-10-31T17:17:26.290Z,1730395046.290 [DataOverHttps](INFO): SBD MOMSN=23352938
2024-10-31T17:17:39.306Z,1730395059.306 [NAL9602](INFO): SBD MO Status=2, MOMSN=59754, MT Status=2, MTMSN=0
2024-10-31T17:17:39.306Z,1730395059.306 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T17:17:58.248Z,1730395078.248 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20241031T171635/Courier0000.lzma
2024-10-31T17:17:59.249Z,1730395079.249 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0000.lzma.bak
2024-10-31T17:17:59.249Z,1730395079.249 [DataOverHttps](INFO): SBD MOMSN=23352940
2024-10-31T17:18:01.126Z,1730395081.126 [NAL9602](INFO): SBD MO Status=1, MOMSN=59754, MT Status=0, MTMSN=0
2024-10-31T17:18:01.126Z,1730395081.126 [NAL9602](INFO): No messages in MT queue
2024-10-31T17:18:15.003Z,1730395095.003 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241031T171635/Courier0003.lzma
2024-10-31T17:18:16.005Z,1730395096.005 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0003.lzma.bak
2024-10-31T17:18:16.005Z,1730395096.005 [DataOverHttps](INFO): SBD MOMSN=23352946
2024-10-31T17:18:31.876Z,1730395111.876 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T17:18:34.914Z,1730395114.914 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20241030T190539/Express0097.lzma
2024-10-31T17:18:36.918Z,1730395116.918 [DataOverHttps](INFO): Moved sent file to Logs/20241030T190539/Express0097.lzma.bak
2024-10-31T17:18:36.918Z,1730395116.918 [DataOverHttps](INFO): SBD MOMSN=23352948
2024-10-31T17:18:55.400Z,1730395135.400 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20241031T171635/Express0001.lzma
2024-10-31T17:18:56.401Z,1730395136.401 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0001.lzma.bak
2024-10-31T17:18:56.401Z,1730395136.401 [DataOverHttps](INFO): SBD MOMSN=23352951
2024-10-31T17:19:12.439Z,1730395152.439 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20241031T171635/Express0002.lzma
2024-10-31T17:19:13.441Z,1730395153.441 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0002.lzma.bak
2024-10-31T17:19:13.441Z,1730395153.441 [DataOverHttps](INFO): SBD MOMSN=23352955
2024-10-31T17:19:14.790Z,1730395154.790 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:19:14.791Z,1730395154.791 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:19:14.791Z,1730395154.791 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:24:15.546Z,1730395455.546 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:24:15.547Z,1730395455.547 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:24:15.547Z,1730395455.547 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:24:15.547Z,1730395455.547 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:24:15.958Z,1730395455.958 [Default:CheckIn:D] Stopped
2024-10-31T17:24:15.958Z,1730395455.958 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:24:16.355Z,1730395456.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  25.017489 min 
2024-10-31T17:24:16.355Z,1730395456.355 [Default:CheckIn:E] Stopped
2024-10-31T17:24:16.355Z,1730395456.355 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:24:16.355Z,1730395456.355 [Default:CheckIn] Stopped
2024-10-31T17:24:16.355Z,1730395456.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:24:16.355Z,1730395456.355 [Default:CheckIn](INFO): Running loop #5
2024-10-31T17:24:16.356Z,1730395456.356 [Default:CheckIn] Running Loop=5
2024-10-31T17:24:16.356Z,1730395456.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:24:16.356Z,1730395456.356 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:24:18.388Z,1730395458.388 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172417.00,A,3648.39187,N,12147.39702,W,9.525,53.98,311024,,,D*45
2024-10-31T17:24:18.390Z,1730395458.390 [NAL9602](INFO): GPS fix at 20241031T172417: (36.806531, -121.789950)
2024-10-31T17:24:18.421Z,1730395458.421 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:24:18.421Z,1730395458.421 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:24:28.471Z,1730395468.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0005.lzma
2024-10-31T17:24:29.473Z,1730395469.473 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0005.lzma.bak
2024-10-31T17:24:29.474Z,1730395469.474 [DataOverHttps](INFO): SBD MOMSN=23352957
2024-10-31T17:24:31.374Z,1730395471.374 [NAL9602](INFO): SBD MO Status=1, MOMSN=59755, MT Status=0, MTMSN=0
2024-10-31T17:24:31.374Z,1730395471.374 [NAL9602](INFO): No messages in MT queue
2024-10-31T17:24:45.103Z,1730395485.103 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20241031T171635/Express0007.lzma
2024-10-31T17:24:46.105Z,1730395486.105 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0007.lzma.bak
2024-10-31T17:24:46.105Z,1730395486.105 [DataOverHttps](INFO): SBD MOMSN=23352960
2024-10-31T17:24:47.555Z,1730395487.555 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:24:47.555Z,1730395487.555 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:24:47.555Z,1730395487.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:25:02.125Z,1730395502.125 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T17:29:48.365Z,1730395788.365 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:29:48.365Z,1730395788.365 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:29:48.365Z,1730395788.365 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:29:48.366Z,1730395788.366 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:29:48.774Z,1730395788.774 [Default:CheckIn:D] Stopped
2024-10-31T17:29:48.775Z,1730395788.775 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:29:49.179Z,1730395789.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  30.564435 min 
2024-10-31T17:29:49.179Z,1730395789.179 [Default:CheckIn:E] Stopped
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn] Stopped
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn](INFO): Running loop #6
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn] Running Loop=6
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:29:49.180Z,1730395789.180 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:29:51.191Z,1730395791.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172950.00,A,3648.22951,N,12147.14841,W,4.646,196.16,311024,,,D*70
2024-10-31T17:29:51.202Z,1730395791.202 [NAL9602](INFO): GPS fix at 20241031T172950: (36.803825, -121.785807)
2024-10-31T17:29:51.212Z,1730395791.212 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:29:51.212Z,1730395791.212 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:29:58.367Z,1730395798.367 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241031T171635/Courier0009.lzma
2024-10-31T17:29:59.369Z,1730395799.369 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0009.lzma.bak
2024-10-31T17:29:59.369Z,1730395799.369 [DataOverHttps](INFO): SBD MOMSN=23352970
2024-10-31T17:30:12.194Z,1730395812.194 [NAL9602](INFO): SBD MO Status=1, MOMSN=59756, MT Status=0, MTMSN=0
2024-10-31T17:30:12.194Z,1730395812.194 [NAL9602](INFO): No messages in MT queue
2024-10-31T17:30:15.101Z,1730395815.101 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20241031T171635/Express0010.lzma
2024-10-31T17:30:16.101Z,1730395816.101 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0010.lzma.bak
2024-10-31T17:30:16.101Z,1730395816.101 [DataOverHttps](INFO): SBD MOMSN=23352973
2024-10-31T17:30:17.468Z,1730395817.468 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:30:17.468Z,1730395817.468 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:30:17.468Z,1730395817.468 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:30:42.943Z,1730395842.943 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T17:35:17.902Z,1730396117.902 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:35:17.902Z,1730396117.902 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:35:17.903Z,1730396117.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:35:17.903Z,1730396117.903 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:35:18.298Z,1730396118.298 [Default:CheckIn:D] Stopped
2024-10-31T17:35:18.298Z,1730396118.298 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:35:18.707Z,1730396118.707 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  36.056494 min 
2024-10-31T17:35:18.707Z,1730396118.707 [Default:CheckIn:E] Stopped
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn] Stopped
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn](INFO): Running loop #7
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn] Running Loop=7
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:35:18.708Z,1730396118.708 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:35:20.715Z,1730396120.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173519.00,A,3648.14986,N,12147.22186,W,0.039,213.91,311024,,,D*73
2024-10-31T17:35:20.717Z,1730396120.717 [NAL9602](INFO): GPS fix at 20241031T173519: (36.802498, -121.787031)
2024-10-31T17:35:20.728Z,1730396120.728 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:35:20.728Z,1730396120.728 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:35:28.399Z,1730396128.399 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0012.lzma
2024-10-31T17:35:29.401Z,1730396129.401 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0012.lzma.bak
2024-10-31T17:35:29.401Z,1730396129.401 [DataOverHttps](INFO): SBD MOMSN=23352978
2024-10-31T17:35:45.511Z,1730396145.511 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20241031T171635/Express0013.lzma
2024-10-31T17:35:46.513Z,1730396146.513 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0013.lzma.bak
2024-10-31T17:35:46.513Z,1730396146.513 [DataOverHttps](INFO): SBD MOMSN=23352981
2024-10-31T17:35:47.828Z,1730396147.828 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:35:47.828Z,1730396147.828 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:35:47.828Z,1730396147.828 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:35:58.722Z,1730396158.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=59757, MT Status=2, MTMSN=0
2024-10-31T17:35:58.722Z,1730396158.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T17:36:14.882Z,1730396174.882 [NAL9602](INFO): SBD MO Status=1, MOMSN=59757, MT Status=0, MTMSN=0
2024-10-31T17:36:14.883Z,1730396174.883 [NAL9602](INFO): No messages in MT queue
2024-10-31T17:36:45.635Z,1730396205.635 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T17:40:48.627Z,1730396448.627 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:40:48.627Z,1730396448.627 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:40:48.627Z,1730396448.627 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:40:48.628Z,1730396448.628 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:40:49.014Z,1730396449.014 [Default:CheckIn:D] Stopped
2024-10-31T17:40:49.014Z,1730396449.014 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:40:49.419Z,1730396449.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  41.568433 min 
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn:E] Stopped
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn] Stopped
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn](INFO): Running loop #8
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn] Running Loop=8
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:40:49.420Z,1730396449.420 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:40:51.431Z,1730396451.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174050.00,A,3648.14205,N,12147.23030,W,0.019,211.93,311024,,,D*73
2024-10-31T17:40:51.433Z,1730396451.433 [NAL9602](INFO): GPS fix at 20241031T174050: (36.802368, -121.787172)
2024-10-31T17:40:51.451Z,1730396451.451 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:40:51.473Z,1730396451.473 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:40:59.951Z,1730396459.951 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0015.lzma
2024-10-31T17:41:00.953Z,1730396460.953 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0015.lzma.bak
2024-10-31T17:41:00.953Z,1730396460.953 [DataOverHttps](INFO): SBD MOMSN=23352993
2024-10-31T17:41:10.883Z,1730396470.883 [NAL9602](INFO): SBD MO Status=1, MOMSN=59758, MT Status=0, MTMSN=0
2024-10-31T17:41:10.883Z,1730396470.883 [NAL9602](INFO): No messages in MT queue
2024-10-31T17:41:16.788Z,1730396476.788 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241031T171635/Express0016.lzma
2024-10-31T17:41:17.789Z,1730396477.789 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0016.lzma.bak
2024-10-31T17:41:17.790Z,1730396477.790 [DataOverHttps](INFO): SBD MOMSN=23352997
2024-10-31T17:41:18.976Z,1730396478.976 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:41:18.976Z,1730396478.976 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:41:18.977Z,1730396478.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:41:41.584Z,1730396501.584 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T17:46:19.700Z,1730396779.700 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:46:19.700Z,1730396779.700 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:46:19.700Z,1730396779.700 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:46:19.700Z,1730396779.700 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:46:20.124Z,1730396780.124 [Default:CheckIn:D] Stopped
2024-10-31T17:46:20.124Z,1730396780.124 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:46:20.502Z,1730396780.502 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  47.086934 min 
2024-10-31T17:46:20.502Z,1730396780.502 [Default:CheckIn:E] Stopped
2024-10-31T17:46:20.502Z,1730396780.502 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:46:20.502Z,1730396780.502 [Default:CheckIn] Stopped
2024-10-31T17:46:20.503Z,1730396780.503 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:46:20.503Z,1730396780.503 [Default:CheckIn](INFO): Running loop #9
2024-10-31T17:46:20.503Z,1730396780.503 [Default:CheckIn] Running Loop=9
2024-10-31T17:46:20.503Z,1730396780.503 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:46:20.503Z,1730396780.503 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:46:22.522Z,1730396782.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174621.00,A,3648.14144,N,12147.22968,W,0.039,211.93,311024,,,D*72
2024-10-31T17:46:22.525Z,1730396782.525 [NAL9602](INFO): GPS fix at 20241031T174621: (36.802357, -121.787161)
2024-10-31T17:46:22.545Z,1730396782.545 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:46:22.545Z,1730396782.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:46:30.339Z,1730396790.339 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0018.lzma
2024-10-31T17:46:31.341Z,1730396791.341 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0018.lzma.bak
2024-10-31T17:46:31.341Z,1730396791.341 [DataOverHttps](INFO): SBD MOMSN=23353022
2024-10-31T17:46:37.246Z,1730396797.246 [NAL9602](INFO): SBD MO Status=1, MOMSN=59759, MT Status=0, MTMSN=0
2024-10-31T17:46:37.246Z,1730396797.246 [NAL9602](INFO): No messages in MT queue
2024-10-31T17:46:47.032Z,1730396807.032 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241031T171635/Express0019.lzma
2024-10-31T17:46:48.034Z,1730396808.033 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0019.lzma.bak
2024-10-31T17:46:48.034Z,1730396808.034 [DataOverHttps](INFO): SBD MOMSN=23353025
2024-10-31T17:46:49.398Z,1730396809.398 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:46:49.398Z,1730396809.398 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:46:49.398Z,1730396809.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:47:07.997Z,1730396827.997 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T17:51:50.028Z,1730397110.028 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:51:50.028Z,1730397110.028 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:51:50.028Z,1730397110.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:51:50.028Z,1730397110.028 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:51:50.440Z,1730397110.440 [Default:CheckIn:D] Stopped
2024-10-31T17:51:50.440Z,1730397110.440 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:51:50.835Z,1730397110.835 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  52.592179 min 
2024-10-31T17:51:50.835Z,1730397110.835 [Default:CheckIn:E] Stopped
2024-10-31T17:51:50.835Z,1730397110.835 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:51:50.835Z,1730397110.835 [Default:CheckIn] Stopped
2024-10-31T17:51:50.835Z,1730397110.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:51:50.835Z,1730397110.835 [Default:CheckIn](INFO): Running loop #10
2024-10-31T17:51:50.836Z,1730397110.836 [Default:CheckIn] Running Loop=10
2024-10-31T17:51:50.836Z,1730397110.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:51:50.836Z,1730397110.836 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:51:52.847Z,1730397112.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175152.00,A,3648.17251,N,12147.27964,W,0.194,269.35,311024,,,D*78
2024-10-31T17:51:52.849Z,1730397112.849 [NAL9602](INFO): GPS fix at 20241031T175152: (36.802875, -121.787994)
2024-10-31T17:51:52.864Z,1730397112.864 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:51:52.864Z,1730397112.864 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:51:59.803Z,1730397119.803 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241031T171635/Courier0021.lzma
2024-10-31T17:52:00.806Z,1730397120.806 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0021.lzma.bak
2024-10-31T17:52:00.806Z,1730397120.806 [DataOverHttps](INFO): SBD MOMSN=23353038
2024-10-31T17:52:16.559Z,1730397136.559 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20241031T171635/Express0022.lzma
2024-10-31T17:52:17.562Z,1730397137.562 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0022.lzma.bak
2024-10-31T17:52:17.562Z,1730397137.562 [DataOverHttps](INFO): SBD MOMSN=23353041
2024-10-31T17:52:18.716Z,1730397138.716 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:52:18.716Z,1730397138.716 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:52:18.716Z,1730397138.716 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:52:34.931Z,1730397154.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=59760, MT Status=2, MTMSN=0
2024-10-31T17:52:34.932Z,1730397154.932 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T17:55:02.110Z,1730397302.110 [NAL9602](INFO): SBD MO Status=2, MOMSN=59760, MT Status=2, MTMSN=0
2024-10-31T17:55:02.110Z,1730397302.110 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T17:56:55.657Z,1730397415.657 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-31T17:57:19.514Z,1730397439.514 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T17:57:19.514Z,1730397439.514 [Default:CheckIn:C.Wait] Stopped
2024-10-31T17:57:19.514Z,1730397439.514 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T17:57:19.514Z,1730397439.514 [Default:CheckIn:D] Running Loop=1
2024-10-31T17:57:19.906Z,1730397439.906 [Default:CheckIn:D] Stopped
2024-10-31T17:57:19.906Z,1730397439.906 [Default:CheckIn:E] Running Loop=1
2024-10-31T17:57:20.316Z,1730397440.316 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  58.083293 min 
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn:E] Stopped
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn] Stopped
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn](INFO): Running loop #11
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn] Running Loop=11
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T17:57:20.317Z,1730397440.317 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T17:57:22.323Z,1730397442.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175721.00,A,3648.17327,N,12147.27761,W,1.011,271.61,311024,,,D*74
2024-10-31T17:57:22.325Z,1730397442.325 [NAL9602](INFO): GPS fix at 20241031T175721: (36.802888, -121.787960)
2024-10-31T17:57:22.336Z,1730397442.336 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T17:57:22.336Z,1730397442.336 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T17:57:29.267Z,1730397449.267 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0024.lzma
2024-10-31T17:57:30.269Z,1730397450.269 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0024.lzma.bak
2024-10-31T17:57:30.269Z,1730397450.269 [DataOverHttps](INFO): SBD MOMSN=23353044
2024-10-31T17:57:46.059Z,1730397466.059 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20241031T171635/Express0025.lzma
2024-10-31T17:57:47.061Z,1730397467.061 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0025.lzma.bak
2024-10-31T17:57:47.062Z,1730397467.062 [DataOverHttps](INFO): SBD MOMSN=23353047
2024-10-31T17:57:48.196Z,1730397468.196 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T17:57:48.196Z,1730397468.196 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T17:57:48.196Z,1730397468.196 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T17:57:54.650Z,1730397474.650 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:02:48.939Z,1730397768.939 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:02:48.939Z,1730397768.939 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:02:48.939Z,1730397768.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:02:48.939Z,1730397768.939 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:02:49.368Z,1730397769.368 [Default:CheckIn:D] Stopped
2024-10-31T18:02:49.368Z,1730397769.368 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:02:49.744Z,1730397769.744 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  63.574320 min 
2024-10-31T18:02:49.744Z,1730397769.744 [Default:CheckIn:E] Stopped
2024-10-31T18:02:49.744Z,1730397769.744 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:02:49.744Z,1730397769.744 [Default:CheckIn] Stopped
2024-10-31T18:02:49.744Z,1730397769.744 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:02:49.745Z,1730397769.745 [Default:CheckIn](INFO): Running loop #12
2024-10-31T18:02:49.745Z,1730397769.745 [Default:CheckIn] Running Loop=12
2024-10-31T18:02:49.745Z,1730397769.745 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:02:49.745Z,1730397769.745 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:02:51.746Z,1730397771.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180251.00,A,3648.17975,N,12147.28016,W,0.078,271.61,311024,,,A*72
2024-10-31T18:02:51.748Z,1730397771.748 [NAL9602](INFO): GPS fix at 20241031T180251: (36.802996, -121.788003)
2024-10-31T18:02:51.798Z,1730397771.798 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:02:51.798Z,1730397771.798 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:02:59.835Z,1730397779.835 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0027.lzma
2024-10-31T18:03:00.837Z,1730397780.837 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0027.lzma.bak
2024-10-31T18:03:00.837Z,1730397780.837 [DataOverHttps](INFO): SBD MOMSN=23353058
2024-10-31T18:03:16.604Z,1730397796.604 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20241031T171635/Express0028.lzma
2024-10-31T18:03:17.597Z,1730397797.597 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0028.lzma.bak
2024-10-31T18:03:17.598Z,1730397797.598 [DataOverHttps](INFO): SBD MOMSN=23353061
2024-10-31T18:03:19.007Z,1730397799.007 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:03:19.007Z,1730397799.007 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:03:19.007Z,1730397799.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:03:22.213Z,1730397802.213 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-10-31T18:03:22.292Z,1730397802.292 [NAL9602](ERROR): received: 
+CSQ:0

OK760, 2, 0, 0, 0

OK
2024-10-31T18:04:45.466Z,1730397885.466 [NAL9602](INFO): SBD MO Status=2, MOMSN=59760, MT Status=2, MTMSN=0
2024-10-31T18:04:45.466Z,1730397885.466 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T18:07:34.390Z,1730398054.390 [NAL9602](INFO): SBD MO Status=2, MOMSN=59760, MT Status=2, MTMSN=0
2024-10-31T18:07:34.391Z,1730398054.391 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T18:07:54.597Z,1730398074.597 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-31T18:08:19.698Z,1730398099.698 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:08:19.698Z,1730398099.698 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:08:19.698Z,1730398099.698 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:08:19.699Z,1730398099.699 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:08:20.071Z,1730398100.071 [Default:CheckIn:D] Stopped
2024-10-31T18:08:20.072Z,1730398100.072 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  69.086043 min 
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn:E] Stopped
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn] Stopped
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn](INFO): Running loop #13
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn] Running Loop=13
2024-10-31T18:08:20.473Z,1730398100.473 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:08:20.474Z,1730398100.474 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:08:22.470Z,1730398102.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180821.00,A,3648.17465,N,12147.27637,W,0.350,299.04,311024,,,A*75
2024-10-31T18:08:22.473Z,1730398102.473 [NAL9602](INFO): GPS fix at 20241031T180821: (36.802911, -121.787939)
2024-10-31T18:08:22.520Z,1730398102.520 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:08:22.520Z,1730398102.520 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:08:30.479Z,1730398110.479 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0030.lzma
2024-10-31T18:08:31.482Z,1730398111.482 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0030.lzma.bak
2024-10-31T18:08:31.482Z,1730398111.482 [DataOverHttps](INFO): SBD MOMSN=23353066
2024-10-31T18:08:47.184Z,1730398127.184 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20241031T171635/Express0031.lzma
2024-10-31T18:08:48.186Z,1730398128.186 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0031.lzma.bak
2024-10-31T18:08:48.186Z,1730398128.186 [DataOverHttps](INFO): SBD MOMSN=23353072
2024-10-31T18:08:49.547Z,1730398129.547 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:08:49.547Z,1730398129.547 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:08:49.547Z,1730398129.547 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:08:54.413Z,1730398134.413 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:13:50.218Z,1730398430.218 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:13:50.218Z,1730398430.218 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:13:50.218Z,1730398430.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:13:50.219Z,1730398430.219 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:13:50.628Z,1730398430.628 [Default:CheckIn:D] Stopped
2024-10-31T18:13:50.628Z,1730398430.628 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:13:51.027Z,1730398431.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  74.595329 min 
2024-10-31T18:13:51.028Z,1730398431.028 [Default:CheckIn:E] Stopped
2024-10-31T18:13:51.028Z,1730398431.028 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:13:51.028Z,1730398431.028 [Default:CheckIn] Stopped
2024-10-31T18:13:51.028Z,1730398431.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:13:51.029Z,1730398431.029 [Default:CheckIn](INFO): Running loop #14
2024-10-31T18:13:51.029Z,1730398431.029 [Default:CheckIn] Running Loop=14
2024-10-31T18:13:51.029Z,1730398431.029 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:13:51.029Z,1730398431.029 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:13:53.039Z,1730398433.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181352.00,A,3648.17566,N,12147.27878,W,0.078,299.04,311024,,,A*75
2024-10-31T18:13:53.041Z,1730398433.041 [NAL9602](INFO): GPS fix at 20241031T181352: (36.802928, -121.787980)
2024-10-31T18:13:53.072Z,1730398433.072 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:13:53.072Z,1730398433.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:14:00.455Z,1730398440.455 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241031T171635/Courier0033.lzma
2024-10-31T18:14:01.457Z,1730398441.457 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0033.lzma.bak
2024-10-31T18:14:01.458Z,1730398441.458 [DataOverHttps](INFO): SBD MOMSN=23353083
2024-10-31T18:14:17.384Z,1730398457.384 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241031T171635/Express0034.lzma
2024-10-31T18:14:18.386Z,1730398458.386 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0034.lzma.bak
2024-10-31T18:14:18.386Z,1730398458.386 [DataOverHttps](INFO): SBD MOMSN=23353086
2024-10-31T18:14:19.723Z,1730398459.723 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:14:19.723Z,1730398459.723 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:14:19.748Z,1730398459.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:14:23.777Z,1730398463.777 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-10-31T18:14:23.852Z,1730398463.852 [NAL9602](ERROR): received: 
+CSQ:0

OK760, 2, 0, 0, 0

OK
2024-10-31T18:15:57.937Z,1730398557.937 [DataOverHttps](IMPORTANT): SBD MTMSN=20241031T181557
2024-10-31T18:16:05.243Z,1730398565.243 [DataOverHttps](INFO): Received command: strobe off
2024-10-31T18:16:05.297Z,1730398565.297 [CommandExec](IMPORTANT): got command strobe off
2024-10-31T18:16:05.297Z,1730398565.297 [CommandExec](IMPORTANT): Deactivating strobe
2024-10-31T18:18:55.373Z,1730398735.373 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-31T18:19:20.444Z,1730398760.444 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:19:20.444Z,1730398760.444 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:19:20.444Z,1730398760.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:19:20.444Z,1730398760.444 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:19:20.839Z,1730398760.839 [Default:CheckIn:D] Stopped
2024-10-31T18:19:20.839Z,1730398760.839 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:19:21.243Z,1730398761.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  80.098836 min 
2024-10-31T18:19:21.243Z,1730398761.243 [Default:CheckIn:E] Stopped
2024-10-31T18:19:21.243Z,1730398761.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:19:21.243Z,1730398761.243 [Default:CheckIn] Stopped
2024-10-31T18:19:21.243Z,1730398761.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:19:21.244Z,1730398761.244 [Default:CheckIn](INFO): Running loop #15
2024-10-31T18:19:21.244Z,1730398761.244 [Default:CheckIn] Running Loop=15
2024-10-31T18:19:21.244Z,1730398761.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:19:21.244Z,1730398761.244 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:19:23.251Z,1730398763.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181922.00,A,3648.17594,N,12147.27574,W,0.078,299.04,311024,,,A*74
2024-10-31T18:19:23.253Z,1730398763.253 [NAL9602](INFO): GPS fix at 20241031T181922: (36.802932, -121.787929)
2024-10-31T18:19:23.265Z,1730398763.265 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:19:23.265Z,1730398763.265 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:19:31.099Z,1730398771.099 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0036.lzma
2024-10-31T18:19:32.101Z,1730398772.101 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0036.lzma.bak
2024-10-31T18:19:32.102Z,1730398772.102 [DataOverHttps](INFO): SBD MOMSN=23353115
2024-10-31T18:19:47.880Z,1730398787.880 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20241031T171635/Express0037.lzma
2024-10-31T18:19:48.881Z,1730398788.881 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0037.lzma.bak
2024-10-31T18:19:48.882Z,1730398788.882 [DataOverHttps](INFO): SBD MOMSN=23353123
2024-10-31T18:19:55.578Z,1730398795.578 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:19:56.783Z,1730398796.783 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:20:04.859Z,1730398804.859 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20241031T171635/Express0040.lzma
2024-10-31T18:20:05.861Z,1730398805.861 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0040.lzma.bak
2024-10-31T18:20:05.862Z,1730398805.862 [DataOverHttps](INFO): SBD MOMSN=23353130
2024-10-31T18:20:07.303Z,1730398807.303 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:20:07.303Z,1730398807.303 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:20:07.303Z,1730398807.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:20:10.128Z,1730398810.128 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:20:23.460Z,1730398823.460 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:20:36.788Z,1730398836.788 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:20:50.124Z,1730398850.124 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:21:03.457Z,1730398863.457 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:21:17.178Z,1730398877.178 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:21:30.510Z,1730398890.510 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:21:43.856Z,1730398903.856 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:21:57.578Z,1730398917.578 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:22:11.718Z,1730398931.718 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:22:17.375Z,1730398937.375 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-10-31T18:25:07.881Z,1730399107.881 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:25:07.881Z,1730399107.881 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:25:07.881Z,1730399107.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:25:07.882Z,1730399107.882 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:25:08.292Z,1730399108.292 [Default:CheckIn:D] Stopped
2024-10-31T18:25:08.292Z,1730399108.292 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:25:08.691Z,1730399108.691 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  85.889722 min 
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn:E] Stopped
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn] Stopped
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn](INFO): Running loop #16
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn] Running Loop=16
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:25:08.692Z,1730399108.692 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:25:10.702Z,1730399110.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182509.00,A,3648.17410,N,12147.27587,W,0.544,0.00,311024,,,A*7F
2024-10-31T18:25:10.704Z,1730399110.704 [NAL9602](INFO): GPS fix at 20241031T182509: (36.802902, -121.787931)
2024-10-31T18:25:10.739Z,1730399110.739 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:25:10.739Z,1730399110.739 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:25:18.124Z,1730399118.124 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0042.lzma
2024-10-31T18:25:19.125Z,1730399119.125 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0042.lzma.bak
2024-10-31T18:25:19.126Z,1730399119.126 [DataOverHttps](INFO): SBD MOMSN=23353132
2024-10-31T18:25:36.027Z,1730399136.027 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20241031T171635/Express0043.lzma
2024-10-31T18:25:37.029Z,1730399137.029 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0043.lzma.bak
2024-10-31T18:25:37.030Z,1730399137.030 [DataOverHttps](INFO): SBD MOMSN=23353135
2024-10-31T18:25:38.196Z,1730399138.196 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:25:38.196Z,1730399138.196 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:25:38.196Z,1730399138.196 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:25:41.405Z,1730399141.405 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-10-31T18:25:41.488Z,1730399141.488 [NAL9602](ERROR): received: 
+CSQ:1

OK760, 2, 0, 0, 0

OK
2024-10-31T18:27:06.706Z,1730399226.706 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-31T18:28:08.125Z,1730399288.125 [NAL9602](INFO): SBD MO Status=2, MOMSN=59760, MT Status=2, MTMSN=0
2024-10-31T18:28:08.125Z,1730399288.125 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T18:28:31.960Z,1730399311.960 [NAL9602](INFO): SBD MO Status=1, MOMSN=59760, MT Status=0, MTMSN=0
2024-10-31T18:28:31.960Z,1730399311.960 [NAL9602](INFO): No messages in MT queue
2024-10-31T18:29:02.681Z,1730399342.681 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:29:29.325Z,1730399369.325 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-31T18:29:34.975Z,1730399374.975 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:29:48.307Z,1730399388.307 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:30:01.638Z,1730399401.638 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:30:14.971Z,1730399414.971 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:30:27.926Z,1730399427.926 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:30:38.855Z,1730399438.855 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:30:38.855Z,1730399438.855 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:30:38.856Z,1730399438.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:30:38.856Z,1730399438.856 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:30:39.262Z,1730399439.262 [Default:CheckIn:D] Stopped
2024-10-31T18:30:39.262Z,1730399439.262 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:30:39.658Z,1730399439.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  91.405892 min 
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn:E] Stopped
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn] Stopped
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn](INFO): Running loop #17
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn] Running Loop=17
2024-10-31T18:30:39.659Z,1730399439.659 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:30:39.660Z,1730399439.660 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:30:41.662Z,1730399441.662 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:30:41.674Z,1730399441.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183040.00,A,3648.17570,N,12147.27460,W,0.078,230.01,311024,,,A*73
2024-10-31T18:30:41.677Z,1730399441.677 [NAL9602](INFO): GPS fix at 20241031T183040: (36.802928, -121.787910)
2024-10-31T18:30:41.687Z,1730399441.687 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:30:41.687Z,1730399441.687 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:30:49.499Z,1730399449.499 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241031T171635/Courier0045.lzma
2024-10-31T18:30:50.502Z,1730399450.502 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0045.lzma.bak
2024-10-31T18:30:50.502Z,1730399450.502 [DataOverHttps](INFO): SBD MOMSN=23353146
2024-10-31T18:30:55.815Z,1730399455.815 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:30:56.650Z,1730399456.650 [NAL9602](INFO): SBD MO Status=1, MOMSN=59761, MT Status=0, MTMSN=0
2024-10-31T18:30:56.650Z,1730399456.650 [NAL9602](INFO): No messages in MT queue
2024-10-31T18:31:08.891Z,1730399468.891 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241031T171635/Express0046.lzma
2024-10-31T18:31:09.562Z,1730399469.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:31:09.894Z,1730399469.894 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0046.lzma.bak
2024-10-31T18:31:09.894Z,1730399469.894 [DataOverHttps](INFO): SBD MOMSN=23353152
2024-10-31T18:31:11.216Z,1730399471.216 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:31:11.217Z,1730399471.217 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:31:11.217Z,1730399471.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:31:24.106Z,1730399484.106 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:31:27.340Z,1730399487.340 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:31:37.842Z,1730399497.842 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:31:51.174Z,1730399511.174 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:36:11.883Z,1730399771.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:36:11.883Z,1730399771.883 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:36:11.883Z,1730399771.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:36:11.883Z,1730399771.883 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:36:12.287Z,1730399772.287 [Default:CheckIn:D] Stopped
2024-10-31T18:36:12.287Z,1730399772.287 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:36:12.695Z,1730399772.695 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  96.956307 min 
2024-10-31T18:36:12.695Z,1730399772.695 [Default:CheckIn:E] Stopped
2024-10-31T18:36:12.695Z,1730399772.695 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:36:12.695Z,1730399772.695 [Default:CheckIn] Stopped
2024-10-31T18:36:12.696Z,1730399772.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:36:12.696Z,1730399772.696 [Default:CheckIn](INFO): Running loop #18
2024-10-31T18:36:12.696Z,1730399772.696 [Default:CheckIn] Running Loop=18
2024-10-31T18:36:12.696Z,1730399772.696 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:36:12.696Z,1730399772.696 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:36:14.712Z,1730399774.712 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183613.00,A,3648.17215,N,12147.28115,W,0.661,94.64,311024,,,A*4E
2024-10-31T18:36:14.714Z,1730399774.714 [NAL9602](INFO): GPS fix at 20241031T183613: (36.802869, -121.788019)
2024-10-31T18:36:14.730Z,1730399774.730 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:36:14.730Z,1730399774.730 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:36:24.867Z,1730399784.867 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241031T171635/Courier0048.lzma
2024-10-31T18:36:25.870Z,1730399785.870 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0048.lzma.bak
2024-10-31T18:36:25.870Z,1730399785.870 [DataOverHttps](INFO): SBD MOMSN=23353183
2024-10-31T18:36:42.268Z,1730399802.268 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241031T171635/Express0049.lzma
2024-10-31T18:36:43.269Z,1730399803.269 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0049.lzma.bak
2024-10-31T18:36:43.270Z,1730399803.270 [DataOverHttps](INFO): SBD MOMSN=23353186
2024-10-31T18:36:44.672Z,1730399804.672 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:36:44.672Z,1730399804.672 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:36:44.672Z,1730399804.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:39:07.414Z,1730399947.414 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:39:20.755Z,1730399960.755 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:39:33.674Z,1730399973.674 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:39:47.006Z,1730399987.006 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:40:01.551Z,1730400001.551 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:40:10.446Z,1730400010.446 [NAL9602](INFO): SBD MO Status=2, MOMSN=59762, MT Status=2, MTMSN=0
2024-10-31T18:40:10.446Z,1730400010.446 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-31T18:40:15.286Z,1730400015.286 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:40:29.918Z,1730400029.918 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:40:35.596Z,1730400035.596 [NAL9602](INFO): SBD MO Status=1, MOMSN=59762, MT Status=0, MTMSN=0
2024-10-31T18:40:35.596Z,1730400035.596 [NAL9602](INFO): No messages in MT queue
2024-10-31T18:40:44.078Z,1730400044.078 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:40:57.462Z,1730400057.462 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:41:06.348Z,1730400066.348 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:41:10.790Z,1730400070.790 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:41:24.526Z,1730400084.526 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T18:41:45.153Z,1730400105.153 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:41:45.153Z,1730400105.153 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:41:45.154Z,1730400105.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:41:45.154Z,1730400105.154 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:41:45.546Z,1730400105.546 [Default:CheckIn:D] Stopped
2024-10-31T18:41:45.546Z,1730400105.546 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:41:45.951Z,1730400105.951 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  102.510620 min 
2024-10-31T18:41:45.951Z,1730400105.951 [Default:CheckIn:E] Stopped
2024-10-31T18:41:45.951Z,1730400105.951 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:41:45.951Z,1730400105.951 [Default:CheckIn] Stopped
2024-10-31T18:41:45.951Z,1730400105.951 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:41:45.952Z,1730400105.952 [Default:CheckIn](INFO): Running loop #19
2024-10-31T18:41:45.952Z,1730400105.952 [Default:CheckIn] Running Loop=19
2024-10-31T18:41:45.952Z,1730400105.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:41:45.952Z,1730400105.952 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:41:47.963Z,1730400107.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184147.00,A,3648.17983,N,12147.27974,W,0.564,124.90,311024,,,A*7C
2024-10-31T18:41:47.965Z,1730400107.965 [NAL9602](INFO): GPS fix at 20241031T184147: (36.802997, -121.787996)
2024-10-31T18:41:47.981Z,1730400107.981 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:41:47.981Z,1730400107.981 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:41:55.943Z,1730400115.943 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241031T171635/Courier0051.lzma
2024-10-31T18:41:56.945Z,1730400116.945 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0051.lzma.bak
2024-10-31T18:41:56.946Z,1730400116.946 [DataOverHttps](INFO): SBD MOMSN=23353208
2024-10-31T18:42:02.122Z,1730400122.122 [NAL9602](INFO): SBD MO Status=1, MOMSN=59763, MT Status=0, MTMSN=0
2024-10-31T18:42:02.122Z,1730400122.122 [NAL9602](INFO): No messages in MT queue
2024-10-31T18:42:13.071Z,1730400133.071 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20241031T171635/Express0052.lzma
2024-10-31T18:42:14.073Z,1730400134.073 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0052.lzma.bak
2024-10-31T18:42:14.074Z,1730400134.074 [DataOverHttps](INFO): SBD MOMSN=23353211
2024-10-31T18:42:15.478Z,1730400135.478 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:42:15.478Z,1730400135.478 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:42:15.479Z,1730400135.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:42:32.865Z,1730400152.865 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:45:58.682Z,1730400358.682 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4.
2024-10-31T18:45:58.686Z,1730400358.686 [BPC1](FAULT): Failed to receive data from 10 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 37, 39, 50, 51, 54.
2024-10-31T18:46:08.783Z,1730400368.783 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2024-10-31T18:46:08.783Z,1730400368.783 [DropWeight] Hardware Fault, FailCount= 1
2024-10-31T18:46:08.783Z,1730400368.783 [DropWeight](ERROR): Hardware Fault
2024-10-31T18:46:08.801Z,1730400368.801 [CommandExec](FAULT): Scheduling is paused
2024-10-31T18:46:08.801Z,1730400368.801 [CBIT](INFO): Critical error at 20241031T184608
2024-10-31T18:46:08.804Z,1730400368.804 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-10-31T18:46:08.804Z,1730400368.804 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-10-31T18:46:09.215Z,1730400369.215 [CBIT](INFO): Critical error at 20241031T184608
2024-10-31T18:47:15.970Z,1730400435.970 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:47:15.970Z,1730400435.970 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:47:15.970Z,1730400435.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:47:15.970Z,1730400435.970 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:47:16.382Z,1730400436.382 [Default:CheckIn:D] Stopped
2024-10-31T18:47:16.382Z,1730400436.382 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  108.024569 min 
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn:E] Stopped
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn] Stopped
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn](INFO): Running loop #20
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn] Running Loop=20
2024-10-31T18:47:16.774Z,1730400436.774 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:47:16.775Z,1730400436.775 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:48:46.135Z,1730400526.135 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-31T18:48:48.984Z,1730400528.984 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2024-10-31T18:48:57.149Z,1730400537.149 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002326
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002130
CHAN A3 (5V): -0.001328
CHAN B0 (3.3V): -0.000086
CHAN B1 (3.15aV): -0.000032
CHAN B2 (3.15bV): -0.000229
CHAN B3 (GND): 0.000123
OPEN: -0.000133
Full Scale: +/- 1 mA
2024-10-31T18:52:17.088Z,1730400737.088 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-31T18:47:16.8Z
2024-10-31T18:52:17.088Z,1730400737.088 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T18:52:17.088Z,1730400737.088 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-31T18:52:21.541Z,1730400741.541 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-31T18:52:27.348Z,1730400747.348 [DataOverHttps](INFO): Sending 258 bytes from file Logs/20241031T171635/Courier0054.lzma
2024-10-31T18:52:28.350Z,1730400748.350 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Courier0054.lzma.bak
2024-10-31T18:52:28.350Z,1730400748.350 [DataOverHttps](INFO): SBD MOMSN=23353257
2024-10-31T18:52:44.104Z,1730400764.104 [DataOverHttps](INFO): Sending 482 bytes from file Logs/20241031T171635/Express0055.lzma
2024-10-31T18:52:45.105Z,1730400765.105 [DataOverHttps](INFO): Moved sent file to Logs/20241031T171635/Express0055.lzma.bak
2024-10-31T18:52:45.106Z,1730400765.106 [DataOverHttps](INFO): SBD MOMSN=23353261
2024-10-31T18:52:46.206Z,1730400766.206 [Default:CheckIn:Read_Iridium] Stopped
2024-10-31T18:52:46.206Z,1730400766.206 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-31T18:52:46.206Z,1730400766.206 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-31T18:52:52.308Z,1730400772.308 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-31T18:57:46.687Z,1730401066.687 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-31T18:57:46.687Z,1730401066.687 [Default:CheckIn:C.Wait] Stopped
2024-10-31T18:57:46.687Z,1730401066.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-31T18:57:46.687Z,1730401066.687 [Default:CheckIn:D] Running Loop=1
2024-10-31T18:57:47.099Z,1730401067.099 [Default:CheckIn:D] Stopped
2024-10-31T18:57:47.099Z,1730401067.099 [Default:CheckIn:E] Running Loop=1
2024-10-31T18:57:47.490Z,1730401067.490 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  118.536507 min 
2024-10-31T18:57:47.490Z,1730401067.490 [Default:CheckIn:E] Stopped
2024-10-31T18:57:47.490Z,1730401067.490 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-31T18:57:47.490Z,1730401067.490 [Default:CheckIn] Stopped
2024-10-31T18:57:47.490Z,1730401067.490 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T18:57:47.491Z,1730401067.491 [Default:CheckIn](INFO): Running loop #21
2024-10-31T18:57:47.491Z,1730401067.491 [Default:CheckIn] Running Loop=21
2024-10-31T18:57:47.491Z,1730401067.491 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-31T18:57:47.491Z,1730401067.491 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-31T18:57:49.122Z,1730401069.122 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-31T18:57:49.122Z,1730401069.122 [NAL9602] Data Fault, FailCount= 1
2024-10-31T18:57:49.122Z,1730401069.122 [NAL9602](ERROR): Data Fault
2024-10-31T18:57:49.141Z,1730401069.141 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-31T18:57:49.501Z,1730401069.501 [NAL9602](INFO): Powering down
2024-10-31T18:57:50.341Z,1730401070.341 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-31T18:57:50.341Z,1730401070.341 [NAL9602] No Fault, FailCount= 1
2024-10-31T18:58:19.800Z,1730401099.800 [NAL9602](INFO): Powering up NAL9602
2024-10-31T18:58:27.481Z,1730401107.481 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-31T18:58:30.709Z,1730401110.709 [NAL9602](INFO): NAL9602 initialized
2024-10-31T18:58:39.213Z,1730401119.213 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-31T19:00:56.984Z,1730401256.984 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T19:01:09.122Z,1730401269.122 [CBIT](INFO): Clearing failed state for component DropWeight
2024-10-31T19:01:09.122Z,1730401269.122 [DropWeight] No Fault, FailCount= 1
2024-10-31T19:01:10.720Z,1730401270.720 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T19:01:24.052Z,1730401284.052 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T19:01:37.369Z,1730401297.369 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T19:01:44.792Z,1730401304.792 [CommandExec](IMPORTANT): got command quit
2024-10-31T19:01:45.796Z,1730401305.796 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:45.796Z,1730401305.796 [CommandExec](INFO): Uninitializing the command executive.
2024-10-31T19:01:45.796Z,1730401305.796 [CommandExec](INFO): Uninitializing the command scheduler.
2024-10-31T19:01:45.796Z,1730401305.796 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:45.935Z,1730401305.935 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2024-10-31T19:01:45.936Z,1730401305.936 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-10-31T19:01:45.936Z,1730401305.936 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:45.937Z,1730401305.937 [NavChartDb](INFO): Join timeout helper Thread ID is 4190
2024-10-31T19:01:46.180Z,1730401306.180 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:46.180Z,1730401306.180 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:46.195Z,1730401306.195 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-10-31T19:01:46.196Z,1730401306.196 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:46.196Z,1730401306.196 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4191
2024-10-31T19:01:46.588Z,1730401306.588 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:46.588Z,1730401306.588 [WetLabsBB2FL](INFO): Powering down
2024-10-31T19:01:46.589Z,1730401306.589 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:46.595Z,1730401306.595 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-10-31T19:01:46.596Z,1730401306.596 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:46.596Z,1730401306.596 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4192
2024-10-31T19:01:47.356Z,1730401307.356 [CTD_Seabird](INFO): Powering down
2024-10-31T19:01:47.368Z,1730401307.368 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:47.368Z,1730401307.368 [CTD_Seabird](INFO): Powering down
2024-10-31T19:01:47.379Z,1730401307.379 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:47.396Z,1730401307.396 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-10-31T19:01:47.396Z,1730401307.396 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:47.396Z,1730401307.396 [Radio_Surface](INFO): Join timeout helper Thread ID is 4193
2024-10-31T19:01:47.696Z,1730401307.696 [Radio_Surface](INFO): Powering down
2024-10-31T19:01:47.696Z,1730401307.696 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:47.697Z,1730401307.697 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:47.699Z,1730401307.699 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-10-31T19:01:47.700Z,1730401307.700 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:47.700Z,1730401307.700 [Onboard](INFO): Join timeout helper Thread ID is 4194
2024-10-31T19:01:48.368Z,1730401308.368 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-10-31T19:01:50.702Z,1730401310.702 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-31T19:01:52.188Z,1730401312.188 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:52.188Z,1730401312.188 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.192Z,1730401312.192 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-10-31T19:01:52.192Z,1730401312.192 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.193Z,1730401312.193 [DataOverHttps](INFO): Join timeout helper Thread ID is 4195
2024-10-31T19:01:52.384Z,1730401312.384 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:52.384Z,1730401312.384 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.396Z,1730401312.396 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-10-31T19:01:52.396Z,1730401312.396 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.397Z,1730401312.397 [logger](INFO): Join timeout helper Thread ID is 4196
2024-10-31T19:01:52.404Z,1730401312.404 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:52.404Z,1730401312.404 [logger ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.416Z,1730401312.416 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-10-31T19:01:52.416Z,1730401312.416 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.417Z,1730401312.417 [CommandLine](INFO): Join timeout helper Thread ID is 4197
2024-10-31T19:01:52.428Z,1730401312.428 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:52.428Z,1730401312.428 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.436Z,1730401312.436 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-10-31T19:01:52.436Z,1730401312.436 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.437Z,1730401312.437 [CommandExec](INFO): Join timeout helper Thread ID is 4198
2024-10-31T19:01:52.438Z,1730401312.438 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-10-31T19:01:52.438Z,1730401312.438 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:52.438Z,1730401312.438 [controlThread](INFO): Join timeout helper Thread ID is 4199
2024-10-31T19:01:52.708Z,1730401312.708 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-10-31T19:01:52.708Z,1730401312.708 [controlThread](DEBUG): Uninitializing ControlThread
2024-10-31T19:01:52.708Z,1730401312.708 [AHRS_M2](INFO): Powering down
2024-10-31T19:01:52.781Z,1730401312.781 [NAL9602](INFO): Powering down
2024-10-31T19:01:52.782Z,1730401312.782 [Sonardyne_Nano](INFO): Powering down
2024-10-31T19:01:52.901Z,1730401312.901 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-10-31T19:01:52.902Z,1730401312.902 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-10-31T19:01:52.902Z,1730401312.902 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-10-31T19:01:52.903Z,1730401312.903 [MissionManager](INFO): Uninitializing Mission Default
2024-10-31T19:01:52.903Z,1730401312.903 [Default] Stopped
2024-10-31T19:01:52.903Z,1730401312.903 [Default](DEBUG): Aggregate::uninitialize Default
2024-10-31T19:01:52.903Z,1730401312.903 [Default:B.GoToSurface] Stopped
2024-10-31T19:01:52.903Z,1730401312.903 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-31T19:01:52.903Z,1730401312.903 [Default:CheckIn] Stopped
2024-10-31T19:01:52.903Z,1730401312.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-31T19:01:52.903Z,1730401312.903 [Default:CheckIn:Read_GPS] Stopped
2024-10-31T19:01:52.906Z,1730401312.906 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-10-31T19:01:52.906Z,1730401312.906 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-10-31T19:01:52.907Z,1730401312.907 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-10-31T19:01:52.907Z,1730401312.907 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-10-31T19:01:52.907Z,1730401312.907 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-10-31T19:01:52.907Z,1730401312.907 [BuoyancyServo](INFO): Powering down
2024-10-31T19:01:52.920Z,1730401312.920 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-10-31T19:01:52.920Z,1730401312.920 [ElevatorServo](INFO): Powering down
2024-10-31T19:01:52.920Z,1730401312.920 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-10-31T19:01:52.921Z,1730401312.921 [MassServo](INFO): Powering down
2024-10-31T19:01:52.921Z,1730401312.921 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-10-31T19:01:52.921Z,1730401312.921 [RudderServo](INFO): Powering down
2024-10-31T19:01:52.922Z,1730401312.922 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-10-31T19:01:52.922Z,1730401312.922 [ThrusterHE](INFO): Powering down
2024-10-31T19:01:52.923Z,1730401312.923 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-10-31T19:01:52.924Z,1730401312.924 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-10-31T19:01:52.924Z,1730401312.924 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-10-31T19:01:52.924Z,1730401312.924 [CBIT](DEBUG): Powering off loads.
2024-10-31T19:01:52.935Z,1730401312.935 [CBIT](DEBUG): Disabling WDT.
2024-10-31T19:01:52.947Z,1730401312.947 [CBIT](DEBUG): Opening all GF detection circuits.
2024-10-31T19:01:52.948Z,1730401312.948 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.001Z,1730401313.001 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.004Z,1730401313.004 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.010Z,1730401313.010 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.082Z,1730401313.082 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.087Z,1730401313.087 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.128Z,1730401313.128 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-10-31T19:01:53.236Z,1730401313.236 [logger ThreadHandler](INFO): Thread cancelled.