2023-04-13T17:35:42.556Z,1681407342.556 [CommandExec](IMPORTANT): got command restart logs
2023-04-13T17:35:47.856Z,1681407347.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:36:22.996Z,1681407382.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:36:58.155Z,1681407418.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:37:31.711Z,1681407451.711 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002877
2023-04-13T17:37:47.063Z,1681407467.063 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230412T120931/Courier0120.lzma
2023-04-13T17:37:50.073Z,1681407470.073 [DataOverHttps](INFO): Moved sent file to Logs/20230412T120931/Courier0120.lzma.bak
2023-04-13T17:37:50.074Z,1681407470.074 [DataOverHttps](INFO): SBD MOMSN=18218791
2023-04-13T17:38:05.196Z,1681407485.196 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T17:38:21.729Z,1681407501.729 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20230412T120931/Express0118.lzma
2023-04-13T17:38:22.729Z,1681407502.729 [DataOverHttps](INFO): Moved sent file to Logs/20230412T120931/Express0118.lzma.bak
2023-04-13T17:38:22.730Z,1681407502.730 [DataOverHttps](INFO): SBD MOMSN=18218799
2023-04-13T17:38:38.375Z,1681407518.375 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20230412T120931/Express0121.lzma
2023-04-13T17:38:39.377Z,1681407519.377 [DataOverHttps](INFO): Moved sent file to Logs/20230412T120931/Express0121.lzma.bak
2023-04-13T17:38:39.378Z,1681407519.378 [DataOverHttps](INFO): SBD MOMSN=18218805
2023-04-13T17:38:41.182Z,1681407521.182 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T17:38:41.182Z,1681407521.182 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T17:38:41.182Z,1681407521.182 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T17:43:41.593Z,1681407821.593 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T17:43:41.593Z,1681407821.593 [Default:CheckIn:C.Wait] Stopped
2023-04-13T17:43:41.593Z,1681407821.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T17:43:41.593Z,1681407821.593 [Default:CheckIn:D] Running Loop=1
2023-04-13T17:43:42.006Z,1681407822.006 [Default:CheckIn:D] Stopped
2023-04-13T17:43:42.007Z,1681407822.007 [Default:CheckIn:E] Running Loop=1
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.723342 min 
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn:E] Stopped
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn] Stopped
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn](INFO): Running loop #4
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn] Running Loop=4
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T17:43:42.427Z,1681407822.427 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T17:45:06.323Z,1681407906.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:45:10.339Z,1681407910.339 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.238796
2023-04-13T17:45:41.471Z,1681407941.471 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:46:16.611Z,1681407976.611 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:46:51.783Z,1681408011.783 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:47:26.923Z,1681408046.923 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:48:02.095Z,1681408082.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:48:37.239Z,1681408117.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:48:42.581Z,1681408122.581 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-13T17:43:42.4Z
2023-04-13T17:48:42.581Z,1681408122.581 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T17:48:42.581Z,1681408122.581 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T17:48:46.208Z,1681408126.208 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-13T17:49:12.379Z,1681408152.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:49:47.523Z,1681408187.523 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:49:54.129Z,1681408194.129 [NAL9602](INFO): SBD MO Status=2, MOMSN=11424, MT Status=2, MTMSN=0
2023-04-13T17:49:54.130Z,1681408194.130 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-13T17:50:22.664Z,1681408222.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:50:57.803Z,1681408257.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:51:32.943Z,1681408292.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:52:08.095Z,1681408328.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:52:43.077Z,1681408363.077 [NAL9602](INFO): SBD MO Status=2, MOMSN=11424, MT Status=2, MTMSN=0
2023-04-13T17:52:43.077Z,1681408363.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-13T17:52:43.235Z,1681408363.235 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:53:18.375Z,1681408398.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:53:53.515Z,1681408433.515 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:54:28.671Z,1681408468.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:55:03.811Z,1681408503.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:55:38.951Z,1681408538.951 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:56:14.091Z,1681408574.091 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:56:49.231Z,1681408609.231 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:57:24.371Z,1681408644.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:57:48.138Z,1681408668.138 [NAL9602](INFO): SBD MO Status=1, MOMSN=11424, MT Status=0, MTMSN=0
2023-04-13T17:57:48.191Z,1681408668.191 [NAL9602](INFO): Sent 150 bytes from file Logs/20230413T173542/Courier0000.lzma
2023-04-13T17:57:48.191Z,1681408668.191 [NAL9602](INFO): Packets left to send: 0
2023-04-13T17:57:59.511Z,1681408679.511 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:58:01.194Z,1681408681.194 [NAL9602](INFO): SBD MO Status=1, MOMSN=11425, MT Status=0, MTMSN=0
2023-04-13T17:58:01.243Z,1681408681.243 [NAL9602](INFO): Sent 332 bytes from file Logs/20230413T173542/Express0001.lzma
2023-04-13T17:58:01.244Z,1681408681.244 [NAL9602](INFO): Packets left to send: 1
2023-04-13T17:58:08.149Z,1681408688.149 [NAL9602](INFO): SBD MO Status=1, MOMSN=11426, MT Status=0, MTMSN=0
2023-04-13T17:58:08.207Z,1681408688.207 [NAL9602](INFO): Sent 47 bytes from file Logs/20230413T173542/Express0001.lzma
2023-04-13T17:58:08.207Z,1681408688.207 [NAL9602](INFO): Packets left to send: 0
2023-04-13T17:58:18.653Z,1681408698.653 [NAL9602](INFO): SBD MO Status=0, MOMSN=11427, MT Status=0, MTMSN=0
2023-04-13T17:58:18.741Z,1681408698.741 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T17:58:18.741Z,1681408698.741 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T17:58:18.742Z,1681408698.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T17:58:34.663Z,1681408714.663 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:58:49.360Z,1681408729.360 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T17:59:09.803Z,1681408749.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T17:59:44.951Z,1681408784.951 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:00:20.091Z,1681408820.091 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:00:55.231Z,1681408855.231 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:01:30.371Z,1681408890.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:02:05.519Z,1681408925.519 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:02:40.659Z,1681408960.659 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:03:15.803Z,1681408995.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:03:19.238Z,1681408999.238 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T18:03:19.238Z,1681408999.238 [Default:CheckIn:C.Wait] Stopped
2023-04-13T18:03:19.238Z,1681408999.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T18:03:19.239Z,1681408999.239 [Default:CheckIn:D] Running Loop=1
2023-04-13T18:03:19.649Z,1681408999.649 [Default:CheckIn:D] Stopped
2023-04-13T18:03:19.649Z,1681408999.649 [Default:CheckIn:E] Running Loop=1
2023-04-13T18:03:20.051Z,1681409000.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.350716 min 
2023-04-13T18:03:20.051Z,1681409000.051 [Default:CheckIn:E] Stopped
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn] Stopped
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn](INFO): Running loop #5
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn] Running Loop=5
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T18:03:20.052Z,1681409000.052 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T18:03:21.656Z,1681409001.656 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-13T18:03:21.656Z,1681409001.656 [NAL9602] Data Fault, FailCount= 1
2023-04-13T18:03:21.656Z,1681409001.656 [NAL9602](ERROR): Data Fault
2023-04-13T18:03:21.678Z,1681409001.678 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-13T18:03:22.056Z,1681409002.056 [NAL9602](INFO): Powering down
2023-04-13T18:03:22.889Z,1681409002.889 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-13T18:03:22.889Z,1681409002.889 [NAL9602] No Fault, FailCount= 1
2023-04-13T18:03:50.959Z,1681409030.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:03:52.364Z,1681409032.364 [NAL9602](INFO): Powering up NAL9602
2023-04-13T18:04:03.268Z,1681409043.268 [NAL9602](INFO): NAL9602 initialized
2023-04-13T18:04:20.237Z,1681409060.237 [NAL9602](INFO): SBD MO Status=0, MOMSN=11428, MT Status=0, MTMSN=0
2023-04-13T18:04:20.237Z,1681409060.237 [NAL9602](INFO): No messages in MT queue
2023-04-13T18:04:26.099Z,1681409066.099 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:04:32.766Z,1681409072.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180431.00,A,3648.14946,N,12147.21861,W,0.078,0.00,130423,,,A*71
2023-04-13T18:04:32.768Z,1681409072.768 [NAL9602](INFO): GPS fix at 20230413T180431: (36.802491, -121.786977)
2023-04-13T18:04:32.780Z,1681409072.780 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T18:04:32.780Z,1681409072.780 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T18:04:53.837Z,1681409093.837 [NAL9602](INFO): SBD MO Status=1, MOMSN=11429, MT Status=0, MTMSN=0
2023-04-13T18:04:53.887Z,1681409093.887 [NAL9602](INFO): Sent 223 bytes from file Logs/20230413T173542/Courier0003.lzma
2023-04-13T18:04:53.887Z,1681409093.887 [NAL9602](INFO): Packets left to send: 0
2023-04-13T18:05:01.239Z,1681409101.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:05:03.961Z,1681409103.961 [NAL9602](INFO): SBD MO Status=1, MOMSN=11430, MT Status=0, MTMSN=0
2023-04-13T18:05:04.015Z,1681409104.015 [NAL9602](INFO): Sent 258 bytes from file Logs/20230413T173542/Express0004.lzma
2023-04-13T18:05:04.015Z,1681409104.015 [NAL9602](INFO): Packets left to send: 0
2023-04-13T18:05:11.637Z,1681409111.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=11431, MT Status=0, MTMSN=0
2023-04-13T18:05:11.725Z,1681409111.725 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T18:05:11.725Z,1681409111.725 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T18:05:11.725Z,1681409111.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T18:05:36.379Z,1681409136.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:05:42.340Z,1681409142.340 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T18:06:11.519Z,1681409171.519 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:06:46.691Z,1681409206.691 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:07:21.835Z,1681409241.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:07:56.983Z,1681409276.983 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:08:32.123Z,1681409312.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:09:07.265Z,1681409347.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:09:42.403Z,1681409382.403 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:10:12.222Z,1681409412.222 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T18:10:12.222Z,1681409412.222 [Default:CheckIn:C.Wait] Stopped
2023-04-13T18:10:12.222Z,1681409412.222 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T18:10:12.222Z,1681409412.222 [Default:CheckIn:D] Running Loop=1
2023-04-13T18:10:12.631Z,1681409412.631 [Default:CheckIn:D] Stopped
2023-04-13T18:10:12.631Z,1681409412.631 [Default:CheckIn:E] Running Loop=1
2023-04-13T18:10:13.039Z,1681409413.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.233748 min 
2023-04-13T18:10:13.039Z,1681409413.039 [Default:CheckIn:E] Stopped
2023-04-13T18:10:13.039Z,1681409413.039 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T18:10:13.039Z,1681409413.039 [Default:CheckIn] Stopped
2023-04-13T18:10:13.039Z,1681409413.039 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T18:10:13.040Z,1681409413.040 [Default:CheckIn](INFO): Running loop #6
2023-04-13T18:10:13.040Z,1681409413.040 [Default:CheckIn] Running Loop=6
2023-04-13T18:10:13.040Z,1681409413.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T18:10:13.040Z,1681409413.040 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T18:10:15.050Z,1681409415.050 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181013.00,A,3648.15028,N,12147.22874,W,0.680,0.00,130423,,,D*77
2023-04-13T18:10:15.052Z,1681409415.052 [NAL9602](INFO): GPS fix at 20230413T181013: (36.802505, -121.787146)
2023-04-13T18:10:15.065Z,1681409415.065 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T18:10:15.065Z,1681409415.065 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T18:10:17.543Z,1681409417.543 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:10:52.695Z,1681409452.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:11:27.859Z,1681409487.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:12:02.999Z,1681409522.999 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:12:38.155Z,1681409558.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:13:13.295Z,1681409593.295 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:13:48.443Z,1681409628.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:14:23.591Z,1681409663.591 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:14:58.731Z,1681409698.731 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:15:33.871Z,1681409733.871 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:15:56.872Z,1681409756.872 [NAL9602](INFO): SBD MO Status=2, MOMSN=11432, MT Status=2, MTMSN=0
2023-04-13T18:15:56.872Z,1681409756.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-13T18:16:09.023Z,1681409769.023 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:16:15.917Z,1681409775.917 [NAL9602](INFO): SBD MO Status=1, MOMSN=11432, MT Status=0, MTMSN=0
2023-04-13T18:16:15.971Z,1681409775.971 [NAL9602](INFO): Sent 73 bytes from file Logs/20230413T173542/Courier0006.lzma
2023-04-13T18:16:15.971Z,1681409775.971 [NAL9602](INFO): Packets left to send: 0
2023-04-13T18:16:30.541Z,1681409790.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=11433, MT Status=2, MTMSN=0
2023-04-13T18:16:30.541Z,1681409790.541 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-13T18:16:44.163Z,1681409804.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:16:51.997Z,1681409811.997 [NAL9602](INFO): SBD MO Status=2, MOMSN=11433, MT Status=2, MTMSN=0
2023-04-13T18:16:51.997Z,1681409811.997 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-13T18:17:19.311Z,1681409839.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:17:54.451Z,1681409874.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:18:29.603Z,1681409909.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:19:04.751Z,1681409944.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:19:39.004Z,1681409979.004 [NAL9602](INFO): SBD MO Status=2, MOMSN=11433, MT Status=2, MTMSN=0
2023-04-13T18:19:39.004Z,1681409979.004 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-13T18:19:39.891Z,1681409979.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:20:15.031Z,1681410015.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:20:50.175Z,1681410050.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:21:25.327Z,1681410085.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:21:38.103Z,1681410098.103 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002862
2023-04-13T18:21:45.434Z,1681410105.434 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230413T173542/Express0007.lzma
2023-04-13T18:21:46.436Z,1681410106.436 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Express0007.lzma.bak
2023-04-13T18:21:46.436Z,1681410106.436 [DataOverHttps](INFO): SBD MOMSN=18219006
2023-04-13T18:21:47.783Z,1681410107.783 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T18:21:47.783Z,1681410107.783 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T18:21:47.783Z,1681410107.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T18:22:09.171Z,1681410129.171 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T18:25:50.007Z,1681410350.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:25:52.015Z,1681410352.015 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239338
2023-04-13T18:26:25.171Z,1681410385.171 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:26:48.360Z,1681410408.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T18:26:48.360Z,1681410408.360 [Default:CheckIn:C.Wait] Stopped
2023-04-13T18:26:48.360Z,1681410408.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T18:26:48.361Z,1681410408.361 [Default:CheckIn:D] Running Loop=1
2023-04-13T18:26:48.755Z,1681410408.755 [Default:CheckIn:D] Stopped
2023-04-13T18:26:48.755Z,1681410408.755 [Default:CheckIn:E] Running Loop=1
2023-04-13T18:26:49.159Z,1681410409.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.835807 min 
2023-04-13T18:26:49.159Z,1681410409.159 [Default:CheckIn:E] Stopped
2023-04-13T18:26:49.159Z,1681410409.159 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T18:26:49.160Z,1681410409.160 [Default:CheckIn] Stopped
2023-04-13T18:26:49.160Z,1681410409.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T18:26:49.160Z,1681410409.160 [Default:CheckIn](INFO): Running loop #7
2023-04-13T18:26:49.160Z,1681410409.160 [Default:CheckIn] Running Loop=7
2023-04-13T18:26:49.160Z,1681410409.160 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T18:26:49.160Z,1681410409.160 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T18:26:51.198Z,1681410411.198 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182650.00,A,3648.16867,N,12147.28082,W,0.486,231.46,130423,,,D*78
2023-04-13T18:26:51.200Z,1681410411.200 [NAL9602](INFO): GPS fix at 20230413T182650: (36.802811, -121.788014)
2023-04-13T18:26:51.237Z,1681410411.237 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T18:26:51.237Z,1681410411.237 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T18:27:35.743Z,1681410455.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:28:10.883Z,1681410490.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:28:46.035Z,1681410526.035 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:29:21.183Z,1681410561.183 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:29:56.331Z,1681410596.331 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:30:31.471Z,1681410631.471 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:31:06.611Z,1681410666.611 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:31:41.751Z,1681410701.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:32:16.891Z,1681410736.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:32:52.031Z,1681410772.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:32:55.632Z,1681410775.632 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-04-13T18:33:09.312Z,1681410789.312 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-04-13T18:33:09.315Z,1681410789.315 [BPC1](INFO): Received data from all battery sticks.
2023-04-13T18:33:27.175Z,1681410807.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:34:02.379Z,1681410842.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:34:37.523Z,1681410877.523 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:35:12.663Z,1681410912.663 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:35:47.819Z,1681410947.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:36:22.983Z,1681410982.983 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:36:58.143Z,1681411018.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:37:05.855Z,1681411025.855 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-04-13T18:37:05.855Z,1681411025.855 [DropWeight] Hardware Fault, FailCount= 1
2023-04-13T18:37:05.855Z,1681411025.855 [DropWeight](ERROR): Hardware Fault
2023-04-13T18:37:05.874Z,1681411025.874 [CommandExec](FAULT): Scheduling is paused
2023-04-13T18:37:05.875Z,1681411025.875 [CBIT](INFO): Critical error at 20230413T183705
2023-04-13T18:37:05.878Z,1681411025.878 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-04-13T18:37:05.878Z,1681411025.878 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-04-13T18:37:06.278Z,1681411026.278 [CBIT](INFO): Critical error at 20230413T183705
2023-04-13T18:37:33.291Z,1681411053.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:38:08.443Z,1681411088.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:38:43.583Z,1681411123.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:39:18.723Z,1681411158.723 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:39:53.879Z,1681411193.879 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:40:29.019Z,1681411229.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:41:04.167Z,1681411264.167 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:41:39.323Z,1681411299.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:42:14.463Z,1681411334.463 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:42:34.859Z,1681411354.859 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003276
2023-04-13T18:42:42.258Z,1681411362.258 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230413T173542/Courier0009.lzma
2023-04-13T18:42:43.260Z,1681411363.260 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Courier0009.lzma.bak
2023-04-13T18:42:43.260Z,1681411363.260 [DataOverHttps](INFO): SBD MOMSN=18219147
2023-04-13T18:43:03.490Z,1681411383.490 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230413T173542/Express0010.lzma
2023-04-13T18:43:04.492Z,1681411384.492 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Express0010.lzma.bak
2023-04-13T18:43:04.492Z,1681411384.492 [DataOverHttps](INFO): SBD MOMSN=18219150
2023-04-13T18:43:05.851Z,1681411385.851 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T18:43:05.865Z,1681411385.865 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T18:43:05.865Z,1681411385.865 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T18:43:05.865Z,1681411385.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T18:43:36.635Z,1681411416.635 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-13T18:44:07.030Z,1681411447.030 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=62.538361
2023-04-13T18:44:29.323Z,1681411469.323 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003434
2023-04-13T18:48:06.491Z,1681411686.491 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T18:48:06.492Z,1681411686.492 [Default:CheckIn:C.Wait] Stopped
2023-04-13T18:48:06.492Z,1681411686.492 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T18:48:06.492Z,1681411686.492 [Default:CheckIn:D] Running Loop=1
2023-04-13T18:48:06.876Z,1681411686.876 [Default:CheckIn:D] Stopped
2023-04-13T18:48:06.876Z,1681411686.876 [Default:CheckIn:E] Running Loop=1
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.137826 min 
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn:E] Stopped
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn] Stopped
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn](INFO): Running loop #8
2023-04-13T18:48:07.280Z,1681411687.280 [Default:CheckIn] Running Loop=8
2023-04-13T18:48:07.281Z,1681411687.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T18:48:07.281Z,1681411687.281 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T18:52:06.112Z,1681411926.112 [CBIT](INFO): Clearing failed state for component DropWeight
2023-04-13T18:52:06.112Z,1681411926.112 [DropWeight] No Fault, FailCount= 1
2023-04-13T18:53:07.462Z,1681411987.462 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-13T18:48:07.3Z
2023-04-13T18:53:07.462Z,1681411987.462 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T18:53:07.467Z,1681411987.467 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T18:53:11.584Z,1681411991.584 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-13T18:53:19.207Z,1681411999.207 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20230413T173542/Courier0012.lzma
2023-04-13T18:53:20.208Z,1681412000.208 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Courier0012.lzma.bak
2023-04-13T18:53:20.208Z,1681412000.208 [DataOverHttps](INFO): SBD MOMSN=18219167
2023-04-13T18:53:42.288Z,1681412022.288 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T18:53:43.842Z,1681412023.842 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20230413T173542/Express0013.lzma
2023-04-13T18:53:44.837Z,1681412024.837 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Express0013.lzma.bak
2023-04-13T18:53:44.837Z,1681412024.837 [DataOverHttps](INFO): SBD MOMSN=18219171
2023-04-13T18:53:46.779Z,1681412026.779 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T18:53:46.780Z,1681412026.780 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T18:53:46.780Z,1681412026.780 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T18:58:47.385Z,1681412327.385 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T18:58:47.386Z,1681412327.386 [Default:CheckIn:C.Wait] Stopped
2023-04-13T18:58:47.386Z,1681412327.386 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T18:58:47.386Z,1681412327.386 [Default:CheckIn:D] Running Loop=1
2023-04-13T18:58:47.749Z,1681412327.749 [Default:CheckIn:D] Stopped
2023-04-13T18:58:47.749Z,1681412327.749 [Default:CheckIn:E] Running Loop=1
2023-04-13T18:58:48.184Z,1681412328.184 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.819051 min 
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn:E] Stopped
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn] Stopped
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn](INFO): Running loop #9
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn] Running Loop=9
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T18:58:48.185Z,1681412328.185 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T18:58:49.744Z,1681412329.744 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-13T18:58:49.744Z,1681412329.744 [NAL9602] Data Fault, FailCount= 1
2023-04-13T18:58:49.744Z,1681412329.744 [NAL9602](ERROR): Data Fault
2023-04-13T18:58:49.765Z,1681412329.765 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-13T18:58:50.160Z,1681412330.160 [NAL9602](INFO): Powering down
2023-04-13T18:58:51.067Z,1681412331.067 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-13T18:58:51.067Z,1681412331.067 [NAL9602] No Fault, FailCount= 1
2023-04-13T18:59:20.448Z,1681412360.448 [NAL9602](INFO): Powering up NAL9602
2023-04-13T18:59:31.368Z,1681412371.368 [NAL9602](INFO): NAL9602 initialized
2023-04-13T19:03:48.342Z,1681412628.342 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-13T18:58:48.2Z
2023-04-13T19:03:48.342Z,1681412628.342 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T19:03:48.348Z,1681412628.348 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T19:04:00.070Z,1681412640.070 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230413T173542/Courier0015.lzma
2023-04-13T19:04:01.073Z,1681412641.073 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Courier0015.lzma.bak
2023-04-13T19:04:01.073Z,1681412641.073 [DataOverHttps](INFO): SBD MOMSN=18219230
2023-04-13T19:04:25.510Z,1681412665.510 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230413T173542/Express0016.lzma
2023-04-13T19:04:26.513Z,1681412666.513 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Express0016.lzma.bak
2023-04-13T19:04:26.513Z,1681412666.513 [DataOverHttps](INFO): SBD MOMSN=18219233
2023-04-13T19:04:28.446Z,1681412668.446 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T19:04:28.446Z,1681412668.446 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T19:04:28.446Z,1681412668.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T19:04:34.872Z,1681412674.872 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-13T19:05:05.575Z,1681412705.575 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T19:09:29.018Z,1681412969.018 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T19:09:29.018Z,1681412969.018 [Default:CheckIn:C.Wait] Stopped
2023-04-13T19:09:29.018Z,1681412969.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T19:09:29.023Z,1681412969.023 [Default:CheckIn:D] Running Loop=1
2023-04-13T19:09:29.436Z,1681412969.436 [Default:CheckIn:D] Stopped
2023-04-13T19:09:29.436Z,1681412969.436 [Default:CheckIn:E] Running Loop=1
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.513835 min 
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn:E] Stopped
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn] Stopped
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn](INFO): Running loop #10
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn] Running Loop=10
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T19:09:29.803Z,1681412969.803 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T19:10:02.516Z,1681413002.516 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-13T19:10:02.591Z,1681413002.591 [NAL9602](ERROR): received: 
+CSQ:0

OK433, 2, 0, 0, 0

OK
2023-04-13T19:14:30.015Z,1681413270.015 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-13T19:09:29.8Z
2023-04-13T19:14:30.015Z,1681413270.015 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T19:14:30.016Z,1681413270.016 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T19:14:33.724Z,1681413273.724 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-13T19:14:40.786Z,1681413280.786 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230413T173542/Courier0018.lzma
2023-04-13T19:14:41.780Z,1681413281.780 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Courier0018.lzma.bak
2023-04-13T19:14:41.781Z,1681413281.781 [DataOverHttps](INFO): SBD MOMSN=18219263
2023-04-13T19:15:04.435Z,1681413304.435 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T19:15:05.815Z,1681413305.815 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230413T173542/Express0019.lzma
2023-04-13T19:15:06.817Z,1681413306.817 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Express0019.lzma.bak
2023-04-13T19:15:06.817Z,1681413306.817 [DataOverHttps](INFO): SBD MOMSN=18219265
2023-04-13T19:15:08.509Z,1681413308.509 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T19:15:08.509Z,1681413308.509 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T19:15:08.509Z,1681413308.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T19:20:09.117Z,1681413609.117 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T19:20:09.117Z,1681413609.117 [Default:CheckIn:C.Wait] Stopped
2023-04-13T19:20:09.117Z,1681413609.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T19:20:09.118Z,1681413609.118 [Default:CheckIn:D] Running Loop=1
2023-04-13T19:20:09.530Z,1681413609.530 [Default:CheckIn:D] Stopped
2023-04-13T19:20:09.530Z,1681413609.530 [Default:CheckIn:E] Running Loop=1
2023-04-13T19:20:09.914Z,1681413609.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.182064 min 
2023-04-13T19:20:09.914Z,1681413609.914 [Default:CheckIn:E] Stopped
2023-04-13T19:20:09.914Z,1681413609.914 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T19:20:09.914Z,1681413609.914 [Default:CheckIn] Stopped
2023-04-13T19:20:09.914Z,1681413609.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T19:20:09.915Z,1681413609.915 [Default:CheckIn](INFO): Running loop #11
2023-04-13T19:20:09.915Z,1681413609.915 [Default:CheckIn] Running Loop=11
2023-04-13T19:20:09.915Z,1681413609.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T19:20:09.915Z,1681413609.915 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T19:20:11.508Z,1681413611.508 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-13T19:20:11.508Z,1681413611.508 [NAL9602] Data Fault, FailCount= 2
2023-04-13T19:20:11.508Z,1681413611.508 [NAL9602](ERROR): Data Fault
2023-04-13T19:20:11.595Z,1681413611.595 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-13T19:20:11.908Z,1681413611.908 [NAL9602](INFO): Powering down
2023-04-13T19:20:12.788Z,1681413612.788 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-13T19:20:12.788Z,1681413612.788 [NAL9602] No Fault, FailCount= 2
2023-04-13T19:20:42.208Z,1681413642.208 [NAL9602](INFO): Powering up NAL9602
2023-04-13T19:20:53.116Z,1681413653.116 [NAL9602](INFO): NAL9602 initialized
2023-04-13T19:22:07.455Z,1681413727.455 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-04-13T19:22:07.455Z,1681413727.455 [DropWeight] Hardware Fault, FailCount= 1
2023-04-13T19:22:07.455Z,1681413727.455 [DropWeight](ERROR): Hardware Fault
2023-04-13T19:22:07.539Z,1681413727.539 [CBIT](INFO): Critical error at 20230413T192207
2023-04-13T19:22:07.541Z,1681413727.541 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-04-13T19:22:07.542Z,1681413727.542 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-04-13T19:22:07.900Z,1681413727.900 [CBIT](INFO): Critical error at 20230413T192207
2023-04-13T19:25:10.125Z,1681413910.125 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-13T19:20:09.9Z
2023-04-13T19:25:10.125Z,1681413910.125 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T19:25:10.126Z,1681413910.126 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-13T19:25:20.366Z,1681413920.366 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20230413T173542/Courier0021.lzma
2023-04-13T19:25:21.368Z,1681413921.368 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Courier0021.lzma.bak
2023-04-13T19:25:21.369Z,1681413921.369 [DataOverHttps](INFO): SBD MOMSN=18219291
2023-04-13T19:25:45.087Z,1681413945.087 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230413T173542/Express0022.lzma
2023-04-13T19:25:46.077Z,1681413946.077 [DataOverHttps](INFO): Moved sent file to Logs/20230413T173542/Express0022.lzma.bak
2023-04-13T19:25:46.078Z,1681413946.078 [DataOverHttps](INFO): SBD MOMSN=18219295
2023-04-13T19:25:48.356Z,1681413948.356 [Default:CheckIn:Read_Iridium] Stopped
2023-04-13T19:25:48.356Z,1681413948.356 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-13T19:25:48.356Z,1681413948.356 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-13T19:25:56.390Z,1681413956.390 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-13T19:26:27.079Z,1681413987.079 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-13T19:30:46.791Z,1681414246.791 [CommandExec](IMPORTANT): got command quit
2023-04-13T19:30:47.799Z,1681414247.799 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:47.799Z,1681414247.799 [CommandExec](INFO): Uninitializing the command executive.
2023-04-13T19:30:47.799Z,1681414247.799 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-13T19:30:47.799Z,1681414247.799 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:47.894Z,1681414247.894 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-13T19:30:47.895Z,1681414247.895 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-13T19:30:47.895Z,1681414247.895 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:47.895Z,1681414247.895 [NavChartDb](INFO): Join timeout helper Thread ID is 9814
2023-04-13T19:30:47.939Z,1681414247.939 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:47.939Z,1681414247.939 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:47.951Z,1681414247.951 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-13T19:30:47.953Z,1681414247.953 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:47.967Z,1681414247.967 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9815
2023-04-13T19:30:48.151Z,1681414248.151 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:48.151Z,1681414248.151 [WetLabsBB2FL](INFO): Powering down
2023-04-13T19:30:48.152Z,1681414248.152 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:48.159Z,1681414248.159 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-13T19:30:48.159Z,1681414248.159 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:48.159Z,1681414248.159 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9816
2023-04-13T19:30:48.659Z,1681414248.659 [CTD_Seabird](INFO): Powering down
2023-04-13T19:30:48.671Z,1681414248.671 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:48.671Z,1681414248.671 [CTD_Seabird](INFO): Powering down
2023-04-13T19:30:48.683Z,1681414248.683 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:48.687Z,1681414248.687 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-13T19:30:48.687Z,1681414248.687 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:48.687Z,1681414248.687 [Radio_Surface](INFO): Join timeout helper Thread ID is 9817
2023-04-13T19:30:48.755Z,1681414248.755 [Radio_Surface](INFO): Powering down
2023-04-13T19:30:48.755Z,1681414248.755 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:48.756Z,1681414248.756 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:48.763Z,1681414248.763 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-13T19:30:48.763Z,1681414248.763 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:48.763Z,1681414248.763 [Onboard](INFO): Join timeout helper Thread ID is 9818
2023-04-13T19:30:48.989Z,1681414248.989 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-13T19:30:48.989Z,1681414248.989 [Default:CheckIn:C.Wait] Stopped
2023-04-13T19:30:48.990Z,1681414248.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-13T19:30:48.990Z,1681414248.990 [Default:CheckIn:D] Running Loop=1
2023-04-13T19:30:49.300Z,1681414249.300 [Default:CheckIn:D] Stopped
2023-04-13T19:30:49.300Z,1681414249.300 [Default:CheckIn:E] Running Loop=1
2023-04-13T19:30:49.726Z,1681414249.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.844897 min 
2023-04-13T19:30:49.726Z,1681414249.726 [Default:CheckIn:E] Stopped
2023-04-13T19:30:49.726Z,1681414249.726 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-13T19:30:49.734Z,1681414249.734 [Default:CheckIn] Stopped
2023-04-13T19:30:49.734Z,1681414249.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T19:30:49.735Z,1681414249.735 [Default:CheckIn](INFO): Running loop #12
2023-04-13T19:30:49.735Z,1681414249.735 [Default:CheckIn] Running Loop=12
2023-04-13T19:30:49.735Z,1681414249.735 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-13T19:30:49.735Z,1681414249.735 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-13T19:30:52.911Z,1681414252.911 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:52.911Z,1681414252.911 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:52.916Z,1681414252.916 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-13T19:30:52.916Z,1681414252.916 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:52.916Z,1681414252.916 [DataOverHttps](INFO): Join timeout helper Thread ID is 9819
2023-04-13T19:30:53.560Z,1681414253.560 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:53.563Z,1681414253.563 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.575Z,1681414253.575 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-13T19:30:53.576Z,1681414253.576 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.576Z,1681414253.576 [BackseatComponent](INFO): Join timeout helper Thread ID is 9820
2023-04-13T19:30:53.671Z,1681414253.671 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:53.671Z,1681414253.671 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.691Z,1681414253.691 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-13T19:30:53.691Z,1681414253.691 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.692Z,1681414253.692 [logger](INFO): Join timeout helper Thread ID is 9821
2023-04-13T19:30:53.727Z,1681414253.727 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:53.727Z,1681414253.727 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.739Z,1681414253.739 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-13T19:30:53.740Z,1681414253.740 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.740Z,1681414253.740 [CommandLine](INFO): Join timeout helper Thread ID is 9822
2023-04-13T19:30:53.775Z,1681414253.775 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:53.775Z,1681414253.775 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.783Z,1681414253.783 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-13T19:30:53.783Z,1681414253.783 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.784Z,1681414253.784 [CommandExec](INFO): Join timeout helper Thread ID is 9823
2023-04-13T19:30:53.785Z,1681414253.785 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-13T19:30:53.785Z,1681414253.785 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:53.785Z,1681414253.785 [controlThread](INFO): Join timeout helper Thread ID is 9824
2023-04-13T19:30:54.107Z,1681414254.107 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-13T19:30:54.107Z,1681414254.107 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-13T19:30:54.107Z,1681414254.107 [AHRS_M2](INFO): Powering down
2023-04-13T19:30:54.179Z,1681414254.179 [NAL9602](INFO): Powering down
2023-04-13T19:30:54.181Z,1681414254.181 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-13T19:30:54.182Z,1681414254.182 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-13T19:30:54.183Z,1681414254.183 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-13T19:30:54.184Z,1681414254.184 [MissionManager](INFO): Uninitializing Mission Default
2023-04-13T19:30:54.184Z,1681414254.184 [Default] Stopped
2023-04-13T19:30:54.184Z,1681414254.184 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-13T19:30:54.184Z,1681414254.184 [Default:B.GoToSurface] Stopped
2023-04-13T19:30:54.184Z,1681414254.184 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-13T19:30:54.184Z,1681414254.184 [Default:CheckIn] Stopped
2023-04-13T19:30:54.184Z,1681414254.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-13T19:30:54.184Z,1681414254.184 [Default:CheckIn:Read_GPS] Stopped
2023-04-13T19:30:54.187Z,1681414254.187 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-13T19:30:54.187Z,1681414254.187 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-13T19:30:54.188Z,1681414254.188 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-13T19:30:54.188Z,1681414254.188 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-13T19:30:54.188Z,1681414254.188 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-13T19:30:54.188Z,1681414254.188 [BuoyancyServo](INFO): Powering down
2023-04-13T19:30:54.203Z,1681414254.203 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-13T19:30:54.203Z,1681414254.203 [ElevatorServo](INFO): Powering down
2023-04-13T19:30:54.204Z,1681414254.204 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-13T19:30:54.204Z,1681414254.204 [MassServo](INFO): Powering down
2023-04-13T19:30:54.205Z,1681414254.205 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-13T19:30:54.205Z,1681414254.205 [RudderServo](INFO): Powering down
2023-04-13T19:30:54.205Z,1681414254.205 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-13T19:30:54.206Z,1681414254.206 [ThrusterHE](INFO): Powering down
2023-04-13T19:30:54.207Z,1681414254.207 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-13T19:30:54.207Z,1681414254.207 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-13T19:30:54.207Z,1681414254.207 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-13T19:30:54.207Z,1681414254.207 [CBIT](DEBUG): Powering off loads.
2023-04-13T19:30:54.218Z,1681414254.218 [CBIT](DEBUG): Disabling WDT.
2023-04-13T19:30:54.230Z,1681414254.230 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-13T19:30:54.231Z,1681414254.231 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.262Z,1681414254.262 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.266Z,1681414254.266 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.272Z,1681414254.272 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.315Z,1681414254.315 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.371Z,1681414254.371 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.375Z,1681414254.375 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.425Z,1681414254.425 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-13T19:30:54.473Z,1681414254.473 [logger ThreadHandler](INFO): Thread cancelled.