2021-04-19T17:55:13.988Z,1618854913.988 [CommandLine](IMPORTANT): got command restart logs
2021-04-19T17:55:44.520Z,1618854944.520 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T17:58:06.360Z,1618855086.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T17:58:06.361Z,1618855086.361 [Default:CheckIn:C.Wait] Stopped
2021-04-19T17:58:06.361Z,1618855086.361 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T17:58:06.361Z,1618855086.361 [Default:CheckIn:D] Running Loop=1
2021-04-19T17:58:06.748Z,1618855086.748 [Default:CheckIn:D] Stopped
2021-04-19T17:58:06.748Z,1618855086.748 [Default:CheckIn:E] Running Loop=1
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.725800 min 
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn:E] Stopped
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn] Stopped
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn](INFO): Running loop #5
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn] Running Loop=5
2021-04-19T17:58:07.152Z,1618855087.152 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T17:58:07.153Z,1618855087.153 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T17:58:09.158Z,1618855089.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175808.00,A,3648.13691,N,12147.22627,W,0.039,321.06,190421,,,D*7E
2021-04-19T17:58:09.161Z,1618855089.161 [NAL9602](INFO): GPS fix at 20210419T175808: (36.802282, -121.787104)
2021-04-19T17:58:09.204Z,1618855089.204 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T17:58:09.204Z,1618855089.204 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T17:58:16.855Z,1618855096.855 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210419T174525/Courier0006.lzma
2021-04-19T17:58:17.860Z,1618855097.860 [DataOverHttps](INFO): Moved sent file to Logs/20210419T174525/Courier0006.lzma.bak
2021-04-19T17:58:17.860Z,1618855097.860 [DataOverHttps](INFO): SBD MOMSN=15585886
2021-04-19T17:58:30.221Z,1618855110.221 [NAL9602](INFO): SBD MO Status=1, MOMSN=14614, MT Status=0, MTMSN=0
2021-04-19T17:58:30.221Z,1618855110.221 [NAL9602](INFO): No messages in MT queue
2021-04-19T17:58:40.072Z,1618855120.072 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20210419T175513/Courier0000.lzma
2021-04-19T17:58:41.073Z,1618855121.073 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0000.lzma.bak
2021-04-19T17:58:41.073Z,1618855121.073 [DataOverHttps](INFO): SBD MOMSN=15585888
2021-04-19T17:58:57.007Z,1618855137.007 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210419T174525/Express0007.lzma
2021-04-19T17:58:58.009Z,1618855138.009 [DataOverHttps](INFO): Moved sent file to Logs/20210419T174525/Express0007.lzma.bak
2021-04-19T17:58:58.009Z,1618855138.009 [DataOverHttps](INFO): SBD MOMSN=15585892
2021-04-19T17:59:00.997Z,1618855140.997 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T17:59:14.479Z,1618855154.479 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20210419T175513/Express0001.lzma
2021-04-19T17:59:15.481Z,1618855155.481 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0001.lzma.bak
2021-04-19T17:59:15.481Z,1618855155.481 [DataOverHttps](INFO): SBD MOMSN=15585900
2021-04-19T17:59:17.200Z,1618855157.200 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T17:59:17.200Z,1618855157.200 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T17:59:17.200Z,1618855157.200 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:04:17.928Z,1618855457.928 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:04:17.928Z,1618855457.928 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:04:17.929Z,1618855457.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:04:17.929Z,1618855457.929 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:04:18.263Z,1618855458.263 [Default:CheckIn:D] Stopped
2021-04-19T18:04:18.263Z,1618855458.263 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.917721 min 
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn:E] Stopped
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn] Stopped
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn](INFO): Running loop #6
2021-04-19T18:04:18.668Z,1618855458.668 [Default:CheckIn] Running Loop=6
2021-04-19T18:04:18.669Z,1618855458.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:04:18.669Z,1618855458.669 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:04:20.674Z,1618855460.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180419.00,A,3648.17372,N,12147.28064,W,0.097,339.16,190421,,,D*73
2021-04-19T18:04:20.693Z,1618855460.693 [NAL9602](INFO): GPS fix at 20210419T180419: (36.802895, -121.788011)
2021-04-19T18:04:20.730Z,1618855460.730 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:04:20.730Z,1618855460.730 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:04:31.807Z,1618855471.807 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210419T175513/Courier0003.lzma
2021-04-19T18:04:32.809Z,1618855472.809 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0003.lzma.bak
2021-04-19T18:04:32.809Z,1618855472.809 [DataOverHttps](INFO): SBD MOMSN=15585934
2021-04-19T18:04:42.186Z,1618855482.186 [NAL9602](INFO): SBD MO Status=1, MOMSN=14615, MT Status=0, MTMSN=0
2021-04-19T18:04:42.186Z,1618855482.186 [NAL9602](INFO): No messages in MT queue
2021-04-19T18:04:51.923Z,1618855491.923 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210419T175513/Express0004.lzma
2021-04-19T18:04:52.925Z,1618855492.925 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0004.lzma.bak
2021-04-19T18:04:52.926Z,1618855492.926 [DataOverHttps](INFO): SBD MOMSN=15585937
2021-04-19T18:04:54.725Z,1618855494.725 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:04:54.725Z,1618855494.725 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:04:54.725Z,1618855494.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:05:12.888Z,1618855512.888 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T18:09:55.370Z,1618855795.370 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:09:55.370Z,1618855795.370 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:09:55.370Z,1618855795.370 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:09:55.370Z,1618855795.370 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:09:55.800Z,1618855795.800 [Default:CheckIn:D] Stopped
2021-04-19T18:09:55.800Z,1618855795.800 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:09:56.172Z,1618855796.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.543339 min 
2021-04-19T18:09:56.172Z,1618855796.172 [Default:CheckIn:E] Stopped
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn] Stopped
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn](INFO): Running loop #7
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn] Running Loop=7
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:09:56.173Z,1618855796.173 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:09:58.183Z,1618855798.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180957.00,A,3648.16859,N,12147.28549,W,0.467,192.18,190421,,,A*7E
2021-04-19T18:09:58.185Z,1618855798.185 [NAL9602](INFO): GPS fix at 20210419T180957: (36.802810, -121.788091)
2021-04-19T18:09:58.197Z,1618855798.197 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:09:58.197Z,1618855798.197 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:10:05.899Z,1618855805.899 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210419T175513/Courier0006.lzma
2021-04-19T18:10:06.901Z,1618855806.901 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0006.lzma.bak
2021-04-19T18:10:06.901Z,1618855806.901 [DataOverHttps](INFO): SBD MOMSN=15585955
2021-04-19T18:10:27.004Z,1618855827.004 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20210419T175513/Express0007.lzma
2021-04-19T18:10:28.005Z,1618855828.005 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0007.lzma.bak
2021-04-19T18:10:28.005Z,1618855828.005 [DataOverHttps](INFO): SBD MOMSN=15585958
2021-04-19T18:10:29.849Z,1618855829.849 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:10:29.849Z,1618855829.849 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:10:29.849Z,1618855829.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:11:02.166Z,1618855862.166 [NAL9602](INFO): SBD MO Status=2, MOMSN=14616, MT Status=2, MTMSN=0
2021-04-19T18:11:02.166Z,1618855862.166 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-19T18:12:09.226Z,1618855929.226 [NAL9602](INFO): SBD MO Status=1, MOMSN=14616, MT Status=0, MTMSN=0
2021-04-19T18:12:09.226Z,1618855929.226 [NAL9602](INFO): No messages in MT queue
2021-04-19T18:12:39.996Z,1618855959.996 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T18:12:48.888Z,1618855968.888 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2021-04-19T18:12:48.890Z,1618855968.890 [BPC1](INFO): Received data from all battery sticks.
2021-04-19T18:15:30.503Z,1618856130.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:15:30.503Z,1618856130.503 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:15:30.504Z,1618856130.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:15:30.504Z,1618856130.504 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:15:30.916Z,1618856130.916 [Default:CheckIn:D] Stopped
2021-04-19T18:15:30.916Z,1618856130.916 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:15:31.322Z,1618856131.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.128601 min 
2021-04-19T18:15:31.322Z,1618856131.322 [Default:CheckIn:E] Stopped
2021-04-19T18:15:31.322Z,1618856131.322 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:15:31.323Z,1618856131.323 [Default:CheckIn] Stopped
2021-04-19T18:15:31.323Z,1618856131.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:15:31.323Z,1618856131.323 [Default:CheckIn](INFO): Running loop #8
2021-04-19T18:15:31.323Z,1618856131.323 [Default:CheckIn] Running Loop=8
2021-04-19T18:15:31.323Z,1618856131.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:15:31.323Z,1618856131.323 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:15:33.326Z,1618856133.326 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181532.00,A,3648.17462,N,12147.28095,W,1.380,192.18,190421,,,A*7E
2021-04-19T18:15:33.328Z,1618856133.328 [NAL9602](INFO): GPS fix at 20210419T181532: (36.802910, -121.788016)
2021-04-19T18:15:33.364Z,1618856133.364 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:15:33.365Z,1618856133.365 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:15:41.411Z,1618856141.411 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20210419T175513/Courier0009.lzma
2021-04-19T18:15:42.415Z,1618856142.415 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0009.lzma.bak
2021-04-19T18:15:42.415Z,1618856142.415 [DataOverHttps](INFO): SBD MOMSN=15585980
2021-04-19T18:15:58.531Z,1618856158.531 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20210419T175513/Express0010.lzma
2021-04-19T18:15:59.536Z,1618856159.536 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0010.lzma.bak
2021-04-19T18:15:59.537Z,1618856159.537 [DataOverHttps](INFO): SBD MOMSN=15585983
2021-04-19T18:16:01.265Z,1618856161.265 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:16:01.265Z,1618856161.265 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:16:01.265Z,1618856161.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:20:35.608Z,1618856435.608 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-19T18:21:01.906Z,1618856461.906 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:21:01.906Z,1618856461.906 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:21:01.906Z,1618856461.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:21:01.906Z,1618856461.906 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:21:02.284Z,1618856462.284 [Default:CheckIn:D] Stopped
2021-04-19T18:21:02.284Z,1618856462.284 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:21:02.688Z,1618856462.688 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.651396 min 
2021-04-19T18:21:02.688Z,1618856462.688 [Default:CheckIn:E] Stopped
2021-04-19T18:21:02.688Z,1618856462.688 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:21:02.688Z,1618856462.688 [Default:CheckIn] Stopped
2021-04-19T18:21:02.688Z,1618856462.688 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:21:02.688Z,1618856462.688 [Default:CheckIn](INFO): Running loop #9
2021-04-19T18:21:02.689Z,1618856462.689 [Default:CheckIn] Running Loop=9
2021-04-19T18:21:02.689Z,1618856462.689 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:21:02.689Z,1618856462.689 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:21:04.703Z,1618856464.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182103.00,A,3648.16952,N,12147.28409,W,0.622,259.55,190421,,,A*74
2021-04-19T18:21:04.705Z,1618856464.705 [NAL9602](INFO): GPS fix at 20210419T182103: (36.802825, -121.788068)
2021-04-19T18:21:04.717Z,1618856464.717 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:21:04.717Z,1618856464.717 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:21:12.483Z,1618856472.483 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210419T175513/Courier0012.lzma
2021-04-19T18:21:13.485Z,1618856473.485 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0012.lzma.bak
2021-04-19T18:21:13.485Z,1618856473.485 [DataOverHttps](INFO): SBD MOMSN=15585994
2021-04-19T18:21:29.561Z,1618856489.561 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20210419T175513/Express0013.lzma
2021-04-19T18:21:30.557Z,1618856490.557 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0013.lzma.bak
2021-04-19T18:21:30.557Z,1618856490.557 [DataOverHttps](INFO): SBD MOMSN=15585997
2021-04-19T18:21:32.195Z,1618856492.195 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:21:32.195Z,1618856492.195 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:21:32.195Z,1618856492.195 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:21:37.420Z,1618856497.420 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T18:22:41.316Z,1618856561.316 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-04-19T18:22:41.316Z,1618856561.316 [DropWeight] Hardware Fault, FailCount= 1
2021-04-19T18:22:41.316Z,1618856561.316 [DropWeight](ERROR): Hardware Fault
2021-04-19T18:22:41.363Z,1618856561.363 [CommandLine](FAULT): Scheduling is paused
2021-04-19T18:22:41.364Z,1618856561.364 [CBIT](INFO): Critical error at 20210419T182241
2021-04-19T18:22:41.366Z,1618856561.366 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-04-19T18:22:41.367Z,1618856561.367 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-04-19T18:22:41.769Z,1618856561.769 [CBIT](INFO): Critical error at 20210419T182241
2021-04-19T18:26:32.903Z,1618856792.903 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:26:32.923Z,1618856792.923 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:26:32.923Z,1618856792.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:26:32.924Z,1618856792.924 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:26:33.307Z,1618856793.307 [Default:CheckIn:D] Stopped
2021-04-19T18:26:33.307Z,1618856793.307 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:26:33.788Z,1618856793.788 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.168449 min 
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn:E] Stopped
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn] Stopped
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn](INFO): Running loop #10
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn] Running Loop=10
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:26:33.789Z,1618856793.789 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:26:35.726Z,1618856795.726 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182635.00,A,3648.16848,N,12147.28383,W,0.058,259.55,190421,,,A*72
2021-04-19T18:26:35.733Z,1618856795.733 [NAL9602](INFO): GPS fix at 20210419T182635: (36.802808, -121.788064)
2021-04-19T18:26:35.821Z,1618856795.821 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:26:35.821Z,1618856795.821 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:26:43.545Z,1618856803.545 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20210419T175513/Courier0015.lzma
2021-04-19T18:26:44.541Z,1618856804.541 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0015.lzma.bak
2021-04-19T18:26:44.541Z,1618856804.541 [DataOverHttps](INFO): SBD MOMSN=15586018
2021-04-19T18:27:01.243Z,1618856821.243 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20210419T175513/Express0016.lzma
2021-04-19T18:27:02.245Z,1618856822.245 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0016.lzma.bak
2021-04-19T18:27:02.245Z,1618856822.245 [DataOverHttps](INFO): SBD MOMSN=15586023
2021-04-19T18:27:04.019Z,1618856824.019 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:27:04.020Z,1618856824.020 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:27:04.020Z,1618856824.020 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:27:06.424Z,1618856826.424 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-19T18:27:06.507Z,1618856826.507 [NAL9602](ERROR): received: 
+CSQ:0

OK616, 0, 0, 0, 0

OK
2021-04-19T18:29:28.817Z,1618856968.817 [NAL9602](INFO): SBD MO Status=2, MOMSN=14617, MT Status=2, MTMSN=0
2021-04-19T18:29:28.818Z,1618856968.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-19T18:31:38.512Z,1618857098.512 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-19T18:32:04.782Z,1618857124.782 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:32:04.782Z,1618857124.782 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:32:04.782Z,1618857124.782 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:32:04.783Z,1618857124.783 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:32:05.219Z,1618857125.219 [Default:CheckIn:D] Stopped
2021-04-19T18:32:05.219Z,1618857125.219 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:32:05.622Z,1618857125.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.700313 min 
2021-04-19T18:32:05.622Z,1618857125.622 [Default:CheckIn:E] Stopped
2021-04-19T18:32:05.622Z,1618857125.622 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:32:05.622Z,1618857125.622 [Default:CheckIn] Stopped
2021-04-19T18:32:05.622Z,1618857125.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:32:05.622Z,1618857125.622 [Default:CheckIn](INFO): Running loop #11
2021-04-19T18:32:05.623Z,1618857125.623 [Default:CheckIn] Running Loop=11
2021-04-19T18:32:05.623Z,1618857125.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:32:05.623Z,1618857125.623 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:32:07.600Z,1618857127.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183206.00,A,3648.16952,N,12147.28196,W,0.078,348.22,190421,,,A*78
2021-04-19T18:32:07.602Z,1618857127.602 [NAL9602](INFO): GPS fix at 20210419T183206: (36.802825, -121.788033)
2021-04-19T18:32:07.649Z,1618857127.649 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:32:07.649Z,1618857127.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:32:15.537Z,1618857135.537 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210419T175513/Courier0018.lzma
2021-04-19T18:32:16.533Z,1618857136.533 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0018.lzma.bak
2021-04-19T18:32:16.533Z,1618857136.533 [DataOverHttps](INFO): SBD MOMSN=15586039
2021-04-19T18:32:38.911Z,1618857158.911 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20210419T175513/Express0019.lzma
2021-04-19T18:32:39.252Z,1618857159.252 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T18:32:39.913Z,1618857159.913 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0019.lzma.bak
2021-04-19T18:32:39.913Z,1618857159.913 [DataOverHttps](INFO): SBD MOMSN=15586043
2021-04-19T18:32:41.714Z,1618857161.714 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:32:41.715Z,1618857161.715 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:32:41.715Z,1618857161.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:37:41.635Z,1618857461.635 [CBIT](INFO): Clearing failed state for component DropWeight
2021-04-19T18:37:41.635Z,1618857461.635 [DropWeight] No Fault, FailCount= 1
2021-04-19T18:37:42.389Z,1618857462.389 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:37:42.389Z,1618857462.389 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:37:42.389Z,1618857462.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:37:42.389Z,1618857462.389 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:37:42.793Z,1618857462.793 [Default:CheckIn:D] Stopped
2021-04-19T18:37:42.793Z,1618857462.793 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:37:43.204Z,1618857463.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.326546 min 
2021-04-19T18:37:43.204Z,1618857463.204 [Default:CheckIn:E] Stopped
2021-04-19T18:37:43.204Z,1618857463.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:37:43.204Z,1618857463.204 [Default:CheckIn] Stopped
2021-04-19T18:37:43.204Z,1618857463.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:37:43.204Z,1618857463.204 [Default:CheckIn](INFO): Running loop #12
2021-04-19T18:37:43.205Z,1618857463.205 [Default:CheckIn] Running Loop=12
2021-04-19T18:37:43.205Z,1618857463.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:37:43.205Z,1618857463.205 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:37:45.218Z,1618857465.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183744.00,A,3648.17104,N,12147.28277,W,0.078,232.99,190421,,,A*71
2021-04-19T18:37:45.220Z,1618857465.220 [NAL9602](INFO): GPS fix at 20210419T183744: (36.802851, -121.788046)
2021-04-19T18:37:45.232Z,1618857465.232 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:37:45.232Z,1618857465.232 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:37:53.219Z,1618857473.219 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210419T175513/Courier0021.lzma
2021-04-19T18:37:54.222Z,1618857474.222 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0021.lzma.bak
2021-04-19T18:37:54.222Z,1618857474.222 [DataOverHttps](INFO): SBD MOMSN=15586058
2021-04-19T18:38:10.367Z,1618857490.367 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210419T175513/Express0022.lzma
2021-04-19T18:38:11.369Z,1618857491.369 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0022.lzma.bak
2021-04-19T18:38:11.369Z,1618857491.369 [DataOverHttps](INFO): SBD MOMSN=15586064
2021-04-19T18:38:13.104Z,1618857493.104 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:38:13.104Z,1618857493.104 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:38:13.104Z,1618857493.104 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:38:15.913Z,1618857495.913 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-19T18:38:15.991Z,1618857495.991 [NAL9602](ERROR): received: 
+CSQ:1

OK617, 2, 0, 0, 0

OK
2021-04-19T18:40:50.270Z,1618857650.270 [NAL9602](INFO): SBD MO Status=2, MOMSN=14617, MT Status=2, MTMSN=0
2021-04-19T18:40:50.270Z,1618857650.270 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-19T18:42:47.484Z,1618857767.484 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-19T18:43:13.791Z,1618857793.791 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:43:13.791Z,1618857793.791 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:43:13.791Z,1618857793.791 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:43:13.792Z,1618857793.792 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:43:14.160Z,1618857794.160 [Default:CheckIn:D] Stopped
2021-04-19T18:43:14.160Z,1618857794.160 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:43:14.576Z,1618857794.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.849333 min 
2021-04-19T18:43:14.576Z,1618857794.576 [Default:CheckIn:E] Stopped
2021-04-19T18:43:14.576Z,1618857794.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:43:14.576Z,1618857794.576 [Default:CheckIn] Stopped
2021-04-19T18:43:14.576Z,1618857794.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:43:14.577Z,1618857794.577 [Default:CheckIn](INFO): Running loop #13
2021-04-19T18:43:14.577Z,1618857794.577 [Default:CheckIn] Running Loop=13
2021-04-19T18:43:14.577Z,1618857794.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:43:14.577Z,1618857794.577 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:43:16.575Z,1618857796.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184315.00,A,3648.16652,N,12147.28191,W,0.272,266.46,190421,,,A*73
2021-04-19T18:43:16.577Z,1618857796.577 [NAL9602](INFO): GPS fix at 20210419T184315: (36.802775, -121.788032)
2021-04-19T18:43:16.614Z,1618857796.614 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:43:16.614Z,1618857796.614 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:43:23.951Z,1618857803.951 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210419T175513/Courier0024.lzma
2021-04-19T18:43:24.953Z,1618857804.953 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0024.lzma.bak
2021-04-19T18:43:24.953Z,1618857804.953 [DataOverHttps](INFO): SBD MOMSN=15586100
2021-04-19T18:43:41.707Z,1618857821.707 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20210419T175513/Express0025.lzma
2021-04-19T18:43:42.709Z,1618857822.709 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0025.lzma.bak
2021-04-19T18:43:42.709Z,1618857822.709 [DataOverHttps](INFO): SBD MOMSN=15586103
2021-04-19T18:43:44.463Z,1618857824.464 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:43:44.464Z,1618857824.464 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:43:44.464Z,1618857824.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:43:48.484Z,1618857828.484 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-19T18:48:45.180Z,1618858125.180 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:48:45.180Z,1618858125.180 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:48:45.180Z,1618858125.180 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:48:45.180Z,1618858125.180 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:48:45.644Z,1618858125.644 [Default:CheckIn:D] Stopped
2021-04-19T18:48:45.644Z,1618858125.644 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.374064 min 
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn:E] Stopped
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn] Stopped
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn](INFO): Running loop #14
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn] Running Loop=14
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:48:45.972Z,1618858125.972 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:48:47.985Z,1618858127.985 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184847.00,A,3648.16756,N,12147.28751,W,1.225,315.31,190421,,,A*76
2021-04-19T18:48:47.988Z,1618858127.988 [NAL9602](INFO): GPS fix at 20210419T184847: (36.802793, -121.788125)
2021-04-19T18:48:47.999Z,1618858127.999 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:48:47.999Z,1618858127.999 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:48:55.711Z,1618858135.711 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210419T175513/Courier0027.lzma
2021-04-19T18:48:56.713Z,1618858136.713 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0027.lzma.bak
2021-04-19T18:48:56.713Z,1618858136.713 [DataOverHttps](INFO): SBD MOMSN=15586126
2021-04-19T18:49:12.767Z,1618858152.767 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210419T175513/Express0028.lzma
2021-04-19T18:49:14.360Z,1618858154.360 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Express0028.lzma.bak
2021-04-19T18:49:14.360Z,1618858154.360 [DataOverHttps](INFO): SBD MOMSN=15586129
2021-04-19T18:49:15.471Z,1618858155.471 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:49:15.471Z,1618858155.471 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-19T18:49:15.471Z,1618858155.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-19T18:49:18.681Z,1618858158.681 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-19T18:49:18.763Z,1618858158.763 [NAL9602](ERROR): received: 
+CSQ:0

OK617, 2, 0, 0, 0

OK
2021-04-19T18:50:58.165Z,1618858258.165 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.217480
2021-04-19T18:51:09.669Z,1618858269.669 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003816
2021-04-19T18:53:50.252Z,1618858430.252 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-19T18:54:16.122Z,1618858456.122 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-19T18:54:16.122Z,1618858456.122 [Default:CheckIn:C.Wait] Stopped
2021-04-19T18:54:16.123Z,1618858456.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-19T18:54:16.123Z,1618858456.123 [Default:CheckIn:D] Running Loop=1
2021-04-19T18:54:16.552Z,1618858456.552 [Default:CheckIn:D] Stopped
2021-04-19T18:54:16.552Z,1618858456.552 [Default:CheckIn:E] Running Loop=1
2021-04-19T18:54:16.956Z,1618858456.956 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.889193 min 
2021-04-19T18:54:16.956Z,1618858456.956 [Default:CheckIn:E] Stopped
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn] Stopped
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn](INFO): Running loop #15
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn] Running Loop=15
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-19T18:54:16.957Z,1618858456.957 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-19T18:54:18.934Z,1618858458.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185418.00,A,3648.15920,N,12147.28464,W,0.253,25.31,190421,,,A*48
2021-04-19T18:54:18.936Z,1618858458.936 [NAL9602](INFO): GPS fix at 20210419T185418: (36.802653, -121.788077)
2021-04-19T18:54:18.975Z,1618858458.975 [Default:CheckIn:Read_GPS] Stopped
2021-04-19T18:54:18.975Z,1618858458.975 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-19T18:54:30.667Z,1618858470.667 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210419T175513/Courier0030.lzma
2021-04-19T18:54:31.685Z,1618858471.685 [DataOverHttps](INFO): Moved sent file to Logs/20210419T175513/Courier0030.lzma.bak
2021-04-19T18:54:31.685Z,1618858471.685 [DataOverHttps](INFO): SBD MOMSN=15586139
2021-04-19T18:54:35.652Z,1618858475.652 [CommandLine](IMPORTANT): got command quit
2021-04-19T18:54:36.663Z,1618858476.663 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:36.664Z,1618858476.664 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:36.707Z,1618858476.707 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-19T18:54:36.707Z,1618858476.707 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:36.708Z,1618858476.708 [CommandLine](INFO): Join timeout helper Thread ID is 8994
2021-04-19T18:54:36.723Z,1618858476.723 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-19T18:54:36.723Z,1618858476.723 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:36.724Z,1618858476.724 [NavChartDb](INFO): Join timeout helper Thread ID is 8995
2021-04-19T18:54:37.007Z,1618858477.007 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:37.008Z,1618858477.008 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.023Z,1618858477.023 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2021-04-19T18:54:37.023Z,1618858477.023 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.024Z,1618858477.024 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8996
2021-04-19T18:54:37.304Z,1618858477.304 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:37.304Z,1618858477.304 [WetLabsUBAT](INFO): Powering down
2021-04-19T18:54:37.304Z,1618858477.304 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.311Z,1618858477.311 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-04-19T18:54:37.311Z,1618858477.311 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.312Z,1618858477.312 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8997
2021-04-19T18:54:37.667Z,1618858477.667 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:37.668Z,1618858477.668 [WetLabsBB2FL](INFO): Powering down
2021-04-19T18:54:37.668Z,1618858477.668 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.671Z,1618858477.671 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-19T18:54:37.671Z,1618858477.671 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.672Z,1618858477.672 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8998
2021-04-19T18:54:37.775Z,1618858477.775 [CTD_Seabird](INFO): Powering down
2021-04-19T18:54:37.791Z,1618858477.791 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:37.792Z,1618858477.792 [CTD_Seabird](INFO): Powering down
2021-04-19T18:54:37.803Z,1618858477.803 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.812Z,1618858477.812 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-19T18:54:37.812Z,1618858477.812 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:37.813Z,1618858477.813 [Radio_Surface](INFO): Join timeout helper Thread ID is 8999
2021-04-19T18:54:38.112Z,1618858478.112 [Radio_Surface](INFO): Powering down
2021-04-19T18:54:38.113Z,1618858478.113 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:38.113Z,1618858478.113 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:38.132Z,1618858478.132 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-19T18:54:38.132Z,1618858478.132 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:38.133Z,1618858478.133 [Onboard](INFO): Join timeout helper Thread ID is 9000
2021-04-19T18:54:38.187Z,1618858478.187 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:38.188Z,1618858478.188 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:38.196Z,1618858478.196 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-19T18:54:38.196Z,1618858478.196 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:38.196Z,1618858478.196 [DataOverHttps](INFO): Join timeout helper Thread ID is 9001
2021-04-19T18:54:41.450Z,1618858481.450 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:41.453Z,1618858481.453 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.460Z,1618858481.460 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-04-19T18:54:41.460Z,1618858481.460 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.460Z,1618858481.460 [BackseatComponent](INFO): Join timeout helper Thread ID is 9002
2021-04-19T18:54:41.588Z,1618858481.588 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:41.589Z,1618858481.589 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.600Z,1618858481.600 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-19T18:54:41.600Z,1618858481.600 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.600Z,1618858481.600 [logger](INFO): Join timeout helper Thread ID is 9003
2021-04-19T18:54:41.643Z,1618858481.643 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:41.644Z,1618858481.644 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.660Z,1618858481.660 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-19T18:54:41.660Z,1618858481.660 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.660Z,1618858481.660 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-19T18:54:41.660Z,1618858481.660 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:41.661Z,1618858481.661 [controlThread](INFO): Join timeout helper Thread ID is 9004
2021-04-19T18:54:41.691Z,1618858481.691 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-19T18:54:41.692Z,1618858481.692 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-19T18:54:41.692Z,1618858481.692 [AHRS_M2](INFO): Powering down
2021-04-19T18:54:41.764Z,1618858481.764 [NAL9602](INFO): Powering down
2021-04-19T18:54:41.835Z,1618858481.835 [RDI_Pathfinder](INFO): Powering down
2021-04-19T18:54:41.836Z,1618858481.836 [DAT](INFO): Powering down
2021-04-19T18:54:41.956Z,1618858481.956 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-19T18:54:41.957Z,1618858481.957 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-19T18:54:41.958Z,1618858481.958 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-19T18:54:41.958Z,1618858481.958 [MissionManager](INFO): Uninitializing Mission Default
2021-04-19T18:54:41.958Z,1618858481.958 [Default] Stopped
2021-04-19T18:54:41.958Z,1618858481.958 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-19T18:54:41.958Z,1618858481.958 [Default:B.GoToSurface] Stopped
2021-04-19T18:54:41.958Z,1618858481.958 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-19T18:54:41.958Z,1618858481.958 [Default:CheckIn] Stopped
2021-04-19T18:54:41.959Z,1618858481.959 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-19T18:54:41.959Z,1618858481.959 [Default:CheckIn:Read_Iridium] Stopped
2021-04-19T18:54:41.961Z,1618858481.961 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-19T18:54:41.962Z,1618858481.962 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-19T18:54:41.962Z,1618858481.962 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-19T18:54:41.962Z,1618858481.962 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-19T18:54:41.963Z,1618858481.963 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-19T18:54:41.963Z,1618858481.963 [BuoyancyServo](INFO): Powering down
2021-04-19T18:54:41.975Z,1618858481.975 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-19T18:54:41.975Z,1618858481.975 [ElevatorServo](INFO): Powering down
2021-04-19T18:54:41.976Z,1618858481.976 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-19T18:54:41.976Z,1618858481.976 [MassServo](INFO): Powering down
2021-04-19T18:54:41.977Z,1618858481.977 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-19T18:54:41.977Z,1618858481.977 [RudderServo](INFO): Powering down
2021-04-19T18:54:41.978Z,1618858481.978 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-19T18:54:41.978Z,1618858481.978 [ThrusterServo](INFO): Powering down
2021-04-19T18:54:41.979Z,1618858481.979 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-19T18:54:41.979Z,1618858481.979 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-19T18:54:41.980Z,1618858481.980 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-19T18:54:41.980Z,1618858481.980 [CBIT](DEBUG): Powering off loads.
2021-04-19T18:54:41.991Z,1618858481.991 [CBIT](DEBUG): Disabling WDT.
2021-04-19T18:54:42.003Z,1618858482.003 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-19T18:54:42.004Z,1618858482.004 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.073Z,1618858482.073 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.075Z,1618858482.075 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.081Z,1618858482.081 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.123Z,1618858482.123 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.188Z,1618858482.188 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.189Z,1618858482.189 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.194Z,1618858482.194 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.202Z,1618858482.202 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-19T18:54:42.307Z,1618858482.307 [logger ThreadHandler](INFO): Thread cancelled.