2021-04-15T14:47:28.907Z,1618498048.907 [CommandLine](IMPORTANT): got command restart logs
2021-04-15T14:47:40.470Z,1618498060.470 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T14:47:40.470Z,1618498060.470 [Default:CheckIn:C.Wait] Stopped
2021-04-15T14:47:40.470Z,1618498060.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T14:47:40.479Z,1618498060.479 [Default:CheckIn:D] Running Loop=1
2021-04-15T14:47:40.889Z,1618498060.889 [Default:CheckIn:D] Stopped
2021-04-15T14:47:40.889Z,1618498060.889 [Default:CheckIn:E] Running Loop=1
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.897071 min 
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn:E] Stopped
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn] Stopped
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn](INFO): Running loop #3
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn] Running Loop=3
2021-04-15T14:47:41.308Z,1618498061.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T14:47:41.309Z,1618498061.309 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T14:47:43.258Z,1618498063.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,144742.00,A,4133.06834,N,07042.44732,W,5.890,224.59,150421,,,D*7D
2021-04-15T14:47:43.261Z,1618498063.261 [NAL9602](INFO): GPS fix at 20210415T144742: (41.551139, -70.707455)
2021-04-15T14:47:43.304Z,1618498063.304 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T14:47:43.304Z,1618498063.304 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T14:47:46.967Z,1618498066.967 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210415T134220/Courier0027.lzma
2021-04-15T14:47:47.969Z,1618498067.969 [DataOverHttps](INFO): Moved sent file to Logs/20210415T134220/Courier0027.lzma.bak
2021-04-15T14:47:47.969Z,1618498067.969 [DataOverHttps](INFO): SBD MOMSN=106263
2021-04-15T14:47:57.039Z,1618498077.039 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20210415T144728/Courier0000.lzma
2021-04-15T14:47:58.041Z,1618498078.041 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0000.lzma.bak
2021-04-15T14:47:58.041Z,1618498078.041 [DataOverHttps](INFO): SBD MOMSN=106265
2021-04-15T14:48:07.078Z,1618498087.078 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20210415T144728/Courier0001.lzma
2021-04-15T14:48:08.046Z,1618498088.046 [NAL9602](INFO): SBD MO Status=2, MOMSN=15549, MT Status=2, MTMSN=0
2021-04-15T14:48:08.046Z,1618498088.046 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T14:48:08.081Z,1618498088.081 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0001.lzma.bak
2021-04-15T14:48:08.081Z,1618498088.081 [DataOverHttps](INFO): SBD MOMSN=106268
2021-04-15T14:48:17.126Z,1618498097.126 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20210415T134220/Express0028.lzma
2021-04-15T14:48:18.129Z,1618498098.129 [DataOverHttps](INFO): Moved sent file to Logs/20210415T134220/Express0028.lzma.bak
2021-04-15T14:48:18.129Z,1618498098.129 [DataOverHttps](INFO): SBD MOMSN=106270
2021-04-15T14:48:27.170Z,1618498107.170 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20210415T144728/Express0001.lzma
2021-04-15T14:48:28.169Z,1618498108.168 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0001.lzma.bak
2021-04-15T14:48:28.169Z,1618498108.169 [DataOverHttps](INFO): SBD MOMSN=106273
2021-04-15T14:48:29.471Z,1618498109.471 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T14:48:29.471Z,1618498109.471 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T14:48:29.471Z,1618498109.471 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T14:48:36.332Z,1618498116.332 [NAL9602](INFO): SBD MO Status=0, MOMSN=15549, MT Status=0, MTMSN=0
2021-04-15T14:48:36.332Z,1618498116.332 [NAL9602](INFO): No messages in MT queue
2021-04-15T14:49:07.022Z,1618498147.022 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T14:51:38.954Z,1618498298.954 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-15T14:53:30.054Z,1618498410.054 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T14:53:30.054Z,1618498410.054 [Default:CheckIn:C.Wait] Stopped
2021-04-15T14:53:30.054Z,1618498410.054 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T14:53:30.054Z,1618498410.054 [Default:CheckIn:D] Running Loop=1
2021-04-15T14:53:30.468Z,1618498410.468 [Default:CheckIn:D] Stopped
2021-04-15T14:53:30.468Z,1618498410.468 [Default:CheckIn:E] Running Loop=1
2021-04-15T14:53:30.860Z,1618498410.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.723385 min 
2021-04-15T14:53:30.861Z,1618498410.861 [Default:CheckIn:E] Stopped
2021-04-15T14:53:30.861Z,1618498410.861 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T14:53:30.861Z,1618498410.861 [Default:CheckIn] Stopped
2021-04-15T14:53:30.862Z,1618498410.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T14:53:30.862Z,1618498410.862 [Default:CheckIn](INFO): Running loop #4
2021-04-15T14:53:30.862Z,1618498410.862 [Default:CheckIn] Running Loop=4
2021-04-15T14:53:30.898Z,1618498410.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T14:53:30.898Z,1618498410.898 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T14:53:32.876Z,1618498412.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145332.00,A,4131.75222,N,07041.89592,W,25.950,143.91,150421,,,A*42
2021-04-15T14:53:32.878Z,1618498412.878 [NAL9602](INFO): GPS fix at 20210415T145332: (41.529204, -70.698265)
2021-04-15T14:53:32.893Z,1618498412.893 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T14:53:32.893Z,1618498412.893 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T14:53:36.479Z,1618498416.479 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20210415T144728/Courier0004.lzma
2021-04-15T14:53:37.481Z,1618498417.481 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0004.lzma.bak
2021-04-15T14:53:37.481Z,1618498417.481 [DataOverHttps](INFO): SBD MOMSN=106275
2021-04-15T14:53:46.523Z,1618498426.523 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20210415T144728/Express0005.lzma
2021-04-15T14:53:47.525Z,1618498427.525 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0005.lzma.bak
2021-04-15T14:53:47.525Z,1618498427.525 [DataOverHttps](INFO): SBD MOMSN=106278
2021-04-15T14:53:48.989Z,1618498428.989 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-15T14:53:49.031Z,1618498429.031 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T14:53:49.032Z,1618498429.032 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T14:53:49.032Z,1618498429.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T14:53:54.970Z,1618498434.970 [NAL9602](INFO): SBD MO Status=0, MOMSN=15550, MT Status=0, MTMSN=0
2021-04-15T14:53:54.970Z,1618498434.970 [NAL9602](INFO): No messages in MT queue
2021-04-15T14:54:25.673Z,1618498465.673 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T14:56:43.069Z,1618498603.069 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-15T14:56:53.988Z,1618498613.988 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009359
CHAN A1 (24V): -0.028887
CHAN A2 (12V): -0.007666
CHAN A3 (5V): -0.001996
CHAN B0 (3.3V): 0.000314
CHAN B1 (3.15aV): 0.000220
CHAN B2 (3.15bV): 0.000043
CHAN B3 (GND): 0.002101
OPEN: 0.005576
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-15T14:58:49.513Z,1618498729.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T14:58:49.513Z,1618498729.513 [Default:CheckIn:C.Wait] Stopped
2021-04-15T14:58:49.513Z,1618498729.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T14:58:49.514Z,1618498729.514 [Default:CheckIn:D] Running Loop=1
2021-04-15T14:58:49.920Z,1618498729.920 [Default:CheckIn:D] Stopped
2021-04-15T14:58:49.920Z,1618498729.920 [Default:CheckIn:E] Running Loop=1
2021-04-15T14:58:50.307Z,1618498730.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.047591 min 
2021-04-15T14:58:50.307Z,1618498730.307 [Default:CheckIn:E] Stopped
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn] Stopped
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn](INFO): Running loop #5
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn] Running Loop=5
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T14:58:50.308Z,1618498730.308 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T14:58:52.315Z,1618498732.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,145851.00,A,4131.35803,N,07040.42237,W,4.996,30.45,150421,,,A*4A
2021-04-15T14:58:52.317Z,1618498732.317 [NAL9602](INFO): GPS fix at 20210415T145851: (41.522634, -70.673706)
2021-04-15T14:58:52.372Z,1618498732.372 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T14:58:52.372Z,1618498732.372 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T14:58:53.138Z,1618498733.138 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T14:58:53.138Z,1618498733.138 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T14:58:53.138Z,1618498733.138 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T14:59:14.140Z,1618498754.140 [NAL9602](INFO): SBD MO Status=2, MOMSN=15551, MT Status=2, MTMSN=0
2021-04-15T14:59:14.140Z,1618498754.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T14:59:39.288Z,1618498779.288 [NAL9602](INFO): SBD MO Status=0, MOMSN=15551, MT Status=0, MTMSN=0
2021-04-15T14:59:39.288Z,1618498779.288 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:00:09.885Z,1618498809.885 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:03:53.711Z,1618499033.711 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:03:53.727Z,1618499033.727 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:03:53.727Z,1618499033.727 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:03:53.727Z,1618499033.727 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:03:54.121Z,1618499034.121 [Default:CheckIn:D] Stopped
2021-04-15T15:03:54.121Z,1618499034.121 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:03:54.533Z,1618499034.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.117601 min 
2021-04-15T15:03:54.533Z,1618499034.533 [Default:CheckIn:E] Stopped
2021-04-15T15:03:54.533Z,1618499034.533 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:03:54.533Z,1618499034.533 [Default:CheckIn] Stopped
2021-04-15T15:03:54.533Z,1618499034.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:03:54.534Z,1618499034.534 [Default:CheckIn](INFO): Running loop #6
2021-04-15T15:03:54.534Z,1618499034.534 [Default:CheckIn] Running Loop=6
2021-04-15T15:03:54.534Z,1618499034.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:03:54.534Z,1618499034.534 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:03:56.527Z,1618499036.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150355.00,A,4131.45731,N,07040.31502,W,0.078,170.57,150421,,,A*76
2021-04-15T15:03:56.529Z,1618499036.529 [NAL9602](INFO): GPS fix at 20210415T150355: (41.524288, -70.671917)
2021-04-15T15:03:56.568Z,1618499036.568 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:03:56.568Z,1618499036.568 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:04:00.046Z,1618499040.046 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20210415T144728/Courier0009.lzma
2021-04-15T15:04:01.045Z,1618499041.045 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0009.lzma.bak
2021-04-15T15:04:01.045Z,1618499041.045 [DataOverHttps](INFO): SBD MOMSN=106282
2021-04-15T15:04:10.082Z,1618499050.082 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210415T144728/Courier0012.lzma
2021-04-15T15:04:11.084Z,1618499051.084 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0012.lzma.bak
2021-04-15T15:04:11.085Z,1618499051.085 [DataOverHttps](INFO): SBD MOMSN=106285
2021-04-15T15:04:19.177Z,1618499059.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=15552, MT Status=2, MTMSN=0
2021-04-15T15:04:19.177Z,1618499059.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T15:04:20.123Z,1618499060.123 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20210415T144728/Express0010.lzma
2021-04-15T15:04:21.125Z,1618499061.125 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0010.lzma.bak
2021-04-15T15:04:21.125Z,1618499061.125 [DataOverHttps](INFO): SBD MOMSN=106288
2021-04-15T15:04:30.191Z,1618499070.191 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20210415T144728/Express0013.lzma
2021-04-15T15:04:31.194Z,1618499071.194 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0013.lzma.bak
2021-04-15T15:04:31.194Z,1618499071.194 [DataOverHttps](INFO): SBD MOMSN=106293
2021-04-15T15:04:32.522Z,1618499072.522 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:04:32.522Z,1618499072.522 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:04:32.522Z,1618499072.522 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:04:49.072Z,1618499089.072 [NAL9602](INFO): SBD MO Status=2, MOMSN=15552, MT Status=2, MTMSN=0
2021-04-15T15:04:49.072Z,1618499089.072 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T15:05:08.464Z,1618499108.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=15552, MT Status=2, MTMSN=0
2021-04-15T15:05:08.464Z,1618499108.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T15:05:18.150Z,1618499118.150 [NAL9602](INFO): SBD MO Status=0, MOMSN=15552, MT Status=0, MTMSN=0
2021-04-15T15:05:18.150Z,1618499118.150 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:05:48.885Z,1618499148.885 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:09:33.126Z,1618499373.126 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:09:33.126Z,1618499373.126 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:09:33.126Z,1618499373.126 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:09:33.126Z,1618499373.126 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:09:33.564Z,1618499373.564 [Default:CheckIn:D] Stopped
2021-04-15T15:09:33.564Z,1618499373.564 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:09:33.911Z,1618499373.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.774988 min 
2021-04-15T15:09:33.911Z,1618499373.911 [Default:CheckIn:E] Stopped
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn] Stopped
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn](INFO): Running loop #7
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn] Running Loop=7
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:09:33.912Z,1618499373.912 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:09:35.912Z,1618499375.912 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,150935.00,A,4131.45888,N,07040.31479,W,0.194,277.99,150421,,,D*7A
2021-04-15T15:09:35.914Z,1618499375.914 [NAL9602](INFO): GPS fix at 20210415T150935: (41.524315, -70.671913)
2021-04-15T15:09:35.964Z,1618499375.964 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:09:35.965Z,1618499375.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:09:39.471Z,1618499379.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210415T144728/Courier0015.lzma
2021-04-15T15:09:40.473Z,1618499380.473 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0015.lzma.bak
2021-04-15T15:09:40.473Z,1618499380.473 [DataOverHttps](INFO): SBD MOMSN=106296
2021-04-15T15:09:47.838Z,1618499387.838 [NAL9602](INFO): SBD MO Status=0, MOMSN=15553, MT Status=0, MTMSN=0
2021-04-15T15:09:47.838Z,1618499387.838 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:09:49.523Z,1618499389.523 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0016.lzma
2021-04-15T15:09:50.525Z,1618499390.525 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0016.lzma.bak
2021-04-15T15:09:50.525Z,1618499390.525 [DataOverHttps](INFO): SBD MOMSN=106299
2021-04-15T15:09:51.894Z,1618499391.894 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:09:51.895Z,1618499391.895 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:09:51.895Z,1618499391.895 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:10:18.549Z,1618499418.549 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:10:25.024Z,1618499425.024 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-15T15:11:59.963Z,1618499519.963 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-15T15:12:50.054Z,1618499570.054 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-15T15:14:52.509Z,1618499692.509 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:14:52.509Z,1618499692.509 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:14:52.509Z,1618499692.509 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:14:52.510Z,1618499692.510 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:14:52.889Z,1618499692.889 [Default:CheckIn:D] Stopped
2021-04-15T15:14:52.889Z,1618499692.889 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:14:53.283Z,1618499693.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.097066 min 
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn:E] Stopped
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn] Stopped
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn](INFO): Running loop #8
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn] Running Loop=8
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:14:53.284Z,1618499693.284 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:14:55.294Z,1618499695.294 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151454.00,A,4131.45660,N,07040.31012,W,0.039,96.11,150421,,,D*4B
2021-04-15T15:14:55.297Z,1618499695.297 [NAL9602](INFO): GPS fix at 20210415T151454: (41.524277, -70.671835)
2021-04-15T15:14:55.324Z,1618499695.324 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:14:55.324Z,1618499695.324 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:14:58.825Z,1618499698.825 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210415T144728/Courier0018.lzma
2021-04-15T15:14:59.821Z,1618499699.821 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0018.lzma.bak
2021-04-15T15:14:59.821Z,1618499699.821 [DataOverHttps](INFO): SBD MOMSN=106302
2021-04-15T15:15:07.054Z,1618499707.054 [NAL9602](INFO): SBD MO Status=0, MOMSN=15554, MT Status=0, MTMSN=0
2021-04-15T15:15:07.055Z,1618499707.055 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:15:08.867Z,1618499708.867 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0019.lzma
2021-04-15T15:15:09.869Z,1618499709.869 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0019.lzma.bak
2021-04-15T15:15:09.869Z,1618499709.869 [DataOverHttps](INFO): SBD MOMSN=106305
2021-04-15T15:15:11.118Z,1618499711.118 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:15:11.118Z,1618499711.118 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:15:11.118Z,1618499711.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:15:37.768Z,1618499737.768 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:20:11.719Z,1618500011.719 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:20:11.719Z,1618500011.719 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:20:11.719Z,1618500011.719 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:20:11.719Z,1618500011.719 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:20:12.107Z,1618500012.107 [Default:CheckIn:D] Stopped
2021-04-15T15:20:12.107Z,1618500012.107 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:20:12.515Z,1618500012.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.417362 min 
2021-04-15T15:20:12.515Z,1618500012.515 [Default:CheckIn:E] Stopped
2021-04-15T15:20:12.515Z,1618500012.515 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:20:12.515Z,1618500012.515 [Default:CheckIn] Stopped
2021-04-15T15:20:12.516Z,1618500012.516 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:20:12.516Z,1618500012.516 [Default:CheckIn](INFO): Running loop #9
2021-04-15T15:20:12.516Z,1618500012.516 [Default:CheckIn] Running Loop=9
2021-04-15T15:20:12.516Z,1618500012.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:20:12.516Z,1618500012.516 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:20:14.502Z,1618500014.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152013.00,A,4131.46164,N,07040.27942,W,0.117,110.95,150421,,,D*7A
2021-04-15T15:20:14.505Z,1618500014.505 [NAL9602](INFO): GPS fix at 20210415T152013: (41.524361, -70.671324)
2021-04-15T15:20:14.544Z,1618500014.544 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:20:14.544Z,1618500014.544 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:20:18.155Z,1618500018.155 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20210415T144728/Courier0021.lzma
2021-04-15T15:20:19.157Z,1618500019.157 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0021.lzma.bak
2021-04-15T15:20:19.157Z,1618500019.157 [DataOverHttps](INFO): SBD MOMSN=106308
2021-04-15T15:20:28.202Z,1618500028.202 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0022.lzma
2021-04-15T15:20:29.206Z,1618500029.206 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0022.lzma.bak
2021-04-15T15:20:29.206Z,1618500029.206 [DataOverHttps](INFO): SBD MOMSN=106312
2021-04-15T15:20:30.668Z,1618500030.668 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:20:30.668Z,1618500030.668 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:20:30.668Z,1618500030.668 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:20:52.478Z,1618500052.478 [NAL9602](INFO): SBD MO Status=2, MOMSN=15555, MT Status=2, MTMSN=0
2021-04-15T15:20:52.478Z,1618500052.478 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T15:21:10.662Z,1618500070.662 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-15T15:21:44.624Z,1618500104.624 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-04-15T15:21:44.626Z,1618500104.626 [BPC1](INFO): Received data from all battery sticks.
2021-04-15T15:23:28.880Z,1618500208.880 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-15T15:23:34.482Z,1618500214.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=15555, MT Status=2, MTMSN=0
2021-04-15T15:23:34.482Z,1618500214.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T15:24:24.984Z,1618500264.984 [Micromodem](INFO): Nmea in: V,2.0.32731*1E

2021-04-15T15:24:24.985Z,1618500264.985 [Micromodem](ERROR): Response from modem failed NMEA checksum: V,2.0.32731*1E
 expected 0
2021-04-15T15:24:35.495Z,1618500275.495 [NAL9602](INFO): SBD MO Status=2, MOMSN=15555, MT Status=2, MTMSN=0
2021-04-15T15:24:35.496Z,1618500275.496 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-15T15:24:48.820Z,1618500288.820 [NAL9602](INFO): SBD MO Status=0, MOMSN=15555, MT Status=0, MTMSN=0
2021-04-15T15:24:48.820Z,1618500288.820 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:25:19.525Z,1618500319.525 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:25:31.256Z,1618500331.256 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:25:31.256Z,1618500331.256 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:25:31.256Z,1618500331.256 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:25:31.257Z,1618500331.257 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:25:31.773Z,1618500331.773 [Default:CheckIn:D] Stopped
2021-04-15T15:25:31.773Z,1618500331.773 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:25:32.108Z,1618500332.108 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.745129 min 
2021-04-15T15:25:32.108Z,1618500332.108 [Default:CheckIn:E] Stopped
2021-04-15T15:25:32.108Z,1618500332.108 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:25:32.108Z,1618500332.108 [Default:CheckIn] Stopped
2021-04-15T15:25:32.109Z,1618500332.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:25:32.109Z,1618500332.109 [Default:CheckIn](INFO): Running loop #10
2021-04-15T15:25:32.109Z,1618500332.109 [Default:CheckIn] Running Loop=10
2021-04-15T15:25:32.109Z,1618500332.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:25:32.109Z,1618500332.109 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:25:34.250Z,1618500334.250 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152533.00,A,4131.46224,N,07040.27865,W,0.175,87.00,150421,,,D*49
2021-04-15T15:25:34.252Z,1618500334.252 [NAL9602](INFO): GPS fix at 20210415T152533: (41.524371, -70.671311)
2021-04-15T15:25:34.310Z,1618500334.310 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:25:34.310Z,1618500334.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:25:38.483Z,1618500338.483 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20210415T144728/Courier0024.lzma
2021-04-15T15:25:39.485Z,1618500339.485 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0024.lzma.bak
2021-04-15T15:25:39.485Z,1618500339.485 [DataOverHttps](INFO): SBD MOMSN=106315
2021-04-15T15:25:46.232Z,1618500346.232 [NAL9602](INFO): SBD MO Status=0, MOMSN=15556, MT Status=0, MTMSN=0
2021-04-15T15:25:46.232Z,1618500346.232 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:25:48.523Z,1618500348.523 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20210415T144728/Express0025.lzma
2021-04-15T15:25:49.537Z,1618500349.537 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0025.lzma.bak
2021-04-15T15:25:49.537Z,1618500349.537 [DataOverHttps](INFO): SBD MOMSN=106318
2021-04-15T15:25:50.753Z,1618500350.753 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:25:50.753Z,1618500350.753 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:25:50.754Z,1618500350.754 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:26:16.901Z,1618500376.901 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:30:51.250Z,1618500651.250 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:30:51.250Z,1618500651.250 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:30:51.250Z,1618500651.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:30:51.251Z,1618500651.251 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:30:51.653Z,1618500651.653 [Default:CheckIn:D] Stopped
2021-04-15T15:30:51.653Z,1618500651.653 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:30:52.035Z,1618500652.035 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.076469 min 
2021-04-15T15:30:52.035Z,1618500652.035 [Default:CheckIn:E] Stopped
2021-04-15T15:30:52.035Z,1618500652.035 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:30:52.035Z,1618500652.035 [Default:CheckIn] Stopped
2021-04-15T15:30:52.036Z,1618500652.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:30:52.036Z,1618500652.036 [Default:CheckIn](INFO): Running loop #11
2021-04-15T15:30:52.036Z,1618500652.036 [Default:CheckIn] Running Loop=11
2021-04-15T15:30:52.036Z,1618500652.036 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:30:52.036Z,1618500652.036 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:30:54.058Z,1618500654.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153053.00,A,4131.46266,N,07040.27901,W,0.408,133.16,150421,,,D*78
2021-04-15T15:30:54.068Z,1618500654.068 [NAL9602](INFO): GPS fix at 20210415T153053: (41.524378, -70.671317)
2021-04-15T15:30:54.153Z,1618500654.153 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:30:54.153Z,1618500654.153 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:30:57.798Z,1618500657.798 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210415T144728/Courier0027.lzma
2021-04-15T15:30:58.801Z,1618500658.801 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0027.lzma.bak
2021-04-15T15:30:58.801Z,1618500658.801 [DataOverHttps](INFO): SBD MOMSN=106321
2021-04-15T15:31:07.846Z,1618500667.846 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0028.lzma
2021-04-15T15:31:08.841Z,1618500668.841 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0028.lzma.bak
2021-04-15T15:31:08.841Z,1618500668.841 [DataOverHttps](INFO): SBD MOMSN=106324
2021-04-15T15:31:10.250Z,1618500670.250 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:31:10.250Z,1618500670.250 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:31:10.250Z,1618500670.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:32:56.474Z,1618500776.474 [NAL9602](INFO): SBD MO Status=0, MOMSN=15557, MT Status=0, MTMSN=0
2021-04-15T15:32:56.475Z,1618500776.475 [NAL9602](INFO): No messages in MT queue
2021-04-15T15:33:27.198Z,1618500807.198 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:36:10.848Z,1618500970.848 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:36:10.848Z,1618500970.848 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:36:10.848Z,1618500970.848 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:36:10.848Z,1618500970.848 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:36:11.239Z,1618500971.239 [Default:CheckIn:D] Stopped
2021-04-15T15:36:11.239Z,1618500971.239 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:36:11.654Z,1618500971.654 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.402905 min 
2021-04-15T15:36:11.654Z,1618500971.654 [Default:CheckIn:E] Stopped
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn] Stopped
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn](INFO): Running loop #12
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn] Running Loop=12
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:36:11.655Z,1618500971.655 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:36:13.648Z,1618500973.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153612.00,A,4131.46367,N,07040.27758,W,0.156,102.83,150421,,,D*79
2021-04-15T15:36:13.650Z,1618500973.650 [NAL9602](INFO): GPS fix at 20210415T153612: (41.524394, -70.671293)
2021-04-15T15:36:13.677Z,1618500973.677 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:36:13.677Z,1618500973.677 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:36:18.102Z,1618500978.102 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210415T144728/Courier0030.lzma
2021-04-15T15:36:19.105Z,1618500979.105 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0030.lzma.bak
2021-04-15T15:36:19.105Z,1618500979.105 [DataOverHttps](INFO): SBD MOMSN=106327
2021-04-15T15:36:28.150Z,1618500988.150 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0031.lzma
2021-04-15T15:36:29.153Z,1618500989.153 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0031.lzma.bak
2021-04-15T15:36:29.153Z,1618500989.153 [DataOverHttps](INFO): SBD MOMSN=106330
2021-04-15T15:36:30.655Z,1618500990.655 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:36:30.655Z,1618500990.655 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:36:30.655Z,1618500990.655 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:40:01.514Z,1618501201.514 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-15T15:41:15.441Z,1618501275.441 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-15T15:41:31.262Z,1618501291.262 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:41:31.283Z,1618501291.283 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:41:31.283Z,1618501291.283 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:41:31.283Z,1618501291.283 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:41:31.626Z,1618501291.626 [Default:CheckIn:D] Stopped
2021-04-15T15:41:31.626Z,1618501291.626 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:41:32.027Z,1618501292.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.742680 min 
2021-04-15T15:41:32.027Z,1618501292.027 [Default:CheckIn:E] Stopped
2021-04-15T15:41:32.027Z,1618501292.027 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:41:32.027Z,1618501292.027 [Default:CheckIn] Stopped
2021-04-15T15:41:32.028Z,1618501292.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:41:32.028Z,1618501292.028 [Default:CheckIn](INFO): Running loop #13
2021-04-15T15:41:32.028Z,1618501292.028 [Default:CheckIn] Running Loop=13
2021-04-15T15:41:32.028Z,1618501292.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:41:32.028Z,1618501292.028 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:41:34.037Z,1618501294.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154133.00,A,4131.46810,N,07040.30299,W,1.458,102.83,150421,,,D*75
2021-04-15T15:41:34.044Z,1618501294.044 [NAL9602](INFO): GPS fix at 20210415T154133: (41.524468, -70.671717)
2021-04-15T15:41:34.121Z,1618501294.121 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:41:34.121Z,1618501294.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:41:38.474Z,1618501298.474 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210415T144728/Courier0033.lzma
2021-04-15T15:41:39.477Z,1618501299.477 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0033.lzma.bak
2021-04-15T15:41:39.477Z,1618501299.477 [DataOverHttps](INFO): SBD MOMSN=106333
2021-04-15T15:41:48.519Z,1618501308.519 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20210415T144728/Express0034.lzma
2021-04-15T15:41:49.525Z,1618501309.525 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0034.lzma.bak
2021-04-15T15:41:49.525Z,1618501309.525 [DataOverHttps](INFO): SBD MOMSN=106336
2021-04-15T15:41:51.073Z,1618501311.073 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:41:51.073Z,1618501311.073 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:41:51.073Z,1618501311.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:42:01.568Z,1618501321.568 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-15T15:42:06.400Z,1618501326.400 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:42:06.422Z,1618501326.422 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-15T15:46:51.723Z,1618501611.723 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:46:51.723Z,1618501611.723 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:46:51.724Z,1618501611.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:46:51.724Z,1618501611.724 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:46:52.087Z,1618501612.087 [Default:CheckIn:D] Stopped
2021-04-15T15:46:52.087Z,1618501612.087 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:46:52.496Z,1618501612.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.083708 min 
2021-04-15T15:46:52.496Z,1618501612.496 [Default:CheckIn:E] Stopped
2021-04-15T15:46:52.496Z,1618501612.496 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:46:52.497Z,1618501612.497 [Default:CheckIn] Stopped
2021-04-15T15:46:52.497Z,1618501612.497 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:46:52.497Z,1618501612.497 [Default:CheckIn](INFO): Running loop #14
2021-04-15T15:46:52.497Z,1618501612.497 [Default:CheckIn] Running Loop=14
2021-04-15T15:46:52.497Z,1618501612.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:46:52.497Z,1618501612.497 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:46:54.493Z,1618501614.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154653.00,A,4131.46603,N,07040.28000,W,0.389,0.00,150421,,,D*71
2021-04-15T15:46:54.495Z,1618501614.495 [NAL9602](INFO): GPS fix at 20210415T154653: (41.524434, -70.671333)
2021-04-15T15:46:54.517Z,1618501614.517 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:46:54.517Z,1618501614.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:46:58.834Z,1618501618.834 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210415T144728/Courier0036.lzma
2021-04-15T15:46:59.837Z,1618501619.837 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0036.lzma.bak
2021-04-15T15:46:59.837Z,1618501619.837 [DataOverHttps](INFO): SBD MOMSN=106340
2021-04-15T15:47:08.874Z,1618501628.874 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0037.lzma
2021-04-15T15:47:09.877Z,1618501629.877 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0037.lzma.bak
2021-04-15T15:47:09.878Z,1618501629.878 [DataOverHttps](INFO): SBD MOMSN=106343
2021-04-15T15:47:11.102Z,1618501631.102 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:47:11.102Z,1618501631.102 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:47:11.102Z,1618501631.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:48:18.117Z,1618501698.117 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-15T15:48:59.316Z,1618501739.316 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-04-15T15:48:59.316Z,1618501739.316 [DropWeight] Hardware Fault, FailCount= 1
2021-04-15T15:48:59.316Z,1618501739.316 [DropWeight](ERROR): Hardware Fault
2021-04-15T15:48:59.389Z,1618501739.389 [CommandLine](FAULT): Scheduling is paused
2021-04-15T15:48:59.390Z,1618501739.390 [CBIT](INFO): Critical error at 20210415T154859
2021-04-15T15:48:59.390Z,1618501739.390 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-04-15T15:48:59.396Z,1618501739.396 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-04-15T15:48:59.397Z,1618501739.397 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-04-15T15:48:59.746Z,1618501739.746 [CBIT](INFO): Critical error at 20210415T154859
2021-04-15T15:51:56.292Z,1618501916.292 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-15T15:52:11.651Z,1618501931.651 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:52:11.651Z,1618501931.651 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:52:11.651Z,1618501931.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:52:11.651Z,1618501931.651 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:52:12.044Z,1618501932.044 [Default:CheckIn:D] Stopped
2021-04-15T15:52:12.045Z,1618501932.045 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:52:12.453Z,1618501932.453 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.416325 min 
2021-04-15T15:52:12.453Z,1618501932.453 [Default:CheckIn:E] Stopped
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn] Stopped
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn](INFO): Running loop #15
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn] Running Loop=15
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:52:12.454Z,1618501932.454 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:52:14.461Z,1618501934.461 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155213.00,A,4131.46970,N,07040.29062,W,1.419,82.87,150421,,,A*41
2021-04-15T15:52:14.463Z,1618501934.463 [NAL9602](INFO): GPS fix at 20210415T155213: (41.524495, -70.671510)
2021-04-15T15:52:14.494Z,1618501934.494 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:52:14.494Z,1618501934.494 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:52:18.159Z,1618501938.159 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20210415T144728/Courier0039.lzma
2021-04-15T15:52:19.164Z,1618501939.164 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0039.lzma.bak
2021-04-15T15:52:19.164Z,1618501939.164 [DataOverHttps](INFO): SBD MOMSN=106346
2021-04-15T15:52:28.202Z,1618501948.202 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20210415T144728/Express0040.lzma
2021-04-15T15:52:29.201Z,1618501949.201 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0040.lzma.bak
2021-04-15T15:52:29.201Z,1618501949.201 [DataOverHttps](INFO): SBD MOMSN=106351
2021-04-15T15:52:30.318Z,1618501950.318 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:52:30.318Z,1618501950.318 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:52:30.319Z,1618501950.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:52:46.372Z,1618501966.372 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T15:57:30.835Z,1618502250.835 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T15:57:30.835Z,1618502250.835 [Default:CheckIn:C.Wait] Stopped
2021-04-15T15:57:30.835Z,1618502250.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T15:57:30.835Z,1618502250.835 [Default:CheckIn:D] Running Loop=1
2021-04-15T15:57:31.207Z,1618502251.207 [Default:CheckIn:D] Stopped
2021-04-15T15:57:31.207Z,1618502251.207 [Default:CheckIn:E] Running Loop=1
2021-04-15T15:57:31.615Z,1618502251.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.735693 min 
2021-04-15T15:57:31.615Z,1618502251.615 [Default:CheckIn:E] Stopped
2021-04-15T15:57:31.615Z,1618502251.615 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T15:57:31.616Z,1618502251.616 [Default:CheckIn] Stopped
2021-04-15T15:57:31.616Z,1618502251.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T15:57:31.616Z,1618502251.616 [Default:CheckIn](INFO): Running loop #16
2021-04-15T15:57:31.616Z,1618502251.616 [Default:CheckIn] Running Loop=16
2021-04-15T15:57:31.616Z,1618502251.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T15:57:31.616Z,1618502251.616 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T15:57:33.630Z,1618502253.630 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155732.00,A,4131.46829,N,07040.25191,W,0.914,72.85,150421,,,A*47
2021-04-15T15:57:33.632Z,1618502253.632 [NAL9602](INFO): GPS fix at 20210415T155732: (41.524471, -70.670865)
2021-04-15T15:57:33.643Z,1618502253.643 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T15:57:33.643Z,1618502253.643 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T15:57:37.466Z,1618502257.466 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210415T144728/Courier0042.lzma
2021-04-15T15:57:38.468Z,1618502258.468 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0042.lzma.bak
2021-04-15T15:57:38.469Z,1618502258.469 [DataOverHttps](INFO): SBD MOMSN=106355
2021-04-15T15:57:47.506Z,1618502267.506 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20210415T144728/Express0043.lzma
2021-04-15T15:57:48.509Z,1618502268.509 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0043.lzma.bak
2021-04-15T15:57:48.509Z,1618502268.509 [DataOverHttps](INFO): SBD MOMSN=106358
2021-04-15T15:57:49.854Z,1618502269.854 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T15:57:49.863Z,1618502269.863 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T15:57:49.863Z,1618502269.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T15:58:04.329Z,1618502284.329 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-04-15T15:58:04.403Z,1618502284.403 [NAL9602](FAULT): received: 
+CSQ:0

OK557, 0, 0, 0, 0

OK
2021-04-15T15:58:04.403Z,1618502284.403 [NAL9602] Data Fault, FailCount= 1
2021-04-15T15:58:04.403Z,1618502284.403 [NAL9602](ERROR): Data Fault
2021-04-15T15:58:04.434Z,1618502284.434 [CBIT](ERROR): Data Fault in component: NAL9602
2021-04-15T15:58:04.729Z,1618502284.729 [NAL9602](INFO): Powering down
2021-04-15T15:58:05.574Z,1618502285.574 [CBIT](INFO): Clearing failed state for component NAL9602
2021-04-15T15:58:05.574Z,1618502285.574 [NAL9602] No Fault, FailCount= 1
2021-04-15T15:58:35.033Z,1618502315.033 [NAL9602](INFO): Powering up NAL9602
2021-04-15T15:58:37.463Z,1618502317.463 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-15T15:58:45.934Z,1618502325.934 [NAL9602](INFO): NAL9602 initialized
2021-04-15T15:59:17.049Z,1618502357.049 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T16:02:50.388Z,1618502570.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-15T16:02:50.388Z,1618502570.388 [Default:CheckIn:C.Wait] Stopped
2021-04-15T16:02:50.389Z,1618502570.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T16:02:50.390Z,1618502570.390 [Default:CheckIn:D] Running Loop=1
2021-04-15T16:02:50.811Z,1618502570.811 [Default:CheckIn:D] Stopped
2021-04-15T16:02:50.811Z,1618502570.811 [Default:CheckIn:E] Running Loop=1
2021-04-15T16:02:51.220Z,1618502571.220 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.062435 min 
2021-04-15T16:02:51.220Z,1618502571.220 [Default:CheckIn:E] Stopped
2021-04-15T16:02:51.220Z,1618502571.220 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-15T16:02:51.220Z,1618502571.220 [Default:CheckIn] Stopped
2021-04-15T16:02:51.221Z,1618502571.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T16:02:51.221Z,1618502571.221 [Default:CheckIn](INFO): Running loop #17
2021-04-15T16:02:51.221Z,1618502571.221 [Default:CheckIn] Running Loop=17
2021-04-15T16:02:51.221Z,1618502571.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-15T16:02:51.221Z,1618502571.221 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-15T16:02:54.399Z,1618502574.399 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-04-15T16:03:51.793Z,1618502631.793 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-15T16:03:59.526Z,1618502639.526 [CBIT](INFO): Clearing failed state for component DropWeight
2021-04-15T16:03:59.526Z,1618502639.526 [DropWeight] No Fault, FailCount= 1
2021-04-15T16:06:58.842Z,1618502818.842 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-15T16:07:51.368Z,1618502871.368 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-15T16:02:51.2Z
2021-04-15T16:07:51.368Z,1618502871.368 [Default:CheckIn:Read_GPS] Stopped
2021-04-15T16:07:51.368Z,1618502871.368 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-15T16:07:54.998Z,1618502874.998 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210415T144728/Courier0045.lzma
2021-04-15T16:07:56.001Z,1618502876.001 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Courier0045.lzma.bak
2021-04-15T16:07:56.001Z,1618502876.001 [DataOverHttps](INFO): SBD MOMSN=106361
2021-04-15T16:08:05.039Z,1618502885.039 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20210415T144728/Express0046.lzma
2021-04-15T16:08:06.041Z,1618502886.041 [DataOverHttps](INFO): Moved sent file to Logs/20210415T144728/Express0046.lzma.bak
2021-04-15T16:08:06.041Z,1618502886.041 [DataOverHttps](INFO): SBD MOMSN=106363
2021-04-15T16:08:07.154Z,1618502887.154 [Default:CheckIn:Read_Iridium] Stopped
2021-04-15T16:08:07.154Z,1618502887.154 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-15T16:08:07.154Z,1618502887.154 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-15T16:08:22.053Z,1618502902.053 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-15T16:09:53.108Z,1618502993.108 [CommandLine](IMPORTANT): got command quit
2021-04-15T16:09:54.115Z,1618502994.115 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:54.115Z,1618502994.115 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.123Z,1618502994.123 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-15T16:09:54.123Z,1618502994.123 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.125Z,1618502994.125 [CommandLine](INFO): Join timeout helper Thread ID is 2761
2021-04-15T16:09:54.128Z,1618502994.128 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-15T16:09:54.128Z,1618502994.128 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.130Z,1618502994.130 [NavChartDb](INFO): Join timeout helper Thread ID is 2762
2021-04-15T16:09:54.307Z,1618502994.307 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:54.307Z,1618502994.307 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.323Z,1618502994.323 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2021-04-15T16:09:54.323Z,1618502994.323 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.323Z,1618502994.323 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 2763
2021-04-15T16:09:54.707Z,1618502994.707 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:54.707Z,1618502994.707 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-04-15T16:09:54.708Z,1618502994.708 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.727Z,1618502994.727 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-15T16:09:54.727Z,1618502994.727 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:54.727Z,1618502994.727 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2764
2021-04-15T16:09:55.603Z,1618502995.603 [CTD_Seabird](INFO): Powering down
2021-04-15T16:09:55.615Z,1618502995.615 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:55.615Z,1618502995.615 [CTD_Seabird](INFO): Powering down
2021-04-15T16:09:55.627Z,1618502995.627 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:55.627Z,1618502995.627 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-15T16:09:55.627Z,1618502995.627 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:55.628Z,1618502995.628 [Radio_Surface](INFO): Join timeout helper Thread ID is 2765
2021-04-15T16:09:55.887Z,1618502995.887 [Radio_Surface](INFO): Powering down
2021-04-15T16:09:55.888Z,1618502995.888 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:55.888Z,1618502995.888 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:55.892Z,1618502995.892 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-15T16:09:55.892Z,1618502995.892 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:55.892Z,1618502995.892 [Onboard](INFO): Join timeout helper Thread ID is 2766
2021-04-15T16:09:57.483Z,1618502997.483 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-04-15T16:09:57.711Z,1618502997.711 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:57.711Z,1618502997.711 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:57.728Z,1618502997.728 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-15T16:09:57.728Z,1618502997.728 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:57.728Z,1618502997.728 [DataOverHttps](INFO): Join timeout helper Thread ID is 2767
2021-04-15T16:09:58.487Z,1618502998.487 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:58.487Z,1618502998.487 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.496Z,1618502998.496 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-15T16:09:58.496Z,1618502998.496 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.496Z,1618502998.496 [logger](INFO): Join timeout helper Thread ID is 2768
2021-04-15T16:09:58.520Z,1618502998.520 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:58.522Z,1618502998.522 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.536Z,1618502998.536 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-15T16:09:58.536Z,1618502998.536 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.536Z,1618502998.536 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-15T16:09:58.536Z,1618502998.536 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.536Z,1618502998.536 [controlThread](INFO): Join timeout helper Thread ID is 2769
2021-04-15T16:09:58.587Z,1618502998.587 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-15T16:09:58.587Z,1618502998.587 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-15T16:09:58.588Z,1618502998.588 [AHRS_M2](INFO): Powering down
2021-04-15T16:09:58.660Z,1618502998.660 [Micromodem](INFO): Powering down
2021-04-15T16:09:58.757Z,1618502998.757 [NAL9602](INFO): Powering down
2021-04-15T16:09:58.831Z,1618502998.831 [RDI_Pathfinder](INFO): Powering down
2021-04-15T16:09:58.903Z,1618502998.903 [RDI_PathfinderUp](INFO): Powering down
2021-04-15T16:09:58.904Z,1618502998.904 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-15T16:09:58.905Z,1618502998.905 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-15T16:09:58.906Z,1618502998.906 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-15T16:09:58.906Z,1618502998.906 [MissionManager](INFO): Uninitializing Mission Default
2021-04-15T16:09:58.906Z,1618502998.906 [Default] Stopped
2021-04-15T16:09:58.907Z,1618502998.907 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-15T16:09:58.907Z,1618502998.907 [Default:B.GoToSurface] Stopped
2021-04-15T16:09:58.907Z,1618502998.907 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-15T16:09:58.907Z,1618502998.907 [Default:CheckIn] Stopped
2021-04-15T16:09:58.907Z,1618502998.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-15T16:09:58.907Z,1618502998.907 [Default:CheckIn:C.Wait] Stopped
2021-04-15T16:09:58.907Z,1618502998.907 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-15T16:09:58.910Z,1618502998.910 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-15T16:09:58.910Z,1618502998.910 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-15T16:09:58.911Z,1618502998.911 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-15T16:09:58.911Z,1618502998.911 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-15T16:09:58.911Z,1618502998.911 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-15T16:09:58.911Z,1618502998.911 [BuoyancyServo](INFO): Powering down
2021-04-15T16:09:58.923Z,1618502998.923 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-15T16:09:58.923Z,1618502998.923 [ElevatorServo](INFO): Powering down
2021-04-15T16:09:58.924Z,1618502998.924 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-15T16:09:58.924Z,1618502998.924 [MassServo](INFO): Powering down
2021-04-15T16:09:58.925Z,1618502998.925 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-15T16:09:58.925Z,1618502998.925 [RudderServo](INFO): Powering down
2021-04-15T16:09:58.926Z,1618502998.926 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-15T16:09:58.926Z,1618502998.926 [ThrusterServo](INFO): Powering down
2021-04-15T16:09:58.927Z,1618502998.927 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-15T16:09:58.927Z,1618502998.927 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-15T16:09:58.927Z,1618502998.927 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-15T16:09:58.927Z,1618502998.927 [CBIT](DEBUG): Powering off loads.
2021-04-15T16:09:58.939Z,1618502998.939 [CBIT](DEBUG): Disabling WDT.
2021-04-15T16:09:58.951Z,1618502998.951 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-15T16:09:58.952Z,1618502998.952 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.958Z,1618502998.958 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.962Z,1618502998.962 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:58.970Z,1618502998.970 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:59.050Z,1618502999.050 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:59.054Z,1618502999.054 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:59.109Z,1618502999.109 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-15T16:09:59.173Z,1618502999.173 [logger ThreadHandler](INFO): Thread cancelled.