2019-12-12T16:22:18.214Z,1576167738.214 [CommandLine](IMPORTANT): got command restart logs
2019-12-12T16:23:04.148Z,1576167784.148 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:23:04.148Z,1576167784.148 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:23:04.148Z,1576167784.148 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:23:04.148Z,1576167784.148 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:23:04.554Z,1576167784.554 [Default:CheckIn:D] Stopped
2019-12-12T16:23:04.554Z,1576167784.554 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.686894 min 
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn:E] Stopped
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn] Stopped
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn](INFO): Running loop #3
2019-12-12T16:23:04.955Z,1576167784.955 [Default:CheckIn] Running Loop=3
2019-12-12T16:23:04.956Z,1576167784.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:23:04.956Z,1576167784.956 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:23:06.954Z,1576167786.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161931.00,A,3647.33383,N,12153.74128,W,24.823,78.84,121219,,,A*77
2019-12-12T16:23:06.956Z,1576167786.956 [NAL9602](INFO): GPS fix at 20191212T161931: (36.788897, -121.895688)
2019-12-12T16:23:07.009Z,1576167787.009 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:23:07.009Z,1576167787.009 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:23:14.604Z,1576167794.604 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20191212T162218/Courier0000.lzma
2019-12-12T16:23:16.610Z,1576167796.610 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0000.lzma.bak
2019-12-12T16:23:16.610Z,1576167796.610 [DataOverHttps](INFO): SBD MOMSN=12151104
2019-12-12T16:23:30.395Z,1576167810.395 [NAL9602](INFO): SBD MO Status=0, MOMSN=36562, MT Status=0, MTMSN=0
2019-12-12T16:23:30.395Z,1576167810.395 [NAL9602](INFO): No messages in MT queue
2019-12-12T16:23:33.516Z,1576167813.516 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20191212T144701/Express0029.lzma
2019-12-12T16:23:35.522Z,1576167815.522 [DataOverHttps](INFO): Moved sent file to Logs/20191212T144701/Express0029.lzma.bak
2019-12-12T16:23:35.522Z,1576167815.522 [DataOverHttps](INFO): SBD MOMSN=12151109
2019-12-12T16:23:52.749Z,1576167832.749 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20191212T162218/Express0001.lzma
2019-12-12T16:23:54.754Z,1576167834.754 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0001.lzma.bak
2019-12-12T16:23:54.754Z,1576167834.754 [DataOverHttps](INFO): SBD MOMSN=12151112
2019-12-12T16:24:01.086Z,1576167841.086 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T16:24:11.712Z,1576167851.712 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191212T162218/Express0004.lzma
2019-12-12T16:24:13.718Z,1576167853.718 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0004.lzma.bak
2019-12-12T16:24:13.718Z,1576167853.718 [DataOverHttps](INFO): SBD MOMSN=12151116
2019-12-12T16:24:15.268Z,1576167855.268 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:24:15.268Z,1576167855.268 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:24:15.268Z,1576167855.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:29:15.819Z,1576168155.819 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:29:15.819Z,1576168155.819 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:29:15.819Z,1576168155.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:29:15.820Z,1576168155.820 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:29:16.232Z,1576168156.232 [Default:CheckIn:D] Stopped
2019-12-12T16:29:16.232Z,1576168156.232 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:29:16.633Z,1576168156.633 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.881535 min 
2019-12-12T16:29:16.633Z,1576168156.633 [Default:CheckIn:E] Stopped
2019-12-12T16:29:16.633Z,1576168156.633 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:29:16.633Z,1576168156.633 [Default:CheckIn] Stopped
2019-12-12T16:29:16.633Z,1576168156.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:29:16.634Z,1576168156.634 [Default:CheckIn](INFO): Running loop #4
2019-12-12T16:29:16.634Z,1576168156.634 [Default:CheckIn] Running Loop=4
2019-12-12T16:29:16.634Z,1576168156.634 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:29:16.634Z,1576168156.634 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:29:18.631Z,1576168158.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162542.00,A,3647.88805,N,12149.94836,W,30.771,79.77,121219,,,A*7A
2019-12-12T16:29:18.634Z,1576168158.634 [NAL9602](INFO): GPS fix at 20191212T162542: (36.798134, -121.832473)
2019-12-12T16:29:18.681Z,1576168158.681 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:29:18.681Z,1576168158.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:29:26.708Z,1576168166.708 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191212T162218/Courier0006.lzma
2019-12-12T16:29:28.714Z,1576168168.714 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0006.lzma.bak
2019-12-12T16:29:28.714Z,1576168168.714 [DataOverHttps](INFO): SBD MOMSN=12151185
2019-12-12T16:29:31.562Z,1576168171.562 [NAL9602](INFO): SBD MO Status=0, MOMSN=36563, MT Status=0, MTMSN=0
2019-12-12T16:29:31.562Z,1576168171.562 [NAL9602](INFO): No messages in MT queue
2019-12-12T16:29:45.616Z,1576168185.616 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20191212T162218/Express0007.lzma
2019-12-12T16:29:47.622Z,1576168187.622 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0007.lzma.bak
2019-12-12T16:29:47.622Z,1576168187.622 [DataOverHttps](INFO): SBD MOMSN=12151192
2019-12-12T16:29:49.027Z,1576168189.027 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:29:49.328Z,1576168189.328 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:29:49.328Z,1576168189.328 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:30:02.284Z,1576168202.284 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T16:34:49.590Z,1576168489.590 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:34:49.590Z,1576168489.590 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:34:49.590Z,1576168489.590 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:34:49.591Z,1576168489.591 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:34:49.988Z,1576168489.988 [Default:CheckIn:D] Stopped
2019-12-12T16:34:49.988Z,1576168489.988 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:34:50.385Z,1576168490.385 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.444139 min 
2019-12-12T16:34:50.385Z,1576168490.385 [Default:CheckIn:E] Stopped
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn] Stopped
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn](INFO): Running loop #5
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn] Running Loop=5
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:34:50.386Z,1576168490.386 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:34:52.390Z,1576168492.390 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163115.00,A,3648.51611,N,12147.19137,W,6.162,81.97,121219,,,D*43
2019-12-12T16:34:52.392Z,1576168492.392 [NAL9602](INFO): GPS fix at 20191212T163115: (36.808602, -121.786523)
2019-12-12T16:34:52.435Z,1576168492.435 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:34:52.435Z,1576168492.435 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:34:59.384Z,1576168499.384 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191212T162218/Courier0009.lzma
2019-12-12T16:35:01.390Z,1576168501.390 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0009.lzma.bak
2019-12-12T16:35:01.390Z,1576168501.390 [DataOverHttps](INFO): SBD MOMSN=12151197
2019-12-12T16:35:21.102Z,1576168521.102 [NAL9602](INFO): SBD MO Status=0, MOMSN=36564, MT Status=0, MTMSN=0
2019-12-12T16:35:21.102Z,1576168521.102 [NAL9602](INFO): No messages in MT queue
2019-12-12T16:35:21.228Z,1576168521.228 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20191212T162218/Express0010.lzma
2019-12-12T16:35:23.234Z,1576168523.234 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0010.lzma.bak
2019-12-12T16:35:23.235Z,1576168523.235 [DataOverHttps](INFO): SBD MOMSN=12151201
2019-12-12T16:35:24.401Z,1576168524.401 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:35:24.402Z,1576168524.402 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:35:24.402Z,1576168524.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:35:51.815Z,1576168551.815 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T16:40:24.963Z,1576168824.963 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:40:24.963Z,1576168824.963 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:40:24.963Z,1576168824.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:40:24.964Z,1576168824.964 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:40:25.365Z,1576168825.365 [Default:CheckIn:D] Stopped
2019-12-12T16:40:25.366Z,1576168825.366 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:40:25.777Z,1576168825.777 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.033759 min 
2019-12-12T16:40:25.777Z,1576168825.777 [Default:CheckIn:E] Stopped
2019-12-12T16:40:25.777Z,1576168825.777 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:40:25.777Z,1576168825.777 [Default:CheckIn] Stopped
2019-12-12T16:40:25.778Z,1576168825.778 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:40:25.778Z,1576168825.778 [Default:CheckIn](INFO): Running loop #6
2019-12-12T16:40:25.778Z,1576168825.778 [Default:CheckIn] Running Loop=6
2019-12-12T16:40:25.778Z,1576168825.778 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:40:25.778Z,1576168825.778 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:40:27.770Z,1576168827.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163650.00,A,3648.16551,N,12147.21623,W,0.039,235.71,121219,,,D*74
2019-12-12T16:40:27.772Z,1576168827.772 [NAL9602](INFO): GPS fix at 20191212T163650: (36.802759, -121.786937)
2019-12-12T16:40:27.815Z,1576168827.815 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:40:27.815Z,1576168827.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:40:35.656Z,1576168835.656 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191212T162218/Courier0012.lzma
2019-12-12T16:40:37.662Z,1576168837.662 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0012.lzma.bak
2019-12-12T16:40:37.662Z,1576168837.662 [DataOverHttps](INFO): SBD MOMSN=12151217
2019-12-12T16:40:47.989Z,1576168847.989 [NAL9602](INFO): SBD MO Status=0, MOMSN=36565, MT Status=0, MTMSN=0
2019-12-12T16:40:47.989Z,1576168847.989 [NAL9602](INFO): No messages in MT queue
2019-12-12T16:40:54.556Z,1576168854.556 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20191212T162218/Express0013.lzma
2019-12-12T16:40:56.562Z,1576168856.562 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0013.lzma.bak
2019-12-12T16:40:56.562Z,1576168856.562 [DataOverHttps](INFO): SBD MOMSN=12151220
2019-12-12T16:40:57.710Z,1576168857.710 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:40:57.711Z,1576168857.711 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:40:57.711Z,1576168857.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:41:18.688Z,1576168878.688 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T16:45:58.283Z,1576169158.283 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:45:58.283Z,1576169158.283 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:45:58.283Z,1576169158.283 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:45:58.283Z,1576169158.283 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:45:58.700Z,1576169158.700 [Default:CheckIn:D] Stopped
2019-12-12T16:45:58.700Z,1576169158.700 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:45:59.098Z,1576169159.098 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.589331 min 
2019-12-12T16:45:59.098Z,1576169159.098 [Default:CheckIn:E] Stopped
2019-12-12T16:45:59.098Z,1576169159.098 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:45:59.098Z,1576169159.098 [Default:CheckIn] Stopped
2019-12-12T16:45:59.099Z,1576169159.099 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:45:59.099Z,1576169159.099 [Default:CheckIn](INFO): Running loop #7
2019-12-12T16:45:59.099Z,1576169159.099 [Default:CheckIn] Running Loop=7
2019-12-12T16:45:59.099Z,1576169159.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:45:59.099Z,1576169159.099 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:46:01.090Z,1576169161.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164223.00,A,3648.16174,N,12147.21729,W,0.019,221.47,121219,,,D*79
2019-12-12T16:46:01.092Z,1576169161.092 [NAL9602](INFO): GPS fix at 20191212T164223: (36.802696, -121.786955)
2019-12-12T16:46:01.135Z,1576169161.135 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:46:01.135Z,1576169161.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:46:09.144Z,1576169169.144 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191212T162218/Courier0015.lzma
2019-12-12T16:46:11.150Z,1576169171.150 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0015.lzma.bak
2019-12-12T16:46:11.150Z,1576169171.150 [DataOverHttps](INFO): SBD MOMSN=12151259
2019-12-12T16:46:14.439Z,1576169174.439 [NAL9602](INFO): SBD MO Status=0, MOMSN=36566, MT Status=0, MTMSN=0
2019-12-12T16:46:14.439Z,1576169174.439 [NAL9602](INFO): No messages in MT queue
2019-12-12T16:46:27.916Z,1576169187.916 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191212T162218/Express0016.lzma
2019-12-12T16:46:29.933Z,1576169189.933 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0016.lzma.bak
2019-12-12T16:46:29.933Z,1576169189.933 [DataOverHttps](INFO): SBD MOMSN=12151262
2019-12-12T16:46:31.432Z,1576169191.432 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:46:31.432Z,1576169191.432 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:46:31.432Z,1576169191.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:46:45.141Z,1576169205.141 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T16:51:32.025Z,1576169492.025 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:51:32.025Z,1576169492.025 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:51:32.025Z,1576169492.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:51:32.025Z,1576169492.025 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:51:32.423Z,1576169492.423 [Default:CheckIn:D] Stopped
2019-12-12T16:51:32.423Z,1576169492.423 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:51:32.831Z,1576169492.831 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.151375 min 
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn:E] Stopped
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn] Stopped
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn](INFO): Running loop #8
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn] Running Loop=8
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:51:32.832Z,1576169492.832 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:51:34.808Z,1576169494.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164756.00,A,3648.18226,N,12147.28627,W,0.194,296.15,121219,,,A*78
2019-12-12T16:51:34.810Z,1576169494.810 [NAL9602](INFO): GPS fix at 20191212T164756: (36.803038, -121.788105)
2019-12-12T16:51:34.833Z,1576169494.833 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:51:34.833Z,1576169494.833 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:51:42.012Z,1576169502.012 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191212T162218/Courier0018.lzma
2019-12-12T16:51:44.018Z,1576169504.018 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0018.lzma.bak
2019-12-12T16:51:44.019Z,1576169504.019 [DataOverHttps](INFO): SBD MOMSN=12151270
2019-12-12T16:52:05.880Z,1576169525.880 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20191212T162218/Express0019.lzma
2019-12-12T16:52:07.886Z,1576169527.886 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0019.lzma.bak
2019-12-12T16:52:07.886Z,1576169527.886 [DataOverHttps](INFO): SBD MOMSN=12151273
2019-12-12T16:52:09.179Z,1576169529.179 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:52:09.179Z,1576169529.179 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:52:09.180Z,1576169529.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:52:16.019Z,1576169536.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=36567, MT Status=2, MTMSN=0
2019-12-12T16:52:16.019Z,1576169536.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-12-12T16:53:17.833Z,1576169597.833 [NAL9602](INFO): SBD MO Status=2, MOMSN=36567, MT Status=2, MTMSN=0
2019-12-12T16:53:17.833Z,1576169597.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-12-12T16:53:53.786Z,1576169633.786 [NAL9602](INFO): SBD MO Status=2, MOMSN=36567, MT Status=2, MTMSN=0
2019-12-12T16:53:53.786Z,1576169633.786 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-12-12T16:55:22.262Z,1576169722.262 [NAL9602](INFO): SBD MO Status=2, MOMSN=36567, MT Status=2, MTMSN=0
2019-12-12T16:55:22.262Z,1576169722.262 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-12-12T16:56:36.596Z,1576169796.596 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-12-12T16:57:07.301Z,1576169827.301 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T16:57:09.752Z,1576169829.752 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T16:57:09.752Z,1576169829.752 [Default:CheckIn:C.Wait] Stopped
2019-12-12T16:57:09.752Z,1576169829.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T16:57:09.752Z,1576169829.752 [Default:CheckIn:D] Running Loop=1
2019-12-12T16:57:10.160Z,1576169830.160 [Default:CheckIn:D] Stopped
2019-12-12T16:57:10.160Z,1576169830.160 [Default:CheckIn:E] Running Loop=1
2019-12-12T16:57:10.563Z,1576169830.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.780330 min 
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn:E] Stopped
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn] Stopped
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn](INFO): Running loop #9
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn] Running Loop=9
2019-12-12T16:57:10.564Z,1576169830.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T16:57:10.565Z,1576169830.565 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T16:57:12.550Z,1576169832.550 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165334.00,A,3648.16626,N,12147.27944,W,0.816,179.36,121219,,,A*76
2019-12-12T16:57:12.552Z,1576169832.552 [NAL9602](INFO): GPS fix at 20191212T165334: (36.802771, -121.787991)
2019-12-12T16:57:12.576Z,1576169832.576 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T16:57:12.576Z,1576169832.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T16:57:20.500Z,1576169840.500 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191212T162218/Courier0021.lzma
2019-12-12T16:57:22.506Z,1576169842.506 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0021.lzma.bak
2019-12-12T16:57:22.506Z,1576169842.506 [DataOverHttps](INFO): SBD MOMSN=12151285
2019-12-12T16:57:39.333Z,1576169859.333 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20191212T162218/Express0022.lzma
2019-12-12T16:57:41.338Z,1576169861.338 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0022.lzma.bak
2019-12-12T16:57:41.338Z,1576169861.338 [DataOverHttps](INFO): SBD MOMSN=12151288
2019-12-12T16:57:42.876Z,1576169862.876 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T16:57:42.876Z,1576169862.876 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T16:57:42.876Z,1576169862.876 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T16:57:43.254Z,1576169863.254 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-12-12T16:57:43.337Z,1576169863.337 [NAL9602](FAULT): received: 
+CSQ:0

OK567, 2, 0, 0, 0

OK
2019-12-12T16:57:43.337Z,1576169863.337 [NAL9602] Data Fault, FailCount= 1
2019-12-12T16:57:43.337Z,1576169863.337 [NAL9602](ERROR): Data Fault
2019-12-12T16:57:43.394Z,1576169863.394 [CBIT](ERROR): Data Fault in component: NAL9602
2019-12-12T16:57:43.661Z,1576169863.661 [NAL9602](INFO): Powering down
2019-12-12T16:57:44.536Z,1576169864.536 [CBIT](INFO): Clearing failed state for component NAL9602
2019-12-12T16:57:44.536Z,1576169864.536 [NAL9602] No Fault, FailCount= 1
2019-12-12T16:58:13.962Z,1576169893.962 [NAL9602](INFO): Powering up NAL9602
2019-12-12T16:58:24.884Z,1576169904.884 [NAL9602](INFO): NAL9602 initialized
2019-12-12T16:58:55.993Z,1576169935.993 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T17:02:43.524Z,1576170163.524 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:02:43.524Z,1576170163.524 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:02:43.524Z,1576170163.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:02:43.524Z,1576170163.524 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:02:43.912Z,1576170163.912 [Default:CheckIn:D] Stopped
2019-12-12T17:02:43.912Z,1576170163.912 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:02:44.324Z,1576170164.324 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.342871 min 
2019-12-12T17:02:44.324Z,1576170164.324 [Default:CheckIn:E] Stopped
2019-12-12T17:02:44.324Z,1576170164.324 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:02:44.324Z,1576170164.324 [Default:CheckIn] Stopped
2019-12-12T17:02:44.324Z,1576170164.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:02:44.325Z,1576170164.325 [Default:CheckIn](INFO): Running loop #10
2019-12-12T17:02:44.325Z,1576170164.325 [Default:CheckIn] Running Loop=10
2019-12-12T17:02:44.325Z,1576170164.325 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:02:44.325Z,1576170164.325 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:02:48.333Z,1576170168.333 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-12-12T17:07:40.130Z,1576170460.130 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-12-12T17:07:40.498Z,1576170460.498 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170401.00,A,3648.17581,N,12147.28766,W,0.117,321.39,121219,,,A*75
2019-12-12T17:07:40.500Z,1576170460.500 [NAL9602](INFO): GPS fix at 20191212T170401: (36.802930, -121.788128)
2019-12-12T17:07:40.541Z,1576170460.541 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:07:40.542Z,1576170460.542 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T17:07:47.796Z,1576170467.796 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191212T162218/Courier0024.lzma
2019-12-12T17:07:49.802Z,1576170469.802 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0024.lzma.bak
2019-12-12T17:07:49.803Z,1576170469.803 [DataOverHttps](INFO): SBD MOMSN=12151298
2019-12-12T17:08:08.761Z,1576170488.761 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20191212T162218/Express0025.lzma
2019-12-12T17:08:10.762Z,1576170490.762 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0025.lzma.bak
2019-12-12T17:08:10.762Z,1576170490.762 [DataOverHttps](INFO): SBD MOMSN=12151301
2019-12-12T17:08:12.046Z,1576170492.046 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T17:08:12.046Z,1576170492.046 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T17:08:12.046Z,1576170492.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T17:08:13.220Z,1576170493.220 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T17:10:14.082Z,1576170614.082 [BPC1](FAULT): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2019-12-12T17:10:14.082Z,1576170614.082 [BPC1](FAULT): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-12-12T17:10:14.083Z,1576170614.083 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-12-12T17:10:14.085Z,1576170614.085 [BPC1](INFO): Received data from all battery sticks.
2019-12-12T17:13:12.641Z,1576170792.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:13:12.641Z,1576170792.641 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:13:12.641Z,1576170792.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:13:12.641Z,1576170792.641 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:13:13.050Z,1576170793.050 [Default:CheckIn:D] Stopped
2019-12-12T17:13:13.050Z,1576170793.050 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:13:13.454Z,1576170793.454 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.828495 min 
2019-12-12T17:13:13.454Z,1576170793.454 [Default:CheckIn:E] Stopped
2019-12-12T17:13:13.454Z,1576170793.454 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:13:13.454Z,1576170793.454 [Default:CheckIn] Stopped
2019-12-12T17:13:13.454Z,1576170793.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:13:13.455Z,1576170793.455 [Default:CheckIn](INFO): Running loop #11
2019-12-12T17:13:13.455Z,1576170793.455 [Default:CheckIn] Running Loop=11
2019-12-12T17:13:13.455Z,1576170793.455 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:13:13.455Z,1576170793.455 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:13:15.447Z,1576170795.447 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170935.00,A,3648.16796,N,12147.28310,W,0.505,278.40,121219,,,A*7B
2019-12-12T17:13:15.449Z,1576170795.449 [NAL9602](INFO): GPS fix at 20191212T170935: (36.802799, -121.788052)
2019-12-12T17:13:15.500Z,1576170795.500 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:13:15.500Z,1576170795.500 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T17:13:23.036Z,1576170803.036 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20191212T162218/Courier0027.lzma
2019-12-12T17:13:25.042Z,1576170805.042 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0027.lzma.bak
2019-12-12T17:13:25.042Z,1576170805.042 [DataOverHttps](INFO): SBD MOMSN=12151316
2019-12-12T17:13:42.033Z,1576170822.033 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20191212T162218/Express0028.lzma
2019-12-12T17:13:44.038Z,1576170824.038 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0028.lzma.bak
2019-12-12T17:13:44.038Z,1576170824.038 [DataOverHttps](INFO): SBD MOMSN=12151336
2019-12-12T17:13:45.369Z,1576170825.369 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T17:13:45.369Z,1576170825.369 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T17:13:45.369Z,1576170825.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T17:18:17.675Z,1576171097.675 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-12-12T17:18:45.984Z,1576171125.984 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:18:45.984Z,1576171125.984 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:18:45.984Z,1576171125.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:18:45.984Z,1576171125.984 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:18:46.383Z,1576171126.383 [Default:CheckIn:D] Stopped
2019-12-12T17:18:46.383Z,1576171126.383 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:18:46.774Z,1576171126.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.384058 min 
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn:E] Stopped
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn] Stopped
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn](INFO): Running loop #12
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn] Running Loop=12
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:18:46.775Z,1576171126.775 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:18:48.779Z,1576171128.779 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171508.00,A,3648.16738,N,12147.28484,W,0.933,101.30,121219,,,A*75
2019-12-12T17:18:48.781Z,1576171128.781 [NAL9602](INFO): GPS fix at 20191212T171508: (36.802790, -121.788081)
2019-12-12T17:18:48.804Z,1576171128.804 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:18:48.804Z,1576171128.804 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T17:18:56.016Z,1576171136.016 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191212T162218/Courier0030.lzma
2019-12-12T17:18:58.022Z,1576171138.022 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0030.lzma.bak
2019-12-12T17:18:58.022Z,1576171138.022 [DataOverHttps](INFO): SBD MOMSN=12151352
2019-12-12T17:19:14.901Z,1576171154.901 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20191212T162218/Express0031.lzma
2019-12-12T17:19:16.909Z,1576171156.909 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0031.lzma.bak
2019-12-12T17:19:16.909Z,1576171156.909 [DataOverHttps](INFO): SBD MOMSN=12151355
2019-12-12T17:19:18.303Z,1576171158.303 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T17:19:18.303Z,1576171158.303 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T17:19:18.303Z,1576171158.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T17:19:21.103Z,1576171161.103 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T17:24:18.883Z,1576171458.883 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:24:18.883Z,1576171458.883 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:24:18.883Z,1576171458.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:24:18.883Z,1576171458.883 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:24:19.305Z,1576171459.305 [Default:CheckIn:D] Stopped
2019-12-12T17:24:19.305Z,1576171459.305 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:24:19.679Z,1576171459.679 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.932756 min 
2019-12-12T17:24:19.679Z,1576171459.679 [Default:CheckIn:E] Stopped
2019-12-12T17:24:19.679Z,1576171459.679 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:24:19.679Z,1576171459.679 [Default:CheckIn] Stopped
2019-12-12T17:24:19.679Z,1576171459.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:24:19.680Z,1576171459.680 [Default:CheckIn](INFO): Running loop #13
2019-12-12T17:24:19.680Z,1576171459.680 [Default:CheckIn] Running Loop=13
2019-12-12T17:24:19.680Z,1576171459.680 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:24:19.680Z,1576171459.680 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:24:21.682Z,1576171461.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172040.00,A,3648.16532,N,12147.28047,W,0.156,101.30,121219,,,A*77
2019-12-12T17:24:21.684Z,1576171461.684 [NAL9602](INFO): GPS fix at 20191212T172040: (36.802755, -121.788008)
2019-12-12T17:24:21.723Z,1576171461.723 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:24:21.723Z,1576171461.723 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T17:24:29.068Z,1576171469.068 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191212T162218/Courier0033.lzma
2019-12-12T17:24:31.074Z,1576171471.074 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Courier0033.lzma.bak
2019-12-12T17:24:31.074Z,1576171471.074 [DataOverHttps](INFO): SBD MOMSN=12151393
2019-12-12T17:24:47.984Z,1576171487.984 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191212T162218/Express0034.lzma
2019-12-12T17:24:49.986Z,1576171489.986 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0034.lzma.bak
2019-12-12T17:24:49.987Z,1576171489.987 [DataOverHttps](INFO): SBD MOMSN=12151396
2019-12-12T17:24:51.201Z,1576171491.201 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T17:24:51.201Z,1576171491.201 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T17:24:51.201Z,1576171491.201 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T17:24:52.386Z,1576171492.386 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-12-12T17:24:52.461Z,1576171492.461 [NAL9602](FAULT): received: 
+CSQ:0

OK567, 2, 0, 0, 0

OK
2019-12-12T17:24:52.461Z,1576171492.461 [NAL9602] Data Fault, FailCount= 1
2019-12-12T17:24:52.461Z,1576171492.461 [NAL9602](ERROR): Data Fault
2019-12-12T17:24:52.521Z,1576171492.521 [CBIT](ERROR): Data Fault in component: NAL9602
2019-12-12T17:24:52.793Z,1576171492.793 [NAL9602](INFO): Powering down
2019-12-12T17:24:53.636Z,1576171493.636 [CBIT](INFO): Clearing failed state for component NAL9602
2019-12-12T17:24:53.636Z,1576171493.636 [NAL9602] No Fault, FailCount= 1
2019-12-12T17:25:23.095Z,1576171523.095 [NAL9602](INFO): Powering up NAL9602
2019-12-12T17:25:34.014Z,1576171534.014 [NAL9602](INFO): NAL9602 initialized
2019-12-12T17:26:05.104Z,1576171565.104 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T17:29:51.787Z,1576171791.787 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:29:51.787Z,1576171791.787 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:29:51.787Z,1576171791.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:29:51.787Z,1576171791.787 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:29:52.191Z,1576171792.191 [Default:CheckIn:D] Stopped
2019-12-12T17:29:52.191Z,1576171792.191 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:29:52.592Z,1576171792.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.480859 min 
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn:E] Stopped
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn] Stopped
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn](INFO): Running loop #14
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn] Running Loop=14
2019-12-12T17:29:52.593Z,1576171792.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:29:52.594Z,1576171792.594 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:29:56.605Z,1576171796.605 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-12-12T17:34:52.769Z,1576172092.769 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-12-12T17:29:52.6Z
2019-12-12T17:34:52.769Z,1576172092.769 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:34:52.769Z,1576172092.769 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T17:35:00.497Z,1576172100.497 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20191212T162218/Express0037.lzma
2019-12-12T17:35:02.494Z,1576172102.494 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0037.lzma.bak
2019-12-12T17:35:02.494Z,1576172102.494 [DataOverHttps](INFO): SBD MOMSN=12151417
2019-12-12T17:35:03.703Z,1576172103.703 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T17:35:03.703Z,1576172103.703 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T17:35:03.704Z,1576172103.704 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T17:35:24.691Z,1576172124.691 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T17:40:04.276Z,1576172404.276 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:40:04.276Z,1576172404.276 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:40:04.276Z,1576172404.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:40:04.276Z,1576172404.276 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:40:04.683Z,1576172404.683 [Default:CheckIn:D] Stopped
2019-12-12T17:40:04.683Z,1576172404.683 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:40:05.094Z,1576172405.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.689054 min 
2019-12-12T17:40:05.094Z,1576172405.094 [Default:CheckIn:E] Stopped
2019-12-12T17:40:05.094Z,1576172405.094 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:40:05.094Z,1576172405.094 [Default:CheckIn] Stopped
2019-12-12T17:40:05.094Z,1576172405.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:40:05.095Z,1576172405.095 [Default:CheckIn](INFO): Running loop #15
2019-12-12T17:40:05.095Z,1576172405.095 [Default:CheckIn] Running Loop=15
2019-12-12T17:40:05.095Z,1576172405.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:40:05.095Z,1576172405.095 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:40:06.696Z,1576172406.696 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-12-12T17:40:06.701Z,1576172406.701 [NAL9602] Data Fault, FailCount= 1
2019-12-12T17:40:06.701Z,1576172406.701 [NAL9602](ERROR): Data Fault
2019-12-12T17:40:06.795Z,1576172406.795 [CBIT](ERROR): Data Fault in component: NAL9602
2019-12-12T17:40:07.102Z,1576172407.102 [NAL9602](INFO): Powering down
2019-12-12T17:40:07.924Z,1576172407.924 [CBIT](INFO): Clearing failed state for component NAL9602
2019-12-12T17:40:07.925Z,1576172407.925 [NAL9602] No Fault, FailCount= 1
2019-12-12T17:40:37.384Z,1576172437.384 [NAL9602](INFO): Powering up NAL9602
2019-12-12T17:40:48.299Z,1576172448.299 [NAL9602](INFO): NAL9602 initialized
2019-12-12T17:45:05.270Z,1576172705.270 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-12-12T17:40:05.1Z
2019-12-12T17:45:05.270Z,1576172705.270 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:45:05.271Z,1576172705.271 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-12-12T17:45:13.072Z,1576172713.072 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20191212T162218/Express0040.lzma
2019-12-12T17:45:15.078Z,1576172715.078 [DataOverHttps](INFO): Moved sent file to Logs/20191212T162218/Express0040.lzma.bak
2019-12-12T17:45:15.079Z,1576172715.079 [DataOverHttps](INFO): SBD MOMSN=12151440
2019-12-12T17:45:16.582Z,1576172716.582 [Default:CheckIn:Read_Iridium] Stopped
2019-12-12T17:45:16.582Z,1576172716.582 [Default:CheckIn:C.Wait] Running Loop=1
2019-12-12T17:45:16.582Z,1576172716.582 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-12-12T17:45:51.702Z,1576172751.702 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-12-12T17:46:22.421Z,1576172782.421 [NAL9602](INFO): Not Powering down - fast GPS
2019-12-12T17:49:47.257Z,1576172987.257 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-12-12T17:49:47.257Z,1576172987.257 [AHRS_M2] Data Fault, FailCount= 1
2019-12-12T17:49:47.257Z,1576172987.257 [AHRS_M2](ERROR): Data Fault
2019-12-12T17:49:47.289Z,1576172987.289 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-12-12T17:49:47.641Z,1576172987.641 [AHRS_M2](INFO): Powering down
2019-12-12T17:49:48.046Z,1576172988.046 [AHRS_M2](FAULT): LCB fault:  Software Overcurrent.
2019-12-12T17:49:48.046Z,1576172988.046 [AHRS_M2] Hardware Fault, FailCount= 1
2019-12-12T17:49:48.046Z,1576172988.046 [AHRS_M2](ERROR): Hardware Fault
2019-12-12T17:49:48.478Z,1576172988.478 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-12-12T17:49:48.478Z,1576172988.478 [AHRS_M2] No Fault, FailCount= 1
2019-12-12T17:49:48.849Z,1576172988.849 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-12-12T17:49:52.401Z,1576172992.401 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:52.825Z,1576172992.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:53.253Z,1576172993.253 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:53.681Z,1576172993.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:54.109Z,1576172994.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:54.537Z,1576172994.537 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:54.957Z,1576172994.957 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:55.381Z,1576172995.381 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:55.809Z,1576172995.809 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:56.241Z,1576172996.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:56.681Z,1576172996.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:57.113Z,1576172997.113 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:57.565Z,1576172997.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:58.005Z,1576172998.005 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:58.449Z,1576172998.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:58.885Z,1576172998.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:59.313Z,1576172999.313 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:49:59.745Z,1576172999.745 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:00.169Z,1576173000.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:00.585Z,1576173000.585 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:01.013Z,1576173001.013 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:01.441Z,1576173001.441 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:01.877Z,1576173001.877 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:01.877Z,1576173001.877 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-12-12T17:50:01.877Z,1576173001.877 [AHRS_M2] Communications Fault, FailCount= 2
2019-12-12T17:50:01.877Z,1576173001.877 [AHRS_M2](ERROR): Communications Fault
2019-12-12T17:50:01.915Z,1576173001.915 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-12-12T17:50:01.978Z,1576173001.978 [AHRS_M2](INFO): Powering down
2019-12-12T17:50:02.776Z,1576173002.776 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-12-12T17:50:02.776Z,1576173002.776 [AHRS_M2] No Fault, FailCount= 2
2019-12-12T17:50:03.137Z,1576173003.137 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-12-12T17:50:06.689Z,1576173006.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:07.113Z,1576173007.113 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:07.541Z,1576173007.541 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:07.961Z,1576173007.961 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:08.389Z,1576173008.389 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:08.825Z,1576173008.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:09.249Z,1576173009.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:09.681Z,1576173009.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:10.105Z,1576173010.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:10.525Z,1576173010.525 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:10.953Z,1576173010.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:11.381Z,1576173011.381 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:11.801Z,1576173011.801 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:12.233Z,1576173012.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:12.657Z,1576173012.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:13.069Z,1576173013.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:13.497Z,1576173013.497 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:13.921Z,1576173013.921 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:14.357Z,1576173014.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:14.789Z,1576173014.789 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:15.217Z,1576173015.217 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:15.645Z,1576173015.645 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:16.077Z,1576173016.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:16.077Z,1576173016.077 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-12-12T17:50:16.077Z,1576173016.077 [AHRS_M2] Communications Fault, FailCount= 3
2019-12-12T17:50:16.077Z,1576173016.077 [AHRS_M2](ERROR): Communications Fault
2019-12-12T17:50:16.125Z,1576173016.125 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-12-12T17:50:16.193Z,1576173016.193 [AHRS_M2](INFO): Powering down
2019-12-12T17:50:16.984Z,1576173016.984 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-12-12T17:50:16.984Z,1576173016.984 [AHRS_M2] No Fault, FailCount= 3
2019-12-12T17:50:17.349Z,1576173017.349 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-12-12T17:50:17.395Z,1576173017.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-12-12T17:50:17.395Z,1576173017.395 [Default:CheckIn:C.Wait] Stopped
2019-12-12T17:50:17.395Z,1576173017.395 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-12-12T17:50:17.396Z,1576173017.396 [Default:CheckIn:D] Running Loop=1
2019-12-12T17:50:17.778Z,1576173017.778 [Default:CheckIn:D] Stopped
2019-12-12T17:50:17.778Z,1576173017.778 [Default:CheckIn:E] Running Loop=1
2019-12-12T17:50:18.184Z,1576173018.184 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.907292 min 
2019-12-12T17:50:18.184Z,1576173018.184 [Default:CheckIn:E] Stopped
2019-12-12T17:50:18.184Z,1576173018.184 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-12-12T17:50:18.184Z,1576173018.184 [Default:CheckIn] Stopped
2019-12-12T17:50:18.184Z,1576173018.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:50:18.185Z,1576173018.185 [Default:CheckIn](INFO): Running loop #16
2019-12-12T17:50:18.185Z,1576173018.185 [Default:CheckIn] Running Loop=16
2019-12-12T17:50:18.185Z,1576173018.185 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-12-12T17:50:18.185Z,1576173018.185 [Default:CheckIn:Read_GPS] Running Loop=1
2019-12-12T17:50:20.905Z,1576173020.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:21.349Z,1576173021.349 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:21.769Z,1576173021.769 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:22.197Z,1576173022.197 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:22.641Z,1576173022.641 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:23.069Z,1576173023.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:23.493Z,1576173023.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:23.933Z,1576173023.933 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:24.365Z,1576173024.365 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:24.793Z,1576173024.793 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:25.229Z,1576173025.229 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:25.661Z,1576173025.661 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:26.101Z,1576173026.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:26.541Z,1576173026.541 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:26.969Z,1576173026.969 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:27.413Z,1576173027.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:27.861Z,1576173027.861 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:28.309Z,1576173028.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:28.729Z,1576173028.729 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:29.185Z,1576173029.185 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:29.621Z,1576173029.621 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:30.045Z,1576173030.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:30.473Z,1576173030.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:30.473Z,1576173030.473 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-12-12T17:50:30.473Z,1576173030.473 [AHRS_M2] Communications Fault, FailCount= 4
2019-12-12T17:50:30.473Z,1576173030.473 [AHRS_M2](ERROR): Communications Fault
2019-12-12T17:50:30.543Z,1576173030.543 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-12-12T17:50:30.598Z,1576173030.598 [AHRS_M2](INFO): Powering down
2019-12-12T17:50:31.398Z,1576173031.398 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-12-12T17:50:31.398Z,1576173031.398 [AHRS_M2] No Fault, FailCount= 4
2019-12-12T17:50:31.758Z,1576173031.758 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-12-12T17:50:35.093Z,1576173035.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:35.485Z,1576173035.485 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:35.905Z,1576173035.905 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:36.309Z,1576173036.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-12-12T17:50:40.261Z,1576173040.261 [CommandLine](IMPORTANT): got command quit
2019-12-12T17:50:41.265Z,1576173041.265 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:41.265Z,1576173041.265 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:41.421Z,1576173041.421 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-12-12T17:50:41.421Z,1576173041.421 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:41.421Z,1576173041.421 [CommandLine](INFO): Join timeout helper Thread ID is 4750
2019-12-12T17:50:41.422Z,1576173041.422 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-12-12T17:50:41.422Z,1576173041.422 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:41.422Z,1576173041.422 [NavChartDb](INFO): Join timeout helper Thread ID is 4751
2019-12-12T17:50:41.693Z,1576173041.693 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:41.693Z,1576173041.693 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:41.705Z,1576173041.705 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-12-12T17:50:41.705Z,1576173041.705 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:41.705Z,1576173041.705 [Radio_Surface](INFO): Join timeout helper Thread ID is 4752
2019-12-12T17:50:42.017Z,1576173042.017 [Radio_Surface](INFO): Powering down
2019-12-12T17:50:42.018Z,1576173042.018 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:42.018Z,1576173042.018 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:42.029Z,1576173042.029 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-12-12T17:50:42.029Z,1576173042.029 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:42.029Z,1576173042.029 [DataOverHttps](INFO): Join timeout helper Thread ID is 4753
2019-12-12T17:50:42.121Z,1576173042.121 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:42.121Z,1576173042.121 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:42.129Z,1576173042.129 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-12-12T17:50:42.129Z,1576173042.129 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:42.129Z,1576173042.129 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4754
2019-12-12T17:50:42.365Z,1576173042.365 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:42.365Z,1576173042.365 [WetLabsBB2FL](INFO): Powering down
2019-12-12T17:50:42.366Z,1576173042.366 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:42.374Z,1576173042.374 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-12-12T17:50:42.374Z,1576173042.374 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:42.374Z,1576173042.374 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4755
2019-12-12T17:50:43.269Z,1576173043.269 [CTD_Seabird](INFO): Powering down
2019-12-12T17:50:43.281Z,1576173043.281 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:43.281Z,1576173043.281 [CTD_Seabird](INFO): Powering down
2019-12-12T17:50:43.293Z,1576173043.293 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.309Z,1576173043.309 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-12-12T17:50:43.309Z,1576173043.309 [logger ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.310Z,1576173043.310 [logger](INFO): Join timeout helper Thread ID is 4756
2019-12-12T17:50:43.313Z,1576173043.313 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:43.313Z,1576173043.313 [logger ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.329Z,1576173043.329 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-12-12T17:50:43.329Z,1576173043.329 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.330Z,1576173043.330 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-12-12T17:50:43.330Z,1576173043.330 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.330Z,1576173043.330 [controlThread](INFO): Join timeout helper Thread ID is 4757
2019-12-12T17:50:43.469Z,1576173043.469 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-12-12T17:50:43.469Z,1576173043.469 [controlThread](DEBUG): Uninitializing ControlThread
2019-12-12T17:50:43.469Z,1576173043.469 [Aanderaa_O2](INFO): Powering down
2019-12-12T17:50:43.491Z,1576173043.491 [AHRS_M2](INFO): Powering down
2019-12-12T17:50:43.561Z,1576173043.561 [NAL9602](INFO): Powering down
2019-12-12T17:50:43.562Z,1576173043.562 [DAT](INFO): Powering down
2019-12-12T17:50:43.681Z,1576173043.681 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-12-12T17:50:43.682Z,1576173043.682 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-12-12T17:50:43.683Z,1576173043.683 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-12-12T17:50:43.683Z,1576173043.683 [MissionManager](INFO): Uninitializing Mission Default
2019-12-12T17:50:43.683Z,1576173043.683 [Default] Stopped
2019-12-12T17:50:43.683Z,1576173043.683 [Default](DEBUG): Aggregate::uninitialize Default
2019-12-12T17:50:43.684Z,1576173043.684 [Default:B.GoToSurface] Stopped
2019-12-12T17:50:43.684Z,1576173043.684 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-12-12T17:50:43.684Z,1576173043.684 [Default:CheckIn] Stopped
2019-12-12T17:50:43.684Z,1576173043.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-12-12T17:50:43.684Z,1576173043.684 [Default:CheckIn:Read_GPS] Stopped
2019-12-12T17:50:43.686Z,1576173043.686 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-12-12T17:50:43.686Z,1576173043.686 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-12-12T17:50:43.687Z,1576173043.687 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-12-12T17:50:43.687Z,1576173043.687 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-12-12T17:50:43.687Z,1576173043.687 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-12-12T17:50:43.687Z,1576173043.687 [BuoyancyServo](INFO): Powering down
2019-12-12T17:50:43.701Z,1576173043.701 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-12-12T17:50:43.701Z,1576173043.701 [ElevatorServo](INFO): Powering down
2019-12-12T17:50:43.702Z,1576173043.702 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-12-12T17:50:43.702Z,1576173043.702 [RudderServo](INFO): Powering down
2019-12-12T17:50:43.702Z,1576173043.702 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-12-12T17:50:43.703Z,1576173043.703 [ThrusterServo](INFO): Powering down
2019-12-12T17:50:43.703Z,1576173043.703 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-12-12T17:50:43.704Z,1576173043.704 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-12-12T17:50:43.704Z,1576173043.704 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-12-12T17:50:43.704Z,1576173043.704 [CBIT](DEBUG): Powering off loads.
2019-12-12T17:50:43.717Z,1576173043.717 [CBIT](DEBUG): Disabling WDT.
2019-12-12T17:50:43.729Z,1576173043.729 [CBIT](DEBUG): Opening all GF detection circuits.
2019-12-12T17:50:43.729Z,1576173043.729 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.778Z,1576173043.778 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.784Z,1576173043.784 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.850Z,1576173043.850 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.856Z,1576173043.856 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.910Z,1576173043.910 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-12-12T17:50:43.979Z,1576173043.979 [logger ThreadHandler](INFO): Thread cancelled.