2023-05-04T22:24:42.426Z,1683239082.426 [CommandExec](IMPORTANT): got command restart logs
2023-05-04T22:24:57.269Z,1683239097.269 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T22:28:54.570Z,1683239334.570 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243431
2023-05-04T22:29:27.163Z,1683239367.163 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T22:29:27.163Z,1683239367.163 [Default:CheckIn:C.Wait] Stopped
2023-05-04T22:29:27.163Z,1683239367.163 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T22:29:27.164Z,1683239367.164 [Default:CheckIn:D] Running Loop=1
2023-05-04T22:29:27.560Z,1683239367.560 [Default:CheckIn:D] Stopped
2023-05-04T22:29:27.560Z,1683239367.560 [Default:CheckIn:E] Running Loop=1
2023-05-04T22:29:27.960Z,1683239367.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.778701 min 
2023-05-04T22:29:27.960Z,1683239367.960 [Default:CheckIn:E] Stopped
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn] Stopped
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn](INFO): Running loop #2
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn] Running Loop=2
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T22:29:27.961Z,1683239367.961 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T22:29:29.977Z,1683239369.977 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222928.00,A,3647.29202,N,12150.30306,W,28.127,73.25,040523,,,D*79
2023-05-04T22:29:29.979Z,1683239369.979 [NAL9602](INFO): GPS fix at 20230504T222928: (36.788200, -121.838384)
2023-05-04T22:29:29.990Z,1683239369.990 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T22:29:29.990Z,1683239369.990 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T22:29:49.790Z,1683239389.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:30:24.929Z,1683239424.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:31:00.073Z,1683239460.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:31:00.159Z,1683239460.159 [NAL9602](INFO): SBD MO Status=2, MOMSN=25485, MT Status=2, MTMSN=0
2023-05-04T22:31:00.159Z,1683239460.159 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-04T22:31:35.213Z,1683239495.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:31:37.409Z,1683239497.409 [NAL9602](INFO): SBD MO Status=1, MOMSN=25485, MT Status=0, MTMSN=0
2023-05-04T22:31:37.458Z,1683239497.458 [NAL9602](INFO): Sent 39 bytes from file Logs/20230504T002720/Courier0106.lzma
2023-05-04T22:31:37.458Z,1683239497.458 [NAL9602](INFO): Packets left to send: 0
2023-05-04T22:31:53.214Z,1683239513.214 [NAL9602](INFO): SBD MO Status=2, MOMSN=25486, MT Status=2, MTMSN=0
2023-05-04T22:31:53.214Z,1683239513.214 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-04T22:32:10.353Z,1683239530.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:32:10.671Z,1683239530.671 [NAL9602](INFO): SBD MO Status=1, MOMSN=25486, MT Status=0, MTMSN=0
2023-05-04T22:32:10.729Z,1683239530.729 [NAL9602](INFO): Sent 267 bytes from file Logs/20230504T222442/Courier0000.lzma
2023-05-04T22:32:10.729Z,1683239530.729 [NAL9602](INFO): Packets left to send: 0
2023-05-04T22:32:14.424Z,1683239534.424 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2023-05-04T22:32:26.935Z,1683239546.935 [NAL9602](INFO): SBD MO Status=2, MOMSN=25487, MT Status=2, MTMSN=0
2023-05-04T22:32:26.936Z,1683239546.936 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-04T22:32:43.568Z,1683239563.568 [NAL9602](INFO): SBD MO Status=1, MOMSN=25487, MT Status=0, MTMSN=0
2023-05-04T22:32:43.618Z,1683239563.618 [NAL9602](INFO): Sent 103 bytes from file Logs/20230504T002720/Express0107.lzma
2023-05-04T22:32:43.619Z,1683239563.619 [NAL9602](INFO): Packets left to send: 0
2023-05-04T22:32:45.494Z,1683239565.494 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:32:53.779Z,1683239573.779 [NAL9602](INFO): SBD MO Status=1, MOMSN=25488, MT Status=0, MTMSN=0
2023-05-04T22:32:53.835Z,1683239573.835 [NAL9602](INFO): Sent 332 bytes from file Logs/20230504T222442/Express0001.lzma
2023-05-04T22:32:53.835Z,1683239573.835 [NAL9602](INFO): Packets left to send: 1
2023-05-04T22:33:04.371Z,1683239584.371 [NAL9602](INFO): SBD MO Status=1, MOMSN=25489, MT Status=0, MTMSN=0
2023-05-04T22:33:04.421Z,1683239584.421 [NAL9602](INFO): Sent 67 bytes from file Logs/20230504T222442/Express0001.lzma
2023-05-04T22:33:04.421Z,1683239584.421 [NAL9602](INFO): Packets left to send: 0
2023-05-04T22:33:14.875Z,1683239594.875 [NAL9602](INFO): SBD MO Status=0, MOMSN=25490, MT Status=0, MTMSN=0
2023-05-04T22:33:14.953Z,1683239594.953 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T22:33:14.953Z,1683239594.953 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T22:33:14.953Z,1683239594.953 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T22:33:20.649Z,1683239600.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:33:45.578Z,1683239625.578 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T22:33:55.797Z,1683239635.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:34:30.937Z,1683239670.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:35:06.077Z,1683239706.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:35:41.217Z,1683239741.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T22:36:03.102Z,1683239763.102 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003451
2023-05-04T22:38:15.477Z,1683239895.477 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T22:38:15.477Z,1683239895.477 [Default:CheckIn:C.Wait] Stopped
2023-05-04T22:38:15.477Z,1683239895.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T22:38:15.477Z,1683239895.477 [Default:CheckIn:D] Running Loop=1
2023-05-04T22:38:15.868Z,1683239895.868 [Default:CheckIn:D] Stopped
2023-05-04T22:38:15.868Z,1683239895.868 [Default:CheckIn:E] Running Loop=1
2023-05-04T22:38:16.299Z,1683239896.299 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.583842 min 
2023-05-04T22:38:16.299Z,1683239896.299 [Default:CheckIn:E] Stopped
2023-05-04T22:38:16.299Z,1683239896.299 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T22:38:16.299Z,1683239896.299 [Default:CheckIn] Stopped
2023-05-04T22:38:16.299Z,1683239896.299 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T22:38:16.300Z,1683239896.300 [Default:CheckIn](INFO): Running loop #3
2023-05-04T22:38:16.300Z,1683239896.300 [Default:CheckIn] Running Loop=3
2023-05-04T22:38:16.300Z,1683239896.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T22:38:16.300Z,1683239896.300 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T22:38:18.279Z,1683239898.279 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223817.00,A,3648.27019,N,12147.14093,W,5.190,192.88,040523,,,D*79
2023-05-04T22:38:18.281Z,1683239898.281 [NAL9602](INFO): GPS fix at 20230504T223817: (36.804503, -121.785682)
2023-05-04T22:38:18.328Z,1683239898.328 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T22:38:18.328Z,1683239898.328 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T22:38:25.928Z,1683239905.928 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230504T222442/Courier0003.lzma
2023-05-04T22:38:26.930Z,1683239906.930 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0003.lzma.bak
2023-05-04T22:38:26.931Z,1683239906.931 [DataOverHttps](INFO): SBD MOMSN=18281792
2023-05-04T22:38:42.523Z,1683239922.523 [NAL9602](INFO): SBD MO Status=0, MOMSN=25491, MT Status=0, MTMSN=0
2023-05-04T22:38:42.523Z,1683239922.523 [NAL9602](INFO): No messages in MT queue
2023-05-04T22:38:46.413Z,1683239926.413 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230504T222442/Express0004.lzma
2023-05-04T22:38:47.414Z,1683239927.414 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0004.lzma.bak
2023-05-04T22:38:47.415Z,1683239927.415 [DataOverHttps](INFO): SBD MOMSN=18281795
2023-05-04T22:39:04.186Z,1683239944.186 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230504T222442/Express0007.lzma
2023-05-04T22:39:05.199Z,1683239945.199 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0007.lzma.bak
2023-05-04T22:39:05.199Z,1683239945.199 [DataOverHttps](INFO): SBD MOMSN=18281798
2023-05-04T22:39:06.404Z,1683239946.404 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T22:39:06.404Z,1683239946.404 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T22:39:06.404Z,1683239946.404 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T22:39:13.222Z,1683239953.222 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T22:44:06.961Z,1683240246.961 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T22:44:06.962Z,1683240246.962 [Default:CheckIn:C.Wait] Stopped
2023-05-04T22:44:06.962Z,1683240246.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T22:44:06.962Z,1683240246.962 [Default:CheckIn:D] Running Loop=1
2023-05-04T22:44:07.344Z,1683240247.344 [Default:CheckIn:D] Stopped
2023-05-04T22:44:07.344Z,1683240247.344 [Default:CheckIn:E] Running Loop=1
2023-05-04T22:44:07.748Z,1683240247.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.441772 min 
2023-05-04T22:44:07.748Z,1683240247.748 [Default:CheckIn:E] Stopped
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn] Stopped
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn](INFO): Running loop #4
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn] Running Loop=4
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T22:44:07.749Z,1683240247.749 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T22:44:09.760Z,1683240249.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224408.00,A,3648.14888,N,12147.22254,W,0.117,111.66,040523,,,D*71
2023-05-04T22:44:09.763Z,1683240249.763 [NAL9602](INFO): GPS fix at 20230504T224408: (36.802481, -121.787042)
2023-05-04T22:44:09.797Z,1683240249.797 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T22:44:09.797Z,1683240249.797 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T22:44:20.568Z,1683240260.568 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230504T222442/Courier0009.lzma
2023-05-04T22:44:21.571Z,1683240261.571 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0009.lzma.bak
2023-05-04T22:44:21.571Z,1683240261.571 [DataOverHttps](INFO): SBD MOMSN=18281809
2023-05-04T22:44:24.712Z,1683240264.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=25492, MT Status=0, MTMSN=0
2023-05-04T22:44:24.712Z,1683240264.712 [NAL9602](INFO): No messages in MT queue
2023-05-04T22:44:37.988Z,1683240277.988 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230504T222442/Express0010.lzma
2023-05-04T22:44:38.990Z,1683240278.990 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0010.lzma.bak
2023-05-04T22:44:38.991Z,1683240278.991 [DataOverHttps](INFO): SBD MOMSN=18281812
2023-05-04T22:44:40.511Z,1683240280.511 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T22:44:40.511Z,1683240280.511 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T22:44:40.512Z,1683240280.512 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T22:44:55.410Z,1683240295.410 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T22:49:41.109Z,1683240581.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T22:49:41.109Z,1683240581.109 [Default:CheckIn:C.Wait] Stopped
2023-05-04T22:49:41.109Z,1683240581.109 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T22:49:41.109Z,1683240581.109 [Default:CheckIn:D] Running Loop=1
2023-05-04T22:49:41.514Z,1683240581.514 [Default:CheckIn:D] Stopped
2023-05-04T22:49:41.514Z,1683240581.514 [Default:CheckIn:E] Running Loop=1
2023-05-04T22:49:41.923Z,1683240581.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.011267 min 
2023-05-04T22:49:41.923Z,1683240581.923 [Default:CheckIn:E] Stopped
2023-05-04T22:49:41.923Z,1683240581.923 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T22:49:41.923Z,1683240581.923 [Default:CheckIn] Stopped
2023-05-04T22:49:41.923Z,1683240581.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T22:49:41.924Z,1683240581.924 [Default:CheckIn](INFO): Running loop #5
2023-05-04T22:49:41.924Z,1683240581.924 [Default:CheckIn] Running Loop=5
2023-05-04T22:49:41.924Z,1683240581.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T22:49:41.924Z,1683240581.924 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T22:49:43.923Z,1683240583.923 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224942.00,A,3648.17739,N,12147.28127,W,0.214,293.73,040523,,,D*74
2023-05-04T22:49:43.925Z,1683240583.925 [NAL9602](INFO): GPS fix at 20230504T224942: (36.802957, -121.788021)
2023-05-04T22:49:43.936Z,1683240583.936 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T22:49:43.936Z,1683240583.936 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T22:49:51.692Z,1683240591.692 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230504T222442/Courier0012.lzma
2023-05-04T22:49:52.694Z,1683240592.694 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0012.lzma.bak
2023-05-04T22:49:52.695Z,1683240592.695 [DataOverHttps](INFO): SBD MOMSN=18281816
2023-05-04T22:50:09.032Z,1683240609.032 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230504T222442/Express0013.lzma
2023-05-04T22:50:10.034Z,1683240610.034 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0013.lzma.bak
2023-05-04T22:50:10.035Z,1683240610.035 [DataOverHttps](INFO): SBD MOMSN=18281821
2023-05-04T22:50:11.416Z,1683240611.416 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T22:50:11.416Z,1683240611.416 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T22:50:11.416Z,1683240611.416 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T22:51:52.870Z,1683240712.870 [NAL9602](INFO): SBD MO Status=0, MOMSN=25493, MT Status=0, MTMSN=0
2023-05-04T22:51:52.870Z,1683240712.870 [NAL9602](INFO): No messages in MT queue
2023-05-04T22:52:23.578Z,1683240743.578 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T22:55:12.051Z,1683240912.051 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T22:55:12.051Z,1683240912.051 [Default:CheckIn:C.Wait] Stopped
2023-05-04T22:55:12.051Z,1683240912.051 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T22:55:12.051Z,1683240912.051 [Default:CheckIn:D] Running Loop=1
2023-05-04T22:55:12.500Z,1683240912.500 [Default:CheckIn:D] Stopped
2023-05-04T22:55:12.500Z,1683240912.500 [Default:CheckIn:E] Running Loop=1
2023-05-04T22:55:12.881Z,1683240912.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.527698 min 
2023-05-04T22:55:12.881Z,1683240912.881 [Default:CheckIn:E] Stopped
2023-05-04T22:55:12.881Z,1683240912.881 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T22:55:12.881Z,1683240912.881 [Default:CheckIn] Stopped
2023-05-04T22:55:12.881Z,1683240912.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T22:55:12.882Z,1683240912.882 [Default:CheckIn](INFO): Running loop #6
2023-05-04T22:55:12.882Z,1683240912.882 [Default:CheckIn] Running Loop=6
2023-05-04T22:55:12.882Z,1683240912.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T22:55:12.882Z,1683240912.882 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T22:55:14.876Z,1683240914.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225513.00,A,3648.17559,N,12147.27603,W,0.156,293.73,040523,,,A*77
2023-05-04T22:55:14.888Z,1683240914.888 [NAL9602](INFO): GPS fix at 20230504T225513: (36.802926, -121.787934)
2023-05-04T22:55:14.899Z,1683240914.899 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T22:55:14.899Z,1683240914.899 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T22:55:22.224Z,1683240922.224 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230504T222442/Courier0015.lzma
2023-05-04T22:55:23.218Z,1683240923.218 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0015.lzma.bak
2023-05-04T22:55:23.219Z,1683240923.219 [DataOverHttps](INFO): SBD MOMSN=18281829
2023-05-04T22:55:39.516Z,1683240939.516 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230504T222442/Express0016.lzma
2023-05-04T22:55:40.519Z,1683240940.519 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0016.lzma.bak
2023-05-04T22:55:40.519Z,1683240940.519 [DataOverHttps](INFO): SBD MOMSN=18281832
2023-05-04T22:55:41.969Z,1683240941.969 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T22:55:41.969Z,1683240941.969 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T22:55:41.970Z,1683240941.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T22:58:40.919Z,1683241120.919 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-05-04T22:58:40.919Z,1683241120.919 [DropWeight] Hardware Fault, FailCount= 1
2023-05-04T22:58:40.919Z,1683241120.919 [DropWeight](ERROR): Hardware Fault
2023-05-04T22:58:40.956Z,1683241120.956 [CommandExec](FAULT): Scheduling is paused
2023-05-04T22:58:40.957Z,1683241120.957 [CBIT](INFO): Critical error at 20230504T225840
2023-05-04T22:58:40.959Z,1683241120.959 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-05-04T22:58:40.960Z,1683241120.960 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-05-04T22:58:41.363Z,1683241121.363 [CBIT](INFO): Critical error at 20230504T225840
2023-05-04T23:00:17.066Z,1683241217.066 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-04T23:00:42.551Z,1683241242.551 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T23:00:42.552Z,1683241242.552 [Default:CheckIn:C.Wait] Stopped
2023-05-04T23:00:42.552Z,1683241242.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:00:42.552Z,1683241242.552 [Default:CheckIn:D] Running Loop=1
2023-05-04T23:00:42.939Z,1683241242.939 [Default:CheckIn:D] Stopped
2023-05-04T23:00:42.939Z,1683241242.939 [Default:CheckIn:E] Running Loop=1
2023-05-04T23:00:43.336Z,1683241243.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.035018 min 
2023-05-04T23:00:43.336Z,1683241243.336 [Default:CheckIn:E] Stopped
2023-05-04T23:00:43.336Z,1683241243.336 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T23:00:43.336Z,1683241243.336 [Default:CheckIn] Stopped
2023-05-04T23:00:43.336Z,1683241243.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:00:43.336Z,1683241243.336 [Default:CheckIn](INFO): Running loop #7
2023-05-04T23:00:43.337Z,1683241243.337 [Default:CheckIn] Running Loop=7
2023-05-04T23:00:43.337Z,1683241243.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:00:43.337Z,1683241243.337 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:00:45.344Z,1683241245.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230044.00,A,3648.17423,N,12147.27657,W,0.097,139.11,040523,,,A*72
2023-05-04T23:00:45.346Z,1683241245.346 [NAL9602](INFO): GPS fix at 20230504T230044: (36.802904, -121.787943)
2023-05-04T23:00:45.357Z,1683241245.357 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:00:45.357Z,1683241245.357 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T23:01:01.912Z,1683241261.912 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20230504T222442/Courier0018.lzma
2023-05-04T23:01:02.914Z,1683241262.914 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0018.lzma.bak
2023-05-04T23:01:02.915Z,1683241262.915 [DataOverHttps](INFO): SBD MOMSN=18281841
2023-05-04T23:01:18.066Z,1683241278.066 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T23:01:19.593Z,1683241279.593 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20230504T222442/Express0019.lzma
2023-05-04T23:01:20.594Z,1683241280.594 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0019.lzma.bak
2023-05-04T23:01:20.595Z,1683241280.595 [DataOverHttps](INFO): SBD MOMSN=18281846
2023-05-04T23:01:21.714Z,1683241281.714 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T23:01:21.715Z,1683241281.715 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T23:01:21.715Z,1683241281.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:04:16.650Z,1683241456.650 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-04T23:04:16.653Z,1683241456.653 [BPC1](INFO): Received data from all battery sticks.
2023-05-04T23:06:22.330Z,1683241582.330 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T23:06:22.330Z,1683241582.330 [Default:CheckIn:C.Wait] Stopped
2023-05-04T23:06:22.330Z,1683241582.330 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:06:22.331Z,1683241582.331 [Default:CheckIn:D] Running Loop=1
2023-05-04T23:06:22.704Z,1683241582.704 [Default:CheckIn:D] Stopped
2023-05-04T23:06:22.704Z,1683241582.704 [Default:CheckIn:E] Running Loop=1
2023-05-04T23:06:23.112Z,1683241583.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.697774 min 
2023-05-04T23:06:23.112Z,1683241583.112 [Default:CheckIn:E] Stopped
2023-05-04T23:06:23.112Z,1683241583.112 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T23:06:23.113Z,1683241583.113 [Default:CheckIn] Stopped
2023-05-04T23:06:23.113Z,1683241583.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:06:23.113Z,1683241583.113 [Default:CheckIn](INFO): Running loop #8
2023-05-04T23:06:23.113Z,1683241583.113 [Default:CheckIn] Running Loop=8
2023-05-04T23:06:23.113Z,1683241583.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:06:23.113Z,1683241583.113 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:06:25.121Z,1683241585.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230623.00,A,3648.16624,N,12147.28065,W,0.136,271.15,040523,,,A*78
2023-05-04T23:06:25.123Z,1683241585.123 [NAL9602](INFO): GPS fix at 20230504T230623: (36.802771, -121.788011)
2023-05-04T23:06:25.135Z,1683241585.135 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:06:25.135Z,1683241585.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T23:06:35.644Z,1683241595.644 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230504T222442/Courier0021.lzma
2023-05-04T23:06:36.646Z,1683241596.646 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0021.lzma.bak
2023-05-04T23:06:36.647Z,1683241596.647 [DataOverHttps](INFO): SBD MOMSN=18281851
2023-05-04T23:06:53.213Z,1683241613.213 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230504T222442/Express0022.lzma
2023-05-04T23:06:54.070Z,1683241614.070 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0022.lzma.bak
2023-05-04T23:06:54.071Z,1683241614.071 [DataOverHttps](INFO): SBD MOMSN=18281854
2023-05-04T23:06:55.455Z,1683241615.455 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T23:06:55.456Z,1683241615.456 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T23:06:55.456Z,1683241615.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:06:55.820Z,1683241615.820 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-04T23:06:55.897Z,1683241615.897 [NAL9602](ERROR): received: 
+CSQ:0

OK493, 0, 0, 0, 0

OK
2023-05-04T23:11:26.910Z,1683241886.910 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-04T23:11:56.003Z,1683241916.003 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T23:11:56.003Z,1683241916.003 [Default:CheckIn:C.Wait] Stopped
2023-05-04T23:11:56.003Z,1683241916.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:11:56.003Z,1683241916.003 [Default:CheckIn:D] Running Loop=1
2023-05-04T23:11:56.452Z,1683241916.452 [Default:CheckIn:D] Stopped
2023-05-04T23:11:56.452Z,1683241916.452 [Default:CheckIn:E] Running Loop=1
2023-05-04T23:11:56.812Z,1683241916.812 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.260242 min 
2023-05-04T23:11:56.812Z,1683241916.812 [Default:CheckIn:E] Stopped
2023-05-04T23:11:56.812Z,1683241916.812 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T23:11:56.812Z,1683241916.812 [Default:CheckIn] Stopped
2023-05-04T23:11:56.812Z,1683241916.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:11:56.813Z,1683241916.813 [Default:CheckIn](INFO): Running loop #9
2023-05-04T23:11:56.813Z,1683241916.813 [Default:CheckIn] Running Loop=9
2023-05-04T23:11:56.813Z,1683241916.813 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:11:56.813Z,1683241916.813 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:11:58.832Z,1683241918.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231157.00,A,3648.16753,N,12147.27430,W,0.622,158.41,040523,,,A*7C
2023-05-04T23:11:58.835Z,1683241918.835 [NAL9602](INFO): GPS fix at 20230504T231157: (36.802792, -121.787905)
2023-05-04T23:11:58.845Z,1683241918.845 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:11:58.846Z,1683241918.846 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T23:12:07.008Z,1683241927.008 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230504T222442/Courier0024.lzma
2023-05-04T23:12:08.010Z,1683241928.010 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0024.lzma.bak
2023-05-04T23:12:08.011Z,1683241928.011 [DataOverHttps](INFO): SBD MOMSN=18281857
2023-05-04T23:12:24.247Z,1683241944.247 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230504T222442/Express0025.lzma
2023-05-04T23:12:25.242Z,1683241945.242 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0025.lzma.bak
2023-05-04T23:12:25.243Z,1683241945.243 [DataOverHttps](INFO): SBD MOMSN=18281862
2023-05-04T23:12:26.740Z,1683241946.740 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T23:12:26.741Z,1683241946.741 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T23:12:26.741Z,1683241946.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:12:31.165Z,1683241951.165 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T23:13:31.454Z,1683242011.454 [DAT](INFO): DAT read: user:6>Rx Time:23:13:28.9986

2023-05-04T23:13:31.958Z,1683242011.958 [DAT](INFO): DAT read: 

2023-05-04T23:13:31.958Z,1683242011.958 [DAT](INFO): DAT read: $Packet for address 6

2023-05-04T23:13:31.959Z,1683242011.959 [DAT](INFO): received a packet notification
2023-05-04T23:13:33.474Z,1683242013.474 [DAT](INFO): DAT read: Rx Time:23:13:31.0027

2023-05-04T23:13:33.475Z,1683242013.475 [DAT](INFO): Rx dataTimestamp_ set to:1683242011.002701
2023-05-04T23:13:33.475Z,1683242013.475 [DAT](INFO): received an acoustic signal
2023-05-04T23:13:33.977Z,1683242013.977 [DAT](INFO): DAT read: 

2023-05-04T23:13:33.978Z,1683242013.978 [DAT](INFO): DAT read: $Packet for address 5

2023-05-04T23:13:33.978Z,1683242013.978 [DAT](INFO): received a packet notification
2023-05-04T23:13:41.127Z,1683242021.127 [CBIT](INFO): Clearing failed state for component DropWeight
2023-05-04T23:13:41.127Z,1683242021.127 [DropWeight] No Fault, FailCount= 1
2023-05-04T23:14:17.622Z,1683242057.622 [DAT](INFO): DAT read: Rx Time:23:14:15.2470

2023-05-04T23:14:17.623Z,1683242057.623 [DAT](INFO): Rx dataTimestamp_ set to:1683242055.247000
2023-05-04T23:14:17.623Z,1683242057.623 [DAT](INFO): received an acoustic signal
2023-05-04T23:14:18.127Z,1683242058.127 [DAT](INFO): DAT read: 

2023-05-04T23:14:18.129Z,1683242058.129 [DAT](INFO): DAT read: $Packet for address 6

2023-05-04T23:14:18.130Z,1683242058.130 [DAT](INFO): received a packet notification
2023-05-04T23:14:18.141Z,1683242058.141 [DAT](INFO): DAT read: TA PACKET

2023-05-04T23:14:18.141Z,1683242058.141 [DAT](INFO): unknown deviceResponse_: TA PACKET

2023-05-04T23:14:21.906Z,1683242061.906 [DAT](INFO): DAT read: Rx Time:23:14:19.3245

2023-05-04T23:14:21.907Z,1683242061.907 [DAT](INFO): Rx dataTimestamp_ set to:1683242059.324499
2023-05-04T23:14:21.907Z,1683242061.907 [DAT](INFO): received an acoustic signal
2023-05-04T23:14:22.409Z,1683242062.409 [DAT](INFO): DAT read: 

2023-05-04T23:14:22.410Z,1683242062.410 [DAT](INFO): DAT read: $Packet for address 5

2023-05-04T23:14:22.410Z,1683242062.410 [DAT](INFO): received a packet notification
2023-05-04T23:14:25.438Z,1683242065.438 [DAT](INFO): DAT read: Rx Time:23:14:23.0470

2023-05-04T23:14:25.439Z,1683242065.439 [DAT](INFO): Rx dataTimestamp_ set to:1683242063.047001
2023-05-04T23:14:25.439Z,1683242065.439 [DAT](INFO): received an acoustic signal
2023-05-04T23:14:25.941Z,1683242065.941 [DAT](INFO): DAT read: 

2023-05-04T23:14:25.942Z,1683242065.942 [DAT](INFO): DAT read: $Packet for address 6

2023-05-04T23:14:25.942Z,1683242065.942 [DAT](INFO): received a packet notification
2023-05-04T23:15:19.461Z,1683242119.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-04T23:15:21.469Z,1683242121.469 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255341
2023-05-04T23:15:32.022Z,1683242132.022 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003412
2023-05-04T23:17:06.809Z,1683242226.809 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-05-04T23:17:08.817Z,1683242228.817 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.911148
2023-05-04T23:17:19.521Z,1683242239.521 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.010282
2023-05-04T23:17:27.307Z,1683242247.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T23:17:27.307Z,1683242247.307 [Default:CheckIn:C.Wait] Stopped
2023-05-04T23:17:27.308Z,1683242247.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:17:27.308Z,1683242247.308 [Default:CheckIn:D] Running Loop=1
2023-05-04T23:17:27.713Z,1683242247.713 [Default:CheckIn:D] Stopped
2023-05-04T23:17:27.713Z,1683242247.713 [Default:CheckIn:E] Running Loop=1
2023-05-04T23:17:28.129Z,1683242248.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.781242 min 
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn:E] Stopped
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn] Stopped
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn](INFO): Running loop #10
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn] Running Loop=10
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:17:28.130Z,1683242248.130 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:17:30.128Z,1683242250.128 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231728.00,A,3648.17050,N,12147.27575,W,0.680,126.04,040523,,,A*77
2023-05-04T23:17:30.131Z,1683242250.131 [NAL9602](INFO): GPS fix at 20230504T231728: (36.802842, -121.787929)
2023-05-04T23:17:30.142Z,1683242250.142 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:17:30.142Z,1683242250.142 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T23:17:38.312Z,1683242258.312 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230504T222442/Courier0027.lzma
2023-05-04T23:17:39.315Z,1683242259.315 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Courier0027.lzma.bak
2023-05-04T23:17:39.315Z,1683242259.315 [DataOverHttps](INFO): SBD MOMSN=18281926
2023-05-04T23:17:55.524Z,1683242275.524 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230504T222442/Express0028.lzma
2023-05-04T23:17:56.527Z,1683242276.527 [DataOverHttps](INFO): Moved sent file to Logs/20230504T222442/Express0028.lzma.bak
2023-05-04T23:17:56.527Z,1683242276.527 [DataOverHttps](INFO): SBD MOMSN=18281929
2023-05-04T23:17:58.023Z,1683242278.023 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T23:17:58.023Z,1683242278.023 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T23:17:58.023Z,1683242278.023 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:18:00.834Z,1683242280.834 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-04T23:18:00.909Z,1683242280.909 [NAL9602](ERROR): received: 
+CSQ:0

OK493, 0, 0, 0, 0

OK
2023-05-04T23:20:22.050Z,1683242422.050 [CommandExec](IMPORTANT): got command quit
2023-05-04T23:20:23.053Z,1683242423.053 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:23.053Z,1683242423.053 [CommandExec](INFO): Uninitializing the command executive.
2023-05-04T23:20:23.053Z,1683242423.053 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-04T23:20:23.053Z,1683242423.053 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:23.117Z,1683242423.117 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-04T23:20:23.117Z,1683242423.117 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-04T23:20:23.117Z,1683242423.117 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:23.118Z,1683242423.118 [NavChartDb](INFO): Join timeout helper Thread ID is 3899
2023-05-04T23:20:23.333Z,1683242423.333 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:23.334Z,1683242423.334 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:23.337Z,1683242423.337 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-04T23:20:23.337Z,1683242423.337 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:23.337Z,1683242423.337 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3900
2023-05-04T23:20:23.657Z,1683242423.657 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:23.657Z,1683242423.657 [WetLabsUBAT](INFO): Powering down
2023-05-04T23:20:23.658Z,1683242423.658 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:23.661Z,1683242423.661 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-04T23:20:23.661Z,1683242423.661 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:23.661Z,1683242423.661 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3901
2023-05-04T23:20:24.057Z,1683242424.057 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:24.057Z,1683242424.057 [WetLabsBB2FL](INFO): Powering down
2023-05-04T23:20:24.058Z,1683242424.058 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.065Z,1683242424.065 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-04T23:20:24.065Z,1683242424.065 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.065Z,1683242424.065 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3902
2023-05-04T23:20:24.481Z,1683242424.481 [CTD_Seabird](INFO): Powering down
2023-05-04T23:20:24.493Z,1683242424.493 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:24.493Z,1683242424.493 [CTD_Seabird](INFO): Powering down
2023-05-04T23:20:24.521Z,1683242424.521 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.541Z,1683242424.541 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-05-04T23:20:24.541Z,1683242424.541 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.541Z,1683242424.541 [DAT](INFO): Join timeout helper Thread ID is 3903
2023-05-04T23:20:24.625Z,1683242424.625 [DAT](INFO): Powering down
2023-05-04T23:20:24.705Z,1683242424.705 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:24.705Z,1683242424.705 [DAT](INFO): Powering down
2023-05-04T23:20:24.706Z,1683242424.706 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.722Z,1683242424.722 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-04T23:20:24.722Z,1683242424.722 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.722Z,1683242424.722 [Radio_Surface](INFO): Join timeout helper Thread ID is 3904
2023-05-04T23:20:24.881Z,1683242424.881 [Radio_Surface](INFO): Powering down
2023-05-04T23:20:24.882Z,1683242424.882 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:24.882Z,1683242424.882 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.897Z,1683242424.897 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-04T23:20:24.898Z,1683242424.898 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:24.898Z,1683242424.898 [Onboard](INFO): Join timeout helper Thread ID is 3905
2023-05-04T23:20:26.645Z,1683242426.645 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:26.645Z,1683242426.645 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:26.657Z,1683242426.657 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-04T23:20:26.657Z,1683242426.657 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:26.658Z,1683242426.658 [DataOverHttps](INFO): Join timeout helper Thread ID is 3906
2023-05-04T23:20:27.017Z,1683242427.017 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:27.020Z,1683242427.020 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.030Z,1683242427.030 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-04T23:20:27.030Z,1683242427.030 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.030Z,1683242427.030 [BackseatComponent](INFO): Join timeout helper Thread ID is 3907
2023-05-04T23:20:27.185Z,1683242427.185 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:27.185Z,1683242427.185 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.193Z,1683242427.193 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-04T23:20:27.193Z,1683242427.193 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.194Z,1683242427.194 [logger](INFO): Join timeout helper Thread ID is 3908
2023-05-04T23:20:27.213Z,1683242427.213 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:27.213Z,1683242427.213 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.233Z,1683242427.233 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-04T23:20:27.233Z,1683242427.233 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.234Z,1683242427.234 [CommandLine](INFO): Join timeout helper Thread ID is 3909
2023-05-04T23:20:27.249Z,1683242427.249 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:27.249Z,1683242427.249 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.253Z,1683242427.253 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-04T23:20:27.253Z,1683242427.253 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.254Z,1683242427.254 [CommandExec](INFO): Join timeout helper Thread ID is 3910
2023-05-04T23:20:27.255Z,1683242427.255 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-04T23:20:27.255Z,1683242427.255 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.256Z,1683242427.256 [controlThread](INFO): Join timeout helper Thread ID is 3911
2023-05-04T23:20:27.489Z,1683242427.489 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:20:27.489Z,1683242427.489 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-04T23:20:27.489Z,1683242427.489 [AHRS_M2](INFO): Powering down
2023-05-04T23:20:27.561Z,1683242427.561 [NAL9602](INFO): Powering down
2023-05-04T23:20:27.633Z,1683242427.633 [RDI_Pathfinder](INFO): Powering down
2023-05-04T23:20:27.634Z,1683242427.634 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-04T23:20:27.635Z,1683242427.635 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-04T23:20:27.635Z,1683242427.635 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-04T23:20:27.636Z,1683242427.636 [MissionManager](INFO): Uninitializing Mission Default
2023-05-04T23:20:27.636Z,1683242427.636 [Default] Stopped
2023-05-04T23:20:27.636Z,1683242427.636 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T23:20:27.636Z,1683242427.636 [Default:B.GoToSurface] Stopped
2023-05-04T23:20:27.636Z,1683242427.636 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:20:27.636Z,1683242427.636 [Default:CheckIn] Stopped
2023-05-04T23:20:27.637Z,1683242427.637 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:20:27.637Z,1683242427.637 [Default:CheckIn:C.Wait] Stopped
2023-05-04T23:20:27.637Z,1683242427.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:20:27.639Z,1683242427.639 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-04T23:20:27.640Z,1683242427.640 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-04T23:20:27.640Z,1683242427.640 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-04T23:20:27.640Z,1683242427.640 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-04T23:20:27.641Z,1683242427.641 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-04T23:20:27.641Z,1683242427.641 [BuoyancyServo](INFO): Powering down
2023-05-04T23:20:27.653Z,1683242427.653 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-04T23:20:27.653Z,1683242427.653 [ElevatorServo](INFO): Powering down
2023-05-04T23:20:27.654Z,1683242427.654 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-04T23:20:27.654Z,1683242427.654 [MassServo](INFO): Powering down
2023-05-04T23:20:27.655Z,1683242427.655 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-04T23:20:27.655Z,1683242427.655 [RudderServo](INFO): Powering down
2023-05-04T23:20:27.656Z,1683242427.656 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-04T23:20:27.656Z,1683242427.656 [ThrusterServo](INFO): Powering down
2023-05-04T23:20:27.657Z,1683242427.657 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-04T23:20:27.657Z,1683242427.657 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-04T23:20:27.657Z,1683242427.657 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-04T23:20:27.657Z,1683242427.657 [CBIT](DEBUG): Powering off loads.
2023-05-04T23:20:27.668Z,1683242427.668 [CBIT](DEBUG): Disabling WDT.
2023-05-04T23:20:27.680Z,1683242427.680 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-04T23:20:27.682Z,1683242427.682 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.735Z,1683242427.735 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.742Z,1683242427.742 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.744Z,1683242427.744 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.750Z,1683242427.750 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.836Z,1683242427.836 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.908Z,1683242427.908 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.910Z,1683242427.910 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.914Z,1683242427.914 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:27.921Z,1683242427.921 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:20:28.009Z,1683242428.009 [logger ThreadHandler](INFO): Thread cancelled.