2020-02-09T00:49:35.128Z,1581209375.128 [CommandLine](IMPORTANT): got command restart logs
2020-02-09T00:49:47.769Z,1581209387.769 [DataOverHttps](INFO): Sending 1958 bytes from file Logs/20200209T003428/Express0005.lzma
2020-02-09T00:49:49.772Z,1581209389.772 [DataOverHttps](INFO): Moved sent file to Logs/20200209T003428/Express0005.lzma.bak
2020-02-09T00:49:49.772Z,1581209389.772 [DataOverHttps](INFO): SBD MOMSN=12238634
2020-02-09T00:49:53.149Z,1581209393.149 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV.10.0.46*4B
 expected 5E
2020-02-09T00:50:01.273Z,1581209401.273 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-09T00:50:07.766Z,1581209407.766 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200209T003428/Courier0007.lzma
2020-02-09T00:50:09.772Z,1581209409.772 [DataOverHttps](INFO): Moved sent file to Logs/20200209T003428/Courier0007.lzma.bak
2020-02-09T00:50:09.772Z,1581209409.772 [DataOverHttps](INFO): SBD MOMSN=12238805
2020-02-09T00:50:27.854Z,1581209427.854 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200209T003428/Express0008.lzma
2020-02-09T00:50:29.860Z,1581209429.860 [DataOverHttps](INFO): Moved sent file to Logs/20200209T003428/Express0008.lzma.bak
2020-02-09T00:50:29.860Z,1581209429.860 [DataOverHttps](INFO): SBD MOMSN=12238807
2020-02-09T00:50:31.596Z,1581209431.596 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T00:50:31.596Z,1581209431.596 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T00:50:31.597Z,1581209431.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T00:51:47.936Z,1581209507.936 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-02-09T00:51:47.936Z,1581209507.936 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 100
2020-02-09T00:51:48.750Z,1581209508.750 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2020-02-09T00:53:29.762Z,1581209609.762 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T00:53:29.762Z,1581209609.762 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020818062810,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-09T00:54:21.849Z,1581209661.849 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T00:54:34.402Z,1581209674.402 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2020-02-09T00:54:52.558Z,1581209692.558 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T00:55:32.207Z,1581209732.207 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T00:55:32.207Z,1581209732.207 [Default:CheckIn:C.Wait] Stopped
2020-02-09T00:55:32.207Z,1581209732.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T00:55:32.207Z,1581209732.207 [Default:CheckIn:D] Running Loop=1
2020-02-09T00:55:32.589Z,1581209732.589 [Default:CheckIn:D] Stopped
2020-02-09T00:55:32.589Z,1581209732.589 [Default:CheckIn:E] Running Loop=1
2020-02-09T00:55:33.008Z,1581209733.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.474959 min 
2020-02-09T00:55:33.008Z,1581209733.008 [Default:CheckIn:E] Stopped
2020-02-09T00:55:33.008Z,1581209733.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T00:55:33.008Z,1581209733.008 [Default:CheckIn] Stopped
2020-02-09T00:55:33.009Z,1581209733.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T00:55:33.009Z,1581209733.009 [Default:CheckIn](INFO): Running loop #2
2020-02-09T00:55:33.009Z,1581209733.009 [Default:CheckIn] Running Loop=2
2020-02-09T00:55:33.010Z,1581209733.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T00:55:33.010Z,1581209733.010 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T00:55:34.979Z,1581209734.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005518.00,A,4450.23448,N,06733.87430,W,0.253,0.00,090220,,,A*7D
2020-02-09T00:55:34.982Z,1581209734.982 [NAL9602](INFO): GPS fix at 20200209T005518: (44.837241, -67.564572)
2020-02-09T00:55:35.010Z,1581209735.010 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T00:55:35.011Z,1581209735.011 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T00:55:43.879Z,1581209743.879 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20200209T004935/Courier0000.lzma
2020-02-09T00:55:45.885Z,1581209745.885 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0000.lzma.bak
2020-02-09T00:55:45.885Z,1581209745.885 [DataOverHttps](INFO): SBD MOMSN=12238809
2020-02-09T00:55:49.549Z,1581209749.549 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T00:55:49.550Z,1581209749.550 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208185.0, -0.1,   0.0,1448.9,  0
2020-02-09T00:56:03.407Z,1581209763.407 [DataOverHttps](INFO): Sending 432 bytes from file Logs/20200209T004935/Express0001.lzma
2020-02-09T00:56:05.645Z,1581209765.645 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0001.lzma.bak
2020-02-09T00:56:05.645Z,1581209765.645 [DataOverHttps](INFO): SBD MOMSN=12238813
2020-02-09T00:56:07.338Z,1581209767.338 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T00:56:07.338Z,1581209767.338 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T00:56:07.338Z,1581209767.338 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:00:36.781Z,1581210036.781 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T01:00:37.233Z,1581210037.233 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T01:00:37.233Z,1581210037.233 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,              +0.00,     ,   0.00,966.96
2020-02-09T01:01:07.485Z,1581210067.485 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:01:07.934Z,1581210067.934 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:01:07.935Z,1581210067.935 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:01:07.935Z,1581210067.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:01:07.936Z,1581210067.936 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:01:08.335Z,1581210068.335 [Default:CheckIn:D] Stopped
2020-02-09T01:01:08.336Z,1581210068.336 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.070455 min 
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn:E] Stopped
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn] Stopped
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn](INFO): Running loop #3
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn] Running Loop=3
2020-02-09T01:01:08.731Z,1581210068.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:01:08.732Z,1581210068.732 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:01:10.718Z,1581210070.718 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010054.00,A,4450.21718,N,06733.87073,W,0.486,0.00,090220,,,A*7D
2020-02-09T01:01:10.729Z,1581210070.729 [NAL9602](INFO): GPS fix at 20200209T010054: (44.836953, -67.564512)
2020-02-09T01:01:10.756Z,1581210070.756 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:01:10.756Z,1581210070.756 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:01:17.999Z,1581210077.999 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0003.lzma
2020-02-09T01:01:20.004Z,1581210080.004 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0003.lzma.bak
2020-02-09T01:01:20.004Z,1581210080.004 [DataOverHttps](INFO): SBD MOMSN=12238827
2020-02-09T01:01:37.403Z,1581210097.403 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20200209T004935/Express0004.lzma
2020-02-09T01:01:39.408Z,1581210099.408 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0004.lzma.bak
2020-02-09T01:01:39.409Z,1581210099.409 [DataOverHttps](INFO): SBD MOMSN=12238830
2020-02-09T01:01:41.421Z,1581210101.421 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-09T01:01:41.495Z,1581210101.495 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-02-09T01:01:41.495Z,1581210101.495 [NAL9602] Data Fault, FailCount= 1
2020-02-09T01:01:41.495Z,1581210101.495 [NAL9602](ERROR): Data Fault
2020-02-09T01:01:41.531Z,1581210101.531 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:01:41.532Z,1581210101.532 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:01:41.532Z,1581210101.532 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:01:41.577Z,1581210101.577 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-09T01:01:41.821Z,1581210101.821 [NAL9602](INFO): Powering down
2020-02-09T01:01:42.674Z,1581210102.674 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-09T01:01:42.675Z,1581210102.675 [NAL9602] No Fault, FailCount= 1
2020-02-09T01:02:12.121Z,1581210132.121 [NAL9602](INFO): Powering up NAL9602
2020-02-09T01:02:23.050Z,1581210143.050 [NAL9602](INFO): NAL9602 initialized
2020-02-09T01:02:40.423Z,1581210160.423 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:02:40.423Z,1581210160.423 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020818153, -0.1,   0.0,1448.9,  0
2020-02-09T01:02:54.148Z,1581210174.148 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:06:42.111Z,1581210402.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:06:42.111Z,1581210402.111 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:06:42.111Z,1581210402.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:06:42.111Z,1581210402.111 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:06:42.511Z,1581210402.511 [Default:CheckIn:D] Stopped
2020-02-09T01:06:42.511Z,1581210402.511 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:06:42.905Z,1581210402.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.639990 min 
2020-02-09T01:06:42.905Z,1581210402.905 [Default:CheckIn:E] Stopped
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn] Stopped
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn](INFO): Running loop #4
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn] Running Loop=4
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:06:42.906Z,1581210402.906 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:06:44.882Z,1581210404.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010627.00,A,4450.21870,N,06733.87884,W,0.156,0.00,090220,,,A*76
2020-02-09T01:06:44.884Z,1581210404.884 [NAL9602](INFO): GPS fix at 20200209T010627: (44.836978, -67.564647)
2020-02-09T01:06:44.924Z,1581210404.924 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:06:44.924Z,1581210404.924 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:06:46.904Z,1581210406.904 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T01:06:53.830Z,1581210413.830 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0006.lzma
2020-02-09T01:06:55.836Z,1581210415.836 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0006.lzma.bak
2020-02-09T01:06:55.836Z,1581210415.836 [DataOverHttps](INFO): SBD MOMSN=12238834
2020-02-09T01:07:14.178Z,1581210434.178 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20200209T004935/Express0007.lzma
2020-02-09T01:07:16.184Z,1581210436.184 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0007.lzma.bak
2020-02-09T01:07:16.184Z,1581210436.184 [DataOverHttps](INFO): SBD MOMSN=12238837
2020-02-09T01:07:17.600Z,1581210437.600 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:07:18.038Z,1581210438.038 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:07:18.039Z,1581210438.039 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:07:18.039Z,1581210438.039 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:08:00.063Z,1581210480.063 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:08:00.063Z,1581210480.063 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-02-09T01:09:29.772Z,1581210569.772 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:09:29.772Z,1581210569.772 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208185.0, -0.1,   0.0,1448.9,  0
2020-02-09T01:11:12.422Z,1581210672.422 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:11:12.422Z,1581210672.422 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-02-09T01:12:13.418Z,1581210733.418 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:12:13.418Z,1581210733.418 [RDI_Pathfinder](ERROR): Failed to parse:
:TS8251210,35.0, -0.1,   0.0,1448.9,  0
2020-02-09T01:12:15.432Z,1581210735.432 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:12:15.432Z,1581210735.432 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T01:12:18.704Z,1581210738.704 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:12:18.704Z,1581210738.704 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:12:18.705Z,1581210738.705 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:12:18.705Z,1581210738.705 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:12:19.071Z,1581210739.071 [Default:CheckIn:D] Stopped
2020-02-09T01:12:19.071Z,1581210739.071 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.249656 min 
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn:E] Stopped
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn] Stopped
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn](INFO): Running loop #5
2020-02-09T01:12:19.504Z,1581210739.504 [Default:CheckIn] Running Loop=5
2020-02-09T01:12:19.505Z,1581210739.505 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:12:19.505Z,1581210739.505 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:12:21.483Z,1581210741.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011205.00,A,4450.22290,N,06733.86473,W,0.156,4.54,090220,,,A*74
2020-02-09T01:12:21.485Z,1581210741.485 [NAL9602](INFO): GPS fix at 20200209T011205: (44.837048, -67.564412)
2020-02-09T01:12:21.515Z,1581210741.515 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:12:21.515Z,1581210741.515 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:12:29.614Z,1581210749.614 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0009.lzma
2020-02-09T01:12:31.620Z,1581210751.620 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0009.lzma.bak
2020-02-09T01:12:31.620Z,1581210751.620 [DataOverHttps](INFO): SBD MOMSN=12238849
2020-02-09T01:12:48.919Z,1581210768.919 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20200209T004935/Express0010.lzma
2020-02-09T01:12:50.924Z,1581210770.924 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0010.lzma.bak
2020-02-09T01:12:50.924Z,1581210770.924 [DataOverHttps](INFO): SBD MOMSN=12238852
2020-02-09T01:12:52.608Z,1581210772.608 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:12:52.608Z,1581210772.608 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:12:52.608Z,1581210772.608 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:13:35.065Z,1581210815.065 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:13:35.065Z,1581210815.065 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020818263410,35.0, -0.1,   0.0, 0
2020-02-09T01:14:26.323Z,1581210866.323 [NAL9602](INFO): SBD MO Status=2, MOMSN=10623, MT Status=2, MTMSN=0
2020-02-09T01:14:26.323Z,1581210866.323 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T01:15:55.213Z,1581210955.213 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:15:55.213Z,1581210955.213 [RDI_Pathfinder](ERROR): Failed to parse:
:TS85
2020-02-09T01:16:34.042Z,1581210994.042 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:16:34.042Z,1581210994.042 [RDI_Pathfinder](ERROR): Failed to parse:
:TS2768,-32768,-32768,-32768,V
2020-02-09T01:17:24.081Z,1581211044.081 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T01:17:53.209Z,1581211073.209 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:17:53.209Z,1581211073.209 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:17:53.209Z,1581211073.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:17:53.209Z,1581211073.209 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:17:53.606Z,1581211073.606 [Default:CheckIn:D] Stopped
2020-02-09T01:17:53.606Z,1581211073.606 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:17:54.049Z,1581211074.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.825242 min 
2020-02-09T01:17:54.049Z,1581211074.049 [Default:CheckIn:E] Stopped
2020-02-09T01:17:54.050Z,1581211074.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:17:54.050Z,1581211074.050 [Default:CheckIn] Stopped
2020-02-09T01:17:54.055Z,1581211074.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:17:54.055Z,1581211074.055 [Default:CheckIn](INFO): Running loop #6
2020-02-09T01:17:54.055Z,1581211074.055 [Default:CheckIn] Running Loop=6
2020-02-09T01:17:54.055Z,1581211074.055 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:17:54.055Z,1581211074.055 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:17:55.995Z,1581211075.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011739.00,A,4450.23329,N,06733.87416,W,0.078,232.18,090220,,,A*7C
2020-02-09T01:17:55.997Z,1581211075.997 [NAL9602](INFO): GPS fix at 20200209T011739: (44.837221, -67.564569)
2020-02-09T01:17:56.026Z,1581211076.026 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:17:56.027Z,1581211076.027 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:18:03.810Z,1581211083.810 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200209T004935/Courier0012.lzma
2020-02-09T01:18:05.816Z,1581211085.816 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0012.lzma.bak
2020-02-09T01:18:05.816Z,1581211085.816 [DataOverHttps](INFO): SBD MOMSN=12238856
2020-02-09T01:18:27.503Z,1581211107.503 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20200209T004935/Express0013.lzma
2020-02-09T01:18:27.932Z,1581211107.932 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:18:29.508Z,1581211109.508 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0013.lzma.bak
2020-02-09T01:18:29.508Z,1581211109.508 [DataOverHttps](INFO): SBD MOMSN=12238859
2020-02-09T01:18:31.202Z,1581211111.202 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:18:31.203Z,1581211111.203 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:18:31.203Z,1581211111.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:19:29.757Z,1581211169.757 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:19:29.758Z,1581211169.758 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T01:20:34.475Z,1581211234.475 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-02-09T01:20:34.475Z,1581211234.475 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-09T01:20:34.475Z,1581211234.475 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-09T01:20:34.475Z,1581211234.475 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-09T01:20:34.499Z,1581211234.499 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-09T01:20:34.887Z,1581211234.887 [RDI_Pathfinder](INFO): Powering down
2020-02-09T01:20:35.652Z,1581211235.652 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-09T01:20:35.652Z,1581211235.652 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-09T01:20:48.953Z,1581211248.953 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-09T01:23:31.776Z,1581211411.776 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:23:31.776Z,1581211411.776 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:23:31.776Z,1581211411.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:23:31.777Z,1581211411.777 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:23:32.193Z,1581211412.193 [Default:CheckIn:D] Stopped
2020-02-09T01:23:32.193Z,1581211412.193 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.468363 min 
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn:E] Stopped
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn] Stopped
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn](INFO): Running loop #7
2020-02-09T01:23:32.591Z,1581211412.591 [Default:CheckIn] Running Loop=7
2020-02-09T01:23:32.592Z,1581211412.592 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:23:32.592Z,1581211412.592 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:23:34.579Z,1581211414.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012318.00,A,4450.22395,N,06733.87790,W,0.194,192.33,090220,,,A*70
2020-02-09T01:23:34.581Z,1581211414.581 [NAL9602](INFO): GPS fix at 20200209T012318: (44.837066, -67.564632)
2020-02-09T01:23:34.633Z,1581211414.633 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:23:34.634Z,1581211414.634 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:23:42.790Z,1581211422.790 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0015.lzma
2020-02-09T01:23:44.796Z,1581211424.796 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0015.lzma.bak
2020-02-09T01:23:44.796Z,1581211424.796 [DataOverHttps](INFO): SBD MOMSN=12238869
2020-02-09T01:24:02.358Z,1581211442.358 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200209T004935/Express0016.lzma
2020-02-09T01:24:04.364Z,1581211444.364 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0016.lzma.bak
2020-02-09T01:24:04.364Z,1581211444.364 [DataOverHttps](INFO): SBD MOMSN=12238872
2020-02-09T01:24:05.316Z,1581211445.316 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-09T01:24:05.391Z,1581211445.391 [NAL9602](FAULT): received: 
+CSQ:1

OK623, 2, 0, 0, 0

OK
2020-02-09T01:24:05.391Z,1581211445.391 [NAL9602] Data Fault, FailCount= 1
2020-02-09T01:24:05.391Z,1581211445.391 [NAL9602](ERROR): Data Fault
2020-02-09T01:24:05.582Z,1581211445.582 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-09T01:24:05.685Z,1581211445.685 [NAL9602](INFO): Powering down
2020-02-09T01:24:06.122Z,1581211446.122 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:24:06.123Z,1581211446.123 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:24:06.123Z,1581211446.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:24:06.535Z,1581211446.535 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-09T01:24:06.535Z,1581211446.535 [NAL9602] No Fault, FailCount= 1
2020-02-09T01:24:36.036Z,1581211476.036 [NAL9602](INFO): Powering up NAL9602
2020-02-09T01:24:46.921Z,1581211486.921 [NAL9602](INFO): NAL9602 initialized
2020-02-09T01:25:18.030Z,1581211518.030 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:26:46.140Z,1581211606.140 [RDI_Pathfinder](ERROR): Failed to parse:     +0.00,        +0.00,        +0.00,   0.00,355.99
2020-02-09T01:29:06.758Z,1581211746.758 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:29:06.758Z,1581211746.758 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:29:06.758Z,1581211746.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:29:06.758Z,1581211746.758 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:29:07.160Z,1581211747.160 [Default:CheckIn:D] Stopped
2020-02-09T01:29:07.160Z,1581211747.160 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.051139 min 
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn:E] Stopped
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn] Stopped
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn](INFO): Running loop #8
2020-02-09T01:29:07.573Z,1581211747.573 [Default:CheckIn] Running Loop=8
2020-02-09T01:29:07.574Z,1581211747.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:29:07.574Z,1581211747.574 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:29:09.554Z,1581211749.554 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012852.00,A,4450.23611,N,06733.87895,W,0.214,0.00,090220,,,A*76
2020-02-09T01:29:09.556Z,1581211749.556 [NAL9602](INFO): GPS fix at 20200209T012852: (44.837269, -67.564649)
2020-02-09T01:29:09.585Z,1581211749.585 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:29:09.585Z,1581211749.585 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:29:11.580Z,1581211751.580 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T01:29:16.958Z,1581211756.958 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200209T004935/Courier0018.lzma
2020-02-09T01:29:18.964Z,1581211758.964 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0018.lzma.bak
2020-02-09T01:29:18.964Z,1581211758.964 [DataOverHttps](INFO): SBD MOMSN=12238875
2020-02-09T01:29:36.534Z,1581211776.534 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20200209T004935/Express0019.lzma
2020-02-09T01:29:38.540Z,1581211778.540 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0019.lzma.bak
2020-02-09T01:29:38.540Z,1581211778.540 [DataOverHttps](INFO): SBD MOMSN=12238878
2020-02-09T01:29:40.278Z,1581211780.278 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:29:40.279Z,1581211780.279 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:29:40.279Z,1581211780.279 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:29:42.268Z,1581211782.268 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:33:24.972Z,1581212004.972 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T01:33:24.972Z,1581212004.972 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +7
2020-02-09T01:34:40.904Z,1581212080.904 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:34:40.904Z,1581212080.904 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:34:40.905Z,1581212080.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:34:40.905Z,1581212080.905 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:34:41.291Z,1581212081.291 [Default:CheckIn:D] Stopped
2020-02-09T01:34:41.291Z,1581212081.291 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:34:41.703Z,1581212081.703 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.619991 min 
2020-02-09T01:34:41.703Z,1581212081.703 [Default:CheckIn:E] Stopped
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn] Stopped
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn](INFO): Running loop #9
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn] Running Loop=9
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:34:41.704Z,1581212081.704 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:34:43.691Z,1581212083.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013426.00,A,4450.23349,N,06733.88246,W,0.019,305.75,090220,,,A*70
2020-02-09T01:34:43.693Z,1581212083.693 [NAL9602](INFO): GPS fix at 20200209T013426: (44.837225, -67.564708)
2020-02-09T01:34:43.723Z,1581212083.723 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:34:43.723Z,1581212083.723 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:34:51.270Z,1581212091.270 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0021.lzma
2020-02-09T01:34:53.276Z,1581212093.276 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0021.lzma.bak
2020-02-09T01:34:53.276Z,1581212093.276 [DataOverHttps](INFO): SBD MOMSN=12238889
2020-02-09T01:35:10.707Z,1581212110.707 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20200209T004935/Express0022.lzma
2020-02-09T01:35:12.712Z,1581212112.712 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0022.lzma.bak
2020-02-09T01:35:12.712Z,1581212112.712 [DataOverHttps](INFO): SBD MOMSN=12238892
2020-02-09T01:35:14.421Z,1581212114.421 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:35:14.421Z,1581212114.421 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:35:14.421Z,1581212114.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:38:45.735Z,1581212325.735 [NAL9602](INFO): SBD MO Status=2, MOMSN=10623, MT Status=2, MTMSN=0
2020-02-09T01:38:45.735Z,1581212325.735 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T01:39:11.594Z,1581212351.594 [NAL9602](INFO): SBD MO Status=2, MOMSN=10623, MT Status=2, MTMSN=0
2020-02-09T01:39:11.594Z,1581212351.594 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T01:39:45.929Z,1581212385.929 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T01:40:15.060Z,1581212415.060 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:40:15.060Z,1581212415.060 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:40:15.060Z,1581212415.060 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:40:15.061Z,1581212415.061 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:40:15.446Z,1581212415.446 [Default:CheckIn:D] Stopped
2020-02-09T01:40:15.446Z,1581212415.446 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.189242 min 
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn:E] Stopped
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn] Stopped
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn](INFO): Running loop #10
2020-02-09T01:40:15.859Z,1581212415.859 [Default:CheckIn] Running Loop=10
2020-02-09T01:40:15.860Z,1581212415.860 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:40:15.860Z,1581212415.860 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:40:17.847Z,1581212417.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014001.00,A,4450.22015,N,06733.87017,W,0.292,305.75,090220,,,A*75
2020-02-09T01:40:17.849Z,1581212417.849 [NAL9602](INFO): GPS fix at 20200209T014001: (44.837002, -67.564503)
2020-02-09T01:40:17.894Z,1581212417.894 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:40:17.894Z,1581212417.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:40:22.209Z,1581212422.209 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:40:22.209Z,1581212422.209 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020818532091,35.0, -0.11448.9,  0
2020-02-09T01:40:27.954Z,1581212427.954 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0024.lzma
2020-02-09T01:40:29.960Z,1581212429.960 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0024.lzma.bak
2020-02-09T01:40:29.961Z,1581212429.961 [DataOverHttps](INFO): SBD MOMSN=12238897
2020-02-09T01:40:47.337Z,1581212447.337 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20200209T004935/Express0025.lzma
2020-02-09T01:40:49.336Z,1581212449.336 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0025.lzma.bak
2020-02-09T01:40:49.336Z,1581212449.336 [DataOverHttps](INFO): SBD MOMSN=12238905
2020-02-09T01:40:50.064Z,1581212450.064 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:40:50.916Z,1581212450.916 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:40:50.916Z,1581212450.916 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:40:50.916Z,1581212450.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:45:34.942Z,1581212734.942 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:45:34.942Z,1581212734.942 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020818583391,35.0, -0.1,   0.,  0
2020-02-09T01:45:51.490Z,1581212751.490 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:45:51.491Z,1581212751.491 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:45:51.491Z,1581212751.491 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:45:51.491Z,1581212751.491 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:45:51.984Z,1581212751.984 [Default:CheckIn:D] Stopped
2020-02-09T01:45:51.984Z,1581212751.984 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:45:52.331Z,1581212752.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.798206 min 
2020-02-09T01:45:52.331Z,1581212752.331 [Default:CheckIn:E] Stopped
2020-02-09T01:45:52.331Z,1581212752.331 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:45:52.331Z,1581212752.331 [Default:CheckIn] Stopped
2020-02-09T01:45:52.331Z,1581212752.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:45:52.332Z,1581212752.332 [Default:CheckIn](INFO): Running loop #11
2020-02-09T01:45:52.332Z,1581212752.332 [Default:CheckIn] Running Loop=11
2020-02-09T01:45:52.332Z,1581212752.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:45:52.332Z,1581212752.332 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:45:54.280Z,1581212754.280 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014537.00,A,4450.23648,N,06733.87997,W,0.117,305.75,090220,,,A*75
2020-02-09T01:45:54.283Z,1581212754.283 [NAL9602](INFO): GPS fix at 20200209T014537: (44.837275, -67.564666)
2020-02-09T01:45:54.388Z,1581212754.388 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:45:54.388Z,1581212754.388 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:46:01.994Z,1581212761.994 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0027.lzma
2020-02-09T01:46:04.000Z,1581212764.000 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0027.lzma.bak
2020-02-09T01:46:04.000Z,1581212764.000 [DataOverHttps](INFO): SBD MOMSN=12238911
2020-02-09T01:46:15.328Z,1581212775.328 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:46:15.328Z,1581212775.328 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T01:46:25.013Z,1581212785.013 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-09T01:46:25.087Z,1581212785.087 [NAL9602](FAULT): received: 
+CSQ:0

OK623, 2, 0, 0, 0

OK
2020-02-09T01:46:25.087Z,1581212785.087 [NAL9602] Data Fault, FailCount= 1
2020-02-09T01:46:25.087Z,1581212785.087 [NAL9602](ERROR): Data Fault
2020-02-09T01:46:25.223Z,1581212785.223 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-09T01:46:25.420Z,1581212785.420 [NAL9602](INFO): Powering down
2020-02-09T01:46:26.040Z,1581212786.040 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200209T004935/Express0028.lzma
2020-02-09T01:46:26.302Z,1581212786.302 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-09T01:46:26.307Z,1581212786.307 [NAL9602] No Fault, FailCount= 1
2020-02-09T01:46:28.032Z,1581212788.032 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0028.lzma.bak
2020-02-09T01:46:28.032Z,1581212788.032 [DataOverHttps](INFO): SBD MOMSN=12238914
2020-02-09T01:46:29.929Z,1581212789.929 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:46:29.929Z,1581212789.929 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:46:29.929Z,1581212789.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:46:55.721Z,1581212815.721 [NAL9602](INFO): Powering up NAL9602
2020-02-09T01:47:06.625Z,1581212826.625 [NAL9602](INFO): NAL9602 initialized
2020-02-09T01:47:37.734Z,1581212857.734 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:49:28.890Z,1581212968.890 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:49:28.890Z,1581212968.890 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-3:8,-32768,-32768,V
2020-02-09T01:50:13.725Z,1581213013.725 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-09T01:50:13.731Z,1581213013.731 [BPC1](INFO): Received data from all battery sticks.
2020-02-09T01:51:30.489Z,1581213090.489 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:51:30.489Z,1581213090.489 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:51:30.489Z,1581213090.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:51:30.489Z,1581213090.489 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:51:30.913Z,1581213090.913 [Default:CheckIn:D] Stopped
2020-02-09T01:51:30.913Z,1581213090.913 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.447026 min 
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn:E] Stopped
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn] Stopped
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn](INFO): Running loop #12
2020-02-09T01:51:31.295Z,1581213091.295 [Default:CheckIn] Running Loop=12
2020-02-09T01:51:31.296Z,1581213091.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:51:31.296Z,1581213091.296 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:51:33.293Z,1581213093.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015116.00,A,4450.21294,N,06733.86888,W,0.661,359.24,090220,,,A*71
2020-02-09T01:51:33.295Z,1581213093.295 [NAL9602](INFO): GPS fix at 20200209T015116: (44.836882, -67.564481)
2020-02-09T01:51:33.319Z,1581213093.319 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:51:33.319Z,1581213093.319 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:51:34.947Z,1581213094.947 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T01:51:34.947Z,1581213094.947 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     ,        +0.00,        +0.00,   0.00,1845.86
2020-02-09T01:51:35.703Z,1581213095.703 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T01:51:41.011Z,1581213101.011 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200209T004935/Courier0030.lzma
2020-02-09T01:51:43.016Z,1581213103.016 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0030.lzma.bak
2020-02-09T01:51:43.016Z,1581213103.016 [DataOverHttps](INFO): SBD MOMSN=12238924
2020-02-09T01:52:00.374Z,1581213120.374 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20200209T004935/Express0031.lzma
2020-02-09T01:52:02.383Z,1581213122.383 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0031.lzma.bak
2020-02-09T01:52:02.383Z,1581213122.383 [DataOverHttps](INFO): SBD MOMSN=12238927
2020-02-09T01:52:04.025Z,1581213124.025 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:52:04.025Z,1581213124.025 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:52:04.025Z,1581213124.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:52:06.412Z,1581213126.412 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T01:52:14.942Z,1581213134.942 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-09T01:53:45.155Z,1581213225.155 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T01:53:45.155Z,1581213225.155 [CommandLine](IMPORTANT): Failed components:
2020-02-09T01:53:45.156Z,1581213225.156 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T01:53:49.895Z,1581213229.895 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T01:53:49.896Z,1581213229.896 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-3:BE,-32768,-32768,-32768,V
2020-02-09T01:54:35.208Z,1581213275.208 [DataOverHttps](IMPORTANT): SBD MTMSN=20200209T015417
2020-02-09T01:54:42.939Z,1581213282.939 [DataOverHttps](INFO): Received command:failc
2020-02-09T01:54:43.035Z,1581213283.035 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T01:54:43.035Z,1581213283.035 [CommandLine](IMPORTANT): Failed components:
2020-02-09T01:54:43.035Z,1581213283.035 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T01:54:55.312Z,1581213295.312 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T01:54:55.312Z,1581213295.312 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819075391,35.0, -0.1, 48.9,  0
2020-02-09T01:56:06.056Z,1581213366.056 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T01:56:06.056Z,1581213366.056 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +     +0.00,        +0.00,   0.00,2116.82
2020-02-09T01:56:46.425Z,1581213406.425 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-09T01:56:46.425Z,1581213406.425 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,32768,-32768,V
2020-02-09T01:57:04.627Z,1581213424.627 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T01:57:04.627Z,1581213424.627 [Default:CheckIn:C.Wait] Stopped
2020-02-09T01:57:04.627Z,1581213424.627 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T01:57:04.627Z,1581213424.627 [Default:CheckIn:D] Running Loop=1
2020-02-09T01:57:05.040Z,1581213425.040 [Default:CheckIn:D] Stopped
2020-02-09T01:57:05.040Z,1581213425.040 [Default:CheckIn:E] Running Loop=1
2020-02-09T01:57:05.428Z,1581213425.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.015808 min 
2020-02-09T01:57:05.428Z,1581213425.428 [Default:CheckIn:E] Stopped
2020-02-09T01:57:05.428Z,1581213425.428 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T01:57:05.428Z,1581213425.428 [Default:CheckIn] Stopped
2020-02-09T01:57:05.428Z,1581213425.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T01:57:05.429Z,1581213425.429 [Default:CheckIn](INFO): Running loop #13
2020-02-09T01:57:05.429Z,1581213425.429 [Default:CheckIn] Running Loop=13
2020-02-09T01:57:05.429Z,1581213425.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T01:57:05.429Z,1581213425.429 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T01:57:07.426Z,1581213427.426 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015651.00,A,4450.22670,N,06733.86992,W,0.156,16.68,090220,,,A*41
2020-02-09T01:57:07.428Z,1581213427.428 [NAL9602](INFO): GPS fix at 20200209T015651: (44.837112, -67.564499)
2020-02-09T01:57:07.489Z,1581213427.489 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T01:57:07.489Z,1581213427.489 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T01:57:16.005Z,1581213436.005 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0033.lzma
2020-02-09T01:57:18.008Z,1581213438.008 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0033.lzma.bak
2020-02-09T01:57:18.008Z,1581213438.008 [DataOverHttps](INFO): SBD MOMSN=12238933
2020-02-09T01:57:35.447Z,1581213455.447 [DataOverHttps](INFO): Sending 319 bytes from file Logs/20200209T004935/Express0034.lzma
2020-02-09T01:57:37.452Z,1581213457.452 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0034.lzma.bak
2020-02-09T01:57:37.453Z,1581213457.453 [DataOverHttps](INFO): SBD MOMSN=12238936
2020-02-09T01:57:54.842Z,1581213474.842 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20200209T004935/Express0037.lzma
2020-02-09T01:57:56.848Z,1581213476.848 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0037.lzma.bak
2020-02-09T01:57:56.848Z,1581213476.848 [DataOverHttps](INFO): SBD MOMSN=12238947
2020-02-09T01:57:58.758Z,1581213478.758 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T01:57:58.759Z,1581213478.759 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T01:57:58.759Z,1581213478.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T01:58:16.906Z,1581213496.906 [NAL9602](INFO): SBD MO Status=2, MOMSN=10623, MT Status=2, MTMSN=0
2020-02-09T01:58:16.906Z,1581213496.906 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T01:58:45.600Z,1581213525.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=10623, MT Status=2, MTMSN=0
2020-02-09T01:58:45.600Z,1581213525.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T01:58:52.094Z,1581213532.094 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-02-09T01:58:52.094Z,1581213532.094 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768.78
2020-02-09T01:59:07.810Z,1581213547.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=10623, MT Status=0, MTMSN=0
2020-02-09T01:59:07.810Z,1581213547.810 [NAL9602](INFO): No messages in MT queue
2020-02-09T01:59:38.518Z,1581213578.518 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:01:08.242Z,1581213668.242 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T02:01:08.242Z,1581213668.242 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,2418.74
2020-02-09T02:02:25.769Z,1581213745.769 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:02:25.770Z,1581213745.770 [Micromodem](INFO): Have incoming data
2020-02-09T02:02:26.191Z,1581213746.191 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-09T02:02:26.191Z,1581213746.191 [Micromodem](ERROR): Could not determine end of data in CARXD:$CARXD,10,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*53

2020-02-09T02:02:26.202Z,1581213746.202 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209020223.771545,06,485,26,0098,0150,89,02,02,00,02,1,010,001,0,3,1,0,150,18.1,5.00,00,-2.00,-01,0.02,32,14500,2000*64

2020-02-09T02:02:33.074Z,1581213753.074 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.00,        +0.00,        +0.00,   0.00,2502.72
2020-02-09T02:02:59.332Z,1581213779.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:02:59.332Z,1581213779.332 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:02:59.332Z,1581213779.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:02:59.332Z,1581213779.332 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:02:59.738Z,1581213779.738 [Default:CheckIn:D] Stopped
2020-02-09T02:02:59.738Z,1581213779.738 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:03:00.155Z,1581213780.155 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.927441 min 
2020-02-09T02:03:00.155Z,1581213780.155 [Default:CheckIn:E] Stopped
2020-02-09T02:03:00.155Z,1581213780.155 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:03:00.155Z,1581213780.155 [Default:CheckIn] Stopped
2020-02-09T02:03:00.155Z,1581213780.155 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:03:00.156Z,1581213780.156 [Default:CheckIn](INFO): Running loop #14
2020-02-09T02:03:00.156Z,1581213780.156 [Default:CheckIn] Running Loop=14
2020-02-09T02:03:00.156Z,1581213780.156 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:03:00.156Z,1581213780.156 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:03:02.132Z,1581213782.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020245.00,A,4450.23117,N,06733.87665,W,0.058,16.68,090220,,,A*48
2020-02-09T02:03:02.134Z,1581213782.134 [NAL9602](INFO): GPS fix at 20200209T020245: (44.837186, -67.564611)
2020-02-09T02:03:02.168Z,1581213782.168 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:03:02.168Z,1581213782.168 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:03:09.690Z,1581213789.690 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0039.lzma
2020-02-09T02:03:11.696Z,1581213791.696 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0039.lzma.bak
2020-02-09T02:03:11.697Z,1581213791.697 [DataOverHttps](INFO): SBD MOMSN=12238957
2020-02-09T02:03:30.002Z,1581213810.002 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200209T004935/Express0040.lzma
2020-02-09T02:03:32.009Z,1581213812.009 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0040.lzma.bak
2020-02-09T02:03:32.010Z,1581213812.010 [DataOverHttps](INFO): SBD MOMSN=12238960
2020-02-09T02:03:33.701Z,1581213813.701 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:03:33.702Z,1581213813.702 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:03:33.702Z,1581213813.702 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:03:52.239Z,1581213832.239 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:03:52.239Z,1581213832.239 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T02:04:07.612Z,1581213847.612 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:04:07.613Z,1581213847.613 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T02:05:54.263Z,1581213954.263 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:05:54.263Z,1581213954.263 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819180, -0.1,   0.0,1448.9,  0
2020-02-09T02:06:26.167Z,1581213986.167 [Micromodem](INFO): Nmea in: $0627,AUV,2.0.32731*1A

2020-02-09T02:06:26.167Z,1581213986.167 [Micromodem](ERROR): Response from modem failed NMEA checksum: $0627,AUV,2.0.32731*1A
 expected 77
2020-02-09T02:06:47.623Z,1581214007.623 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:06:47.623Z,1581214007.623 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2020-02-09T02:07:09.032Z,1581214029.032 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-09T02:07:21.522Z,1581214041.522 [NAL9602](INFO): SBD MO Status=0, MOMSN=10624, MT Status=0, MTMSN=0
2020-02-09T02:07:21.522Z,1581214041.522 [NAL9602](INFO): No messages in MT queue
2020-02-09T02:07:31.240Z,1581214051.240 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00,   0.00
2020-02-09T02:07:52.219Z,1581214072.219 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:08:34.269Z,1581214114.269 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:08:34.269Z,1581214114.269 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:08:34.270Z,1581214114.270 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:08:34.270Z,1581214114.270 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:08:34.675Z,1581214114.675 [Default:CheckIn:D] Stopped
2020-02-09T02:08:34.675Z,1581214114.675 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.509733 min 
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn:E] Stopped
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn] Stopped
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn](INFO): Running loop #15
2020-02-09T02:08:35.093Z,1581214115.093 [Default:CheckIn] Running Loop=15
2020-02-09T02:08:35.094Z,1581214115.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:08:35.094Z,1581214115.094 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:08:37.071Z,1581214117.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020820.00,A,4450.23200,N,06733.86941,W,0.292,154.33,090220,,,A*71
2020-02-09T02:08:37.073Z,1581214117.073 [NAL9602](INFO): GPS fix at 20200209T020820: (44.837200, -67.564490)
2020-02-09T02:08:37.128Z,1581214117.128 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:08:37.128Z,1581214117.128 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:08:44.342Z,1581214124.342 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200209T004935/Courier0042.lzma
2020-02-09T02:08:46.348Z,1581214126.348 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0042.lzma.bak
2020-02-09T02:08:46.348Z,1581214126.348 [DataOverHttps](INFO): SBD MOMSN=12238963
2020-02-09T02:08:50.873Z,1581214130.873 [NAL9602](INFO): SBD MO Status=2, MOMSN=10625, MT Status=2, MTMSN=0
2020-02-09T02:08:50.873Z,1581214130.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T02:09:05.399Z,1581214145.399 [NAL9602](INFO): SBD MO Status=2, MOMSN=10625, MT Status=2, MTMSN=0
2020-02-09T02:09:05.399Z,1581214145.399 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T02:09:10.998Z,1581214150.998 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200209T004935/Express0043.lzma
2020-02-09T02:09:13.005Z,1581214153.005 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0043.lzma.bak
2020-02-09T02:09:13.005Z,1581214153.005 [DataOverHttps](INFO): SBD MOMSN=12238967
2020-02-09T02:09:14.711Z,1581214154.711 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:09:14.712Z,1581214154.712 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:09:14.712Z,1581214154.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:09:49.892Z,1581214189.892 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T02:09:49.892Z,1581214189.892 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +     +0.00,   0.00,2940.62
2020-02-09T02:09:57.106Z,1581214197.106 [NAL9602](INFO): SBD MO Status=2, MOMSN=10625, MT Status=2, MTMSN=0
2020-02-09T02:09:57.116Z,1581214197.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T02:10:14.899Z,1581214214.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=10625, MT Status=2, MTMSN=0
2020-02-09T02:10:14.899Z,1581214214.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-09T02:10:15.323Z,1581214215.323 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:10:15.323Z,1581214215.323 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   I,-32768,-32768,-32768,-32768,V
2020-02-09T02:10:27.806Z,1581214227.806 [NAL9602](INFO): SBD MO Status=0, MOMSN=10625, MT Status=0, MTMSN=0
2020-02-09T02:10:27.806Z,1581214227.806 [NAL9602](INFO): No messages in MT queue
2020-02-09T02:10:29.886Z,1581214229.886 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:10:29.886Z,1581214229.886 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819232891,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-09T02:10:58.507Z,1581214258.507 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:11:15.102Z,1581214275.102 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-09T02:13:31.241Z,1581214411.241 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:13:31.242Z,1581214411.242 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819262991,35.0, -0.1,448.9,  0
2020-02-09T02:13:54.276Z,1581214434.276 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:13:54.276Z,1581214434.276 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T02:14:15.304Z,1581214455.304 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:14:15.304Z,1581214455.304 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:14:15.304Z,1581214455.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:14:15.304Z,1581214455.304 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:14:15.698Z,1581214455.698 [Default:CheckIn:D] Stopped
2020-02-09T02:14:15.698Z,1581214455.698 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:14:16.130Z,1581214456.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.193449 min 
2020-02-09T02:14:16.130Z,1581214456.130 [Default:CheckIn:E] Stopped
2020-02-09T02:14:16.130Z,1581214456.130 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:14:16.131Z,1581214456.131 [Default:CheckIn] Stopped
2020-02-09T02:14:16.131Z,1581214456.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:14:16.131Z,1581214456.131 [Default:CheckIn](INFO): Running loop #16
2020-02-09T02:14:16.131Z,1581214456.131 [Default:CheckIn] Running Loop=16
2020-02-09T02:14:16.131Z,1581214456.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:14:16.131Z,1581214456.131 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:14:18.099Z,1581214458.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021401.00,A,4450.22482,N,06733.87812,W,0.233,154.33,090220,,,A*7F
2020-02-09T02:14:18.101Z,1581214458.101 [NAL9602](INFO): GPS fix at 20200209T021401: (44.837080, -67.564635)
2020-02-09T02:14:18.167Z,1581214458.167 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:14:18.167Z,1581214458.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:14:27.898Z,1581214467.898 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0045.lzma
2020-02-09T02:14:29.904Z,1581214469.904 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0045.lzma.bak
2020-02-09T02:14:29.904Z,1581214469.904 [DataOverHttps](INFO): SBD MOMSN=12238981
2020-02-09T02:14:30.240Z,1581214470.240 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-09T02:14:52.378Z,1581214492.378 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200209T004935/Express0046.lzma
2020-02-09T02:14:54.384Z,1581214494.384 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0046.lzma.bak
2020-02-09T02:14:54.384Z,1581214494.384 [DataOverHttps](INFO): SBD MOMSN=12238984
2020-02-09T02:14:56.115Z,1581214496.115 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:14:56.115Z,1581214496.115 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:14:56.115Z,1581214496.115 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:16:52.844Z,1581214612.844 [NAL9602](INFO): SBD MO Status=0, MOMSN=10626, MT Status=0, MTMSN=0
2020-02-09T02:16:52.844Z,1581214612.844 [NAL9602](INFO): No messages in MT queue
2020-02-09T02:17:23.537Z,1581214643.537 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:19:56.696Z,1581214796.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:19:56.696Z,1581214796.696 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:19:56.696Z,1581214796.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:19:56.697Z,1581214796.697 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:19:57.087Z,1581214797.087 [Default:CheckIn:D] Stopped
2020-02-09T02:19:57.087Z,1581214797.087 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.883260 min 
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn:E] Stopped
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn] Stopped
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn](INFO): Running loop #17
2020-02-09T02:19:57.507Z,1581214797.507 [Default:CheckIn] Running Loop=17
2020-02-09T02:19:57.508Z,1581214797.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:19:57.508Z,1581214797.508 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:19:59.487Z,1581214799.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021943.00,A,4450.22106,N,06733.88096,W,0.078,154.33,090220,,,A*7B
2020-02-09T02:19:59.489Z,1581214799.489 [NAL9602](INFO): GPS fix at 20200209T021943: (44.837018, -67.564683)
2020-02-09T02:19:59.540Z,1581214799.540 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:19:59.540Z,1581214799.540 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:20:07.614Z,1581214807.614 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0048.lzma
2020-02-09T02:20:09.620Z,1581214809.620 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0048.lzma.bak
2020-02-09T02:20:09.620Z,1581214809.620 [DataOverHttps](INFO): SBD MOMSN=12238989
2020-02-09T02:20:28.520Z,1581214828.520 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200209T004935/Express0049.lzma
2020-02-09T02:20:30.524Z,1581214830.524 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0049.lzma.bak
2020-02-09T02:20:30.524Z,1581214830.524 [DataOverHttps](INFO): SBD MOMSN=12238992
2020-02-09T02:20:32.245Z,1581214832.245 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:20:32.246Z,1581214832.246 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:20:32.246Z,1581214832.246 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:22:37.489Z,1581214957.489 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadC1
2020-02-09T02:23:58.719Z,1581215038.719 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:23:58.719Z,1581215038.719 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:23:58.719Z,1581215038.719 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T02:25:02.093Z,1581215102.093 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T02:25:32.800Z,1581215132.800 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:25:32.874Z,1581215132.874 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:25:32.874Z,1581215132.874 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:25:32.879Z,1581215132.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:25:32.879Z,1581215132.879 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:25:33.238Z,1581215133.238 [Default:CheckIn:D] Stopped
2020-02-09T02:25:33.238Z,1581215133.238 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:25:33.643Z,1581215133.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.485783 min 
2020-02-09T02:25:33.643Z,1581215133.643 [Default:CheckIn:E] Stopped
2020-02-09T02:25:33.643Z,1581215133.643 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:25:33.643Z,1581215133.643 [Default:CheckIn] Stopped
2020-02-09T02:25:33.643Z,1581215133.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:25:33.644Z,1581215133.644 [Default:CheckIn](INFO): Running loop #18
2020-02-09T02:25:33.644Z,1581215133.644 [Default:CheckIn] Running Loop=18
2020-02-09T02:25:33.644Z,1581215133.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:25:33.644Z,1581215133.644 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:25:35.630Z,1581215135.630 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022519.00,A,4450.22445,N,06733.87440,W,0.467,294.23,090220,,,A*7D
2020-02-09T02:25:35.632Z,1581215135.632 [NAL9602](INFO): GPS fix at 20200209T022519: (44.837074, -67.564573)
2020-02-09T02:25:35.681Z,1581215135.681 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:25:35.681Z,1581215135.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:25:43.022Z,1581215143.022 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20200209T004935/Courier0051.lzma
2020-02-09T02:25:45.029Z,1581215145.029 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0051.lzma.bak
2020-02-09T02:25:45.029Z,1581215145.029 [DataOverHttps](INFO): SBD MOMSN=12239003
2020-02-09T02:26:02.739Z,1581215162.739 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20200209T004935/Express0052.lzma
2020-02-09T02:26:04.744Z,1581215164.744 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0052.lzma.bak
2020-02-09T02:26:04.744Z,1581215164.744 [DataOverHttps](INFO): SBD MOMSN=12239006
2020-02-09T02:26:06.426Z,1581215166.426 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-09T02:26:06.507Z,1581215166.507 [NAL9602](FAULT): received: 
+CSQ:0

OK626, 0, 0, 0, 0

OK
2020-02-09T02:26:06.507Z,1581215166.507 [NAL9602] Data Fault, FailCount= 1
2020-02-09T02:26:06.507Z,1581215166.507 [NAL9602](ERROR): Data Fault
2020-02-09T02:26:06.586Z,1581215166.586 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:26:06.586Z,1581215166.586 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:26:06.586Z,1581215166.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:26:06.608Z,1581215166.608 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-09T02:26:06.835Z,1581215166.835 [NAL9602](INFO): Powering down
2020-02-09T02:26:07.241Z,1581215167.241 [NAL9602](FAULT): LCB fault: LCB Watchdog Reset. Hardware Overcurrent Shutdown.
2020-02-09T02:26:07.241Z,1581215167.241 [NAL9602] Hardware Fault, FailCount= 1
2020-02-09T02:26:07.241Z,1581215167.241 [NAL9602](ERROR): Hardware Fault
2020-02-09T02:26:07.697Z,1581215167.697 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-09T02:26:07.697Z,1581215167.697 [NAL9602] No Fault, FailCount= 1
2020-02-09T02:26:37.129Z,1581215197.129 [NAL9602](INFO): Powering up NAL9602
2020-02-09T02:26:48.037Z,1581215208.037 [NAL9602](INFO): NAL9602 initialized
2020-02-09T02:27:19.145Z,1581215239.145 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:27:29.270Z,1581215249.270 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:27:29.271Z,1581215249.271 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819402791,35.0, -0.1,   0.,  0
2020-02-09T02:30:21.904Z,1581215421.904 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-09T02:30:21.905Z,1581215421.905 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,32768,V
2020-02-09T02:30:31.584Z,1581215431.584 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC1
2020-02-09T02:31:07.052Z,1581215467.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:31:07.053Z,1581215467.053 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:31:07.053Z,1581215467.053 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:31:07.053Z,1581215467.053 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:31:07.487Z,1581215467.487 [Default:CheckIn:D] Stopped
2020-02-09T02:31:07.488Z,1581215467.488 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:31:07.865Z,1581215467.865 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.056600 min 
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn:E] Stopped
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn] Stopped
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn](INFO): Running loop #19
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn] Running Loop=19
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:31:07.866Z,1581215467.866 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:31:09.885Z,1581215469.885 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023052.00,A,4450.23114,N,06733.85480,W,0.369,0.00,090220,,,A*7F
2020-02-09T02:31:09.888Z,1581215469.888 [NAL9602](INFO): GPS fix at 20200209T023052: (44.837186, -67.564247)
2020-02-09T02:31:09.945Z,1581215469.945 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:31:09.945Z,1581215469.945 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:31:11.886Z,1581215471.886 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T02:31:17.205Z,1581215477.205 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200209T004935/Courier0054.lzma
2020-02-09T02:31:19.204Z,1581215479.204 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0054.lzma.bak
2020-02-09T02:31:19.204Z,1581215479.204 [DataOverHttps](INFO): SBD MOMSN=12239014
2020-02-09T02:31:27.281Z,1581215487.281 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-09T02:31:38.079Z,1581215498.079 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20200209T004935/Express0055.lzma
2020-02-09T02:31:40.084Z,1581215500.084 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0055.lzma.bak
2020-02-09T02:31:40.084Z,1581215500.084 [DataOverHttps](INFO): SBD MOMSN=12239017
2020-02-09T02:31:41.802Z,1581215501.802 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:31:41.803Z,1581215501.803 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:31:41.803Z,1581215501.803 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:31:42.577Z,1581215502.577 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:32:45.650Z,1581215565.650 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadC1
2020-02-09T02:33:26.444Z,1581215606.444 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:33:26.445Z,1581215606.445 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-3:BE,-32768,-32768,-32768,V
2020-02-09T02:35:26.423Z,1581215726.423 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:35:26.423Z,1581215726.423 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208195.0, -0.1,   0.0,1448.9,  0
2020-02-09T02:35:37.873Z,1581215737.873 [CommandLine](IMPORTANT): got command ! echo i > /dev/loadC1
2020-02-09T02:35:50.261Z,1581215750.261 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:35:50.261Z,1581215750.261 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208194.0, -0.1,   0.0,1448.9,  0
2020-02-09T02:36:42.390Z,1581215802.390 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:36:42.391Z,1581215802.391 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:36:42.391Z,1581215802.391 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:36:42.391Z,1581215802.391 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:36:42.785Z,1581215802.785 [Default:CheckIn:D] Stopped
2020-02-09T02:36:42.785Z,1581215802.785 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:36:43.202Z,1581215803.202 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.644889 min 
2020-02-09T02:36:43.202Z,1581215803.202 [Default:CheckIn:E] Stopped
2020-02-09T02:36:43.202Z,1581215803.202 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:36:43.202Z,1581215803.202 [Default:CheckIn] Stopped
2020-02-09T02:36:43.202Z,1581215803.202 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:36:43.203Z,1581215803.203 [Default:CheckIn](INFO): Running loop #20
2020-02-09T02:36:43.203Z,1581215803.203 [Default:CheckIn] Running Loop=20
2020-02-09T02:36:43.203Z,1581215803.203 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:36:43.203Z,1581215803.203 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:36:45.187Z,1581215805.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,023627.00,A,4450.22490,N,06733.87083,W,0.078,10.51,090220,,,A*40
2020-02-09T02:36:45.189Z,1581215805.189 [NAL9602](INFO): GPS fix at 20200209T023627: (44.837082, -67.564514)
2020-02-09T02:36:45.260Z,1581215805.260 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:36:45.260Z,1581215805.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:36:53.290Z,1581215813.290 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0057.lzma
2020-02-09T02:36:55.297Z,1581215815.297 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0057.lzma.bak
2020-02-09T02:36:55.297Z,1581215815.297 [DataOverHttps](INFO): SBD MOMSN=12239030
2020-02-09T02:37:12.771Z,1581215832.771 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20200209T004935/Express0058.lzma
2020-02-09T02:37:14.776Z,1581215834.776 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0058.lzma.bak
2020-02-09T02:37:14.776Z,1581215834.776 [DataOverHttps](INFO): SBD MOMSN=12239033
2020-02-09T02:37:16.730Z,1581215836.730 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:37:16.731Z,1581215836.731 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:37:16.731Z,1581215836.731 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:37:35.300Z,1581215855.300 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:37:35.300Z,1581215855.300 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819503391,35.0, -0.1, 48.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-09T02:38:08.014Z,1581215888.014 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:38:08.015Z,1581215888.015 [Micromodem](INFO): Have incoming data
2020-02-09T02:38:08.016Z,1581215888.016 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,1*20

2020-02-09T02:38:08.407Z,1581215888.407 [Micromodem](INFO): Nmea in: $CACST,6,1,20200209023807.460627,06,292,24,0103,0150,89,02,02,00,02,1,010,001,3,3,1,1,150,8.6,2.00,00,1.00,-01,0.07,37,14500,4000*74

2020-02-09T02:38:17.697Z,1581215897.697 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2020-02-09T02:38:17.702Z,1581215897.702 [Micromodem](INFO): Nmea in: $CACST,6,1,20200209023818.169102,06,258,24,0099,0150,119,02,02,00,03,-1,-01,-01,1,3,2,0,150,17.7,-3.00,00,6.00,-01,17.85,38,14500,4000*41

2020-02-09T02:38:23.904Z,1581215903.904 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:38:23.905Z,1581215903.905 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819512291,31,   0.0,1448.9,  0
2020-02-09T02:38:29.413Z,1581215909.413 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:38:29.413Z,1581215909.413 [Micromodem](INFO): Have incoming data
2020-02-09T02:38:29.415Z,1581215909.415 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,1*20

2020-02-09T02:38:29.824Z,1581215909.824 [Micromodem](INFO): Nmea in: $CACST,6,1,20200209023828.794925,06,292,24,0102,0150,89,02,02,00,02,1,010,001,3,3,1,1,150,12.7,1.00,00,2.00,-01,0.60,33,14500,4000*42

2020-02-09T02:38:33.909Z,1581215913.909 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:38:33.909Z,1581215913.909 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-02-09T02:39:59.908Z,1581215999.908 [Micromodem](FAULT): Failed to receive CAREV within timeout
2020-02-09T02:39:59.908Z,1581215999.908 [Micromodem] Hardware Fault, FailCount= 1
2020-02-09T02:39:59.908Z,1581215999.908 [Micromodem](ERROR): Hardware Fault
2020-02-09T02:39:59.980Z,1581215999.980 [CBIT](ERROR): Hardware Fault in component: Micromodem
2020-02-09T02:40:00.321Z,1581216000.321 [Micromodem](INFO): Powering down
2020-02-09T02:40:01.181Z,1581216001.181 [CBIT](INFO): Clearing failed state for component Micromodem
2020-02-09T02:40:01.181Z,1581216001.181 [Micromodem] No Fault, FailCount= 1
2020-02-09T02:40:03.946Z,1581216003.946 [Micromodem](INFO): Powering up
2020-02-09T02:40:03.946Z,1581216003.946 [Micromodem](DEBUG): Initializing Micromodem.
2020-02-09T02:40:08.797Z,1581216008.797 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-02-09T02:40:10.816Z,1581216010.816 [Micromodem](INFO): Nmea in: $CATMG,2020-02-09T02:40:10.301706Z,RTC,RTC*5F

2020-02-09T02:40:10.816Z,1581216010.816 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-02-09T02:40:10.301706Z,RTC,RTC*5F

2020-02-09T02:40:11.239Z,1581216011.239 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-02-09T02:40:11.239Z,1581216011.239 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-09T02:40:11.239Z,1581216011.239 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-09T02:40:11.263Z,1581216011.263 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-09T02:40:11.707Z,1581216011.707 [RDI_Pathfinder](INFO): Powering down
2020-02-09T02:40:12.281Z,1581216012.281 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:40:12.281Z,1581216012.281 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:40:12.281Z,1581216012.281 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2020-02-09T02:40:12.510Z,1581216012.510 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-09T02:40:12.510Z,1581216012.510 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-09T02:40:20.132Z,1581216020.132 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-02-09T02:40:20.537Z,1581216020.537 [Micromodem](INFO): Nmea in: $CAERR,024021,NI  ,12,Unknown command*49

2020-02-09T02:40:20.537Z,1581216020.537 [Micromodem](ERROR): Got error from modem: $CAERR,024021,NI  ,12,Unknown command*49

2020-02-09T02:40:25.814Z,1581216025.814 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-09T02:40:31.452Z,1581216031.452 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-02-09T02:40:31.850Z,1581216031.850 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-02-09T02:40:31.851Z,1581216031.851 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-02-09T02:40:32.256Z,1581216032.256 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-02-09T02:40:32.257Z,1581216032.257 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-02-09T02:40:32.660Z,1581216032.660 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-02-09T02:40:32.661Z,1581216032.661 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-02-09T02:40:33.068Z,1581216033.068 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-02-09T02:40:33.069Z,1581216033.069 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-02-09T02:40:33.467Z,1581216033.467 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-02-09T02:40:33.467Z,1581216033.467 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-02-09T02:40:33.872Z,1581216033.872 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-02-09T02:40:33.872Z,1581216033.872 [Micromodem](INFO): Nmea out: $CCCFG,BW0,2000*65

2020-02-09T02:40:34.286Z,1581216034.286 [Micromodem](INFO): Nmea in: $CACFG,BW0,2000*67

2020-02-09T02:40:34.286Z,1581216034.286 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-02-09T02:40:34.684Z,1581216034.684 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-02-09T02:40:34.685Z,1581216034.685 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,09,02,40,35*4F

2020-02-09T02:40:35.083Z,1581216035.083 [Micromodem](INFO): Nmea in: $CACLK,2020,2,9,2,40,35*7D

2020-02-09T02:40:35.893Z,1581216035.893 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-09T02:40:36Z*7E

2020-02-09T02:40:35.894Z,1581216035.894 [Micromodem](INFO): Nmea in: $CATMG,2020-02-09T02:40:36.026274Z,USER_CMD,RTC*1C

2020-02-09T02:40:53.251Z,1581216053.251 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:40:53.251Z,1581216053.251 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:40:53.251Z,1581216053.251 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T02:41:46.997Z,1581216106.997 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T02:42:17.334Z,1581216137.334 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:42:17.335Z,1581216137.335 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:42:17.335Z,1581216137.335 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:42:17.335Z,1581216137.335 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:42:17.731Z,1581216137.731 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:42:17.853Z,1581216137.853 [Default:CheckIn:D] Stopped
2020-02-09T02:42:17.854Z,1581216137.854 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:42:18.138Z,1581216138.138 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.229370 min 
2020-02-09T02:42:18.138Z,1581216138.138 [Default:CheckIn:E] Stopped
2020-02-09T02:42:18.138Z,1581216138.138 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:42:18.167Z,1581216138.167 [Default:CheckIn] Stopped
2020-02-09T02:42:18.167Z,1581216138.167 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:42:18.167Z,1581216138.167 [Default:CheckIn](INFO): Running loop #21
2020-02-09T02:42:18.167Z,1581216138.167 [Default:CheckIn] Running Loop=21
2020-02-09T02:42:18.167Z,1581216138.167 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:42:18.167Z,1581216138.167 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:42:20.125Z,1581216140.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024203.00,A,4450.22718,N,06733.87998,W,0.078,342.30,090220,,,A*76
2020-02-09T02:42:20.127Z,1581216140.127 [NAL9602](INFO): GPS fix at 20200209T024203: (44.837120, -67.564666)
2020-02-09T02:42:20.154Z,1581216140.154 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:42:20.154Z,1581216140.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:42:29.530Z,1581216149.530 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0060.lzma
2020-02-09T02:42:31.536Z,1581216151.536 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0060.lzma.bak
2020-02-09T02:42:31.536Z,1581216151.536 [DataOverHttps](INFO): SBD MOMSN=12239039
2020-02-09T02:42:49.151Z,1581216169.151 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20200209T004935/Express0061.lzma
2020-02-09T02:42:50.838Z,1581216170.838 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-09T02:42:50.919Z,1581216170.919 [NAL9602](FAULT): received: 
+CSQ:0

OK626, 0, 0, 0, 0

OK
2020-02-09T02:42:50.919Z,1581216170.919 [NAL9602] Data Fault, FailCount= 1
2020-02-09T02:42:50.919Z,1581216170.919 [NAL9602](ERROR): Data Fault
2020-02-09T02:42:51.028Z,1581216171.028 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-09T02:42:51.156Z,1581216171.156 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0061.lzma.bak
2020-02-09T02:42:51.156Z,1581216171.156 [DataOverHttps](INFO): SBD MOMSN=12239043
2020-02-09T02:42:51.233Z,1581216171.233 [NAL9602](INFO): Powering down
2020-02-09T02:42:52.103Z,1581216172.103 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-09T02:42:52.103Z,1581216172.103 [NAL9602] No Fault, FailCount= 1
2020-02-09T02:42:52.910Z,1581216172.910 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:42:52.910Z,1581216172.910 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:42:52.910Z,1581216172.910 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:43:21.537Z,1581216201.537 [NAL9602](INFO): Powering up NAL9602
2020-02-09T02:43:32.449Z,1581216212.449 [NAL9602](INFO): NAL9602 initialized
2020-02-09T02:44:03.549Z,1581216243.549 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:44:55.714Z,1581216295.714 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:44:55.714Z,1581216295.714 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020819575472,35.0, -0.1,   0.,  0
2020-02-09T02:45:36.094Z,1581216336.094 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:45:36.094Z,1581216336.094 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T02:45:41.773Z,1581216341.773 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-09T02:46:16.082Z,1581216376.082 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:46:16.083Z,1581216376.083 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T02:47:16.709Z,1581216436.709 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-09T02:47:16.710Z,1581216436.710 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2020-02-09T02:47:53.467Z,1581216473.467 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:47:53.467Z,1581216473.467 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:47:53.467Z,1581216473.467 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:47:53.467Z,1581216473.467 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:47:53.867Z,1581216473.867 [Default:CheckIn:D] Stopped
2020-02-09T02:47:53.867Z,1581216473.867 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:47:54.283Z,1581216474.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.829590 min 
2020-02-09T02:47:54.283Z,1581216474.283 [Default:CheckIn:E] Stopped
2020-02-09T02:47:54.283Z,1581216474.283 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:47:54.283Z,1581216474.283 [Default:CheckIn] Stopped
2020-02-09T02:47:54.284Z,1581216474.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:47:54.284Z,1581216474.284 [Default:CheckIn](INFO): Running loop #22
2020-02-09T02:47:54.284Z,1581216474.284 [Default:CheckIn] Running Loop=22
2020-02-09T02:47:54.284Z,1581216474.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:47:54.284Z,1581216474.284 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:47:56.262Z,1581216476.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024738.00,A,4450.22914,N,06733.86935,W,0.253,304.90,090220,,,A*7C
2020-02-09T02:47:56.264Z,1581216476.264 [NAL9602](INFO): GPS fix at 20200209T024738: (44.837152, -67.564489)
2020-02-09T02:47:56.313Z,1581216476.313 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:47:56.313Z,1581216476.313 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:47:58.699Z,1581216478.699 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T02:48:03.682Z,1581216483.682 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0063.lzma
2020-02-09T02:48:05.691Z,1581216485.691 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0063.lzma.bak
2020-02-09T02:48:05.692Z,1581216485.692 [DataOverHttps](INFO): SBD MOMSN=12239060
2020-02-09T02:48:13.246Z,1581216493.246 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:48:13.246Z,1581216493.246 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208205.0, -0.1,   0.0,1448.9,  0
2020-02-09T02:48:26.558Z,1581216506.558 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20200209T004935/Express0064.lzma
2020-02-09T02:48:28.564Z,1581216508.564 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0064.lzma.bak
2020-02-09T02:48:28.565Z,1581216508.565 [DataOverHttps](INFO): SBD MOMSN=12239063
2020-02-09T02:48:28.787Z,1581216508.787 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-02-09T02:48:28.787Z,1581216508.787 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-09T02:48:28.787Z,1581216508.787 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-09T02:48:28.787Z,1581216508.787 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-09T02:48:28.866Z,1581216508.866 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-09T02:48:29.151Z,1581216509.151 [RDI_Pathfinder](INFO): Powering down
2020-02-09T02:48:29.473Z,1581216509.473 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:48:29.935Z,1581216509.935 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-09T02:48:29.935Z,1581216509.935 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-09T02:48:30.307Z,1581216510.307 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:48:30.307Z,1581216510.307 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:48:30.307Z,1581216510.307 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:48:43.231Z,1581216523.231 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-09T02:49:49.075Z,1581216589.075 [Micromodem](INFO): Nmea in: V,024949,AUV,2.0.32731*19

2020-02-09T02:49:49.075Z,1581216589.075 [Micromodem](ERROR): Response from modem failed NMEA checksum: V,024949,AUV,2.0.32731*19
 expected 0
2020-02-09T02:50:27.487Z,1581216627.487 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:50:27.487Z,1581216627.487 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020820030, -0.1,   0.0,1448.9,  0
2020-02-09T02:51:07.128Z,1581216667.128 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-09T02:51:07.128Z,1581216667.128 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3268,V
2020-02-09T02:51:26.504Z,1581216686.504 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-09T02:51:26.504Z,1581216686.504 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020820042515,35.0, -0.1,   0.0,10
2020-02-09T02:51:28.539Z,1581216688.539 [RDI_Pathfinder](ERROR): Failed to parse:
:R,   0.00,   0.00,   0.00
2020-02-09T02:52:02.873Z,1581216722.873 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-09T02:52:02.873Z,1581216722.873 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-09T02:53:30.937Z,1581216810.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:53:30.937Z,1581216810.937 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:53:30.937Z,1581216810.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:53:30.938Z,1581216810.938 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:53:31.351Z,1581216811.351 [Default:CheckIn:D] Stopped
2020-02-09T02:53:31.351Z,1581216811.351 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:53:31.763Z,1581216811.763 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.454329 min 
2020-02-09T02:53:31.763Z,1581216811.763 [Default:CheckIn:E] Stopped
2020-02-09T02:53:31.763Z,1581216811.763 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:53:31.763Z,1581216811.763 [Default:CheckIn] Stopped
2020-02-09T02:53:31.764Z,1581216811.764 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:53:31.764Z,1581216811.764 [Default:CheckIn](INFO): Running loop #23
2020-02-09T02:53:31.764Z,1581216811.764 [Default:CheckIn] Running Loop=23
2020-02-09T02:53:31.764Z,1581216811.764 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:53:31.764Z,1581216811.764 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:53:33.742Z,1581216813.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,025317.00,A,4450.22610,N,06733.87137,W,0.175,261.66,090220,,,A*78
2020-02-09T02:53:33.745Z,1581216813.745 [NAL9602](INFO): GPS fix at 20200209T025317: (44.837102, -67.564523)
2020-02-09T02:53:33.790Z,1581216813.790 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:53:33.790Z,1581216813.790 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:53:41.910Z,1581216821.910 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20200209T004935/Courier0066.lzma
2020-02-09T02:53:43.916Z,1581216823.916 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0066.lzma.bak
2020-02-09T02:53:43.916Z,1581216823.916 [DataOverHttps](INFO): SBD MOMSN=12239067
2020-02-09T02:54:01.658Z,1581216841.658 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20200209T004935/Express0067.lzma
2020-02-09T02:54:03.665Z,1581216843.665 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0067.lzma.bak
2020-02-09T02:54:03.665Z,1581216843.665 [DataOverHttps](INFO): SBD MOMSN=12239070
2020-02-09T02:54:05.283Z,1581216845.283 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:54:05.284Z,1581216845.284 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:54:05.284Z,1581216845.284 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:54:27.131Z,1581216867.131 [RDI_Pathfinder](ERROR): Failed to parse:0.00,   0.00,   0.00
2020-02-09T02:55:13.533Z,1581216913.533 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:55:13.533Z,1581216913.533 [Micromodem](INFO): Have incoming data
2020-02-09T02:55:13.537Z,1581216913.537 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T02:55:13.579Z,1581216913.579 [Micromodem](INFO): Received command:failc
2020-02-09T02:55:13.627Z,1581216913.627 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:55:13.627Z,1581216913.627 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:55:13.627Z,1581216913.627 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T02:55:13.932Z,1581216913.932 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209025510.433898,06,235,11,0110,0150,37,04,04,02,04,1,010,001,0,3,1,0,150,11.5,3.00,00,0.00,-01,0.01,23,10000,2000*41

2020-02-09T02:57:41.472Z,1581217061.472 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-09T02:57:41.486Z,1581217061.486 [BPC1](INFO): Received data from all battery sticks.
2020-02-09T02:57:53.158Z,1581217073.158 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T02:57:53.158Z,1581217073.158 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +     +0.00,        +0.00,   0.00,549.99
2020-02-09T02:58:01.189Z,1581217081.189 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:58:01.189Z,1581217081.189 [Micromodem](INFO): Have incoming data
2020-02-09T02:58:01.192Z,1581217081.192 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T02:58:01.193Z,1581217081.193 [Micromodem](INFO): Received command:failc
2020-02-09T02:58:01.279Z,1581217081.279 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:58:01.279Z,1581217081.279 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:58:01.279Z,1581217081.279 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T02:58:01.603Z,1581217081.603 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209025757.891749,06,1100,24,0300,0150,89,02,02,01,03,1,010,001,0,3,1,0,150,19.8,9.00,00,-6.00,-01,0.01,31,10000,2000*50

2020-02-09T02:58:11.693Z,1581217091.693 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:58:11.693Z,1581217091.693 [Micromodem](INFO): Have incoming data
2020-02-09T02:58:11.696Z,1581217091.696 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T02:58:11.697Z,1581217091.697 [Micromodem](INFO): Received command:failc
2020-02-09T02:58:11.791Z,1581217091.791 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:58:11.791Z,1581217091.791 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:58:11.791Z,1581217091.791 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T02:58:12.100Z,1581217092.100 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209025808.416021,06,68,12,0097,0150,59,01,01,01,03,1,010,001,0,3,1,0,150,20.0,9.00,00,-6.00,-01,0.01,26,10000,2000*50

2020-02-09T02:58:35.961Z,1581217115.961 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-09T02:59:05.896Z,1581217145.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-09T02:59:05.896Z,1581217145.896 [Default:CheckIn:C.Wait] Stopped
2020-02-09T02:59:05.896Z,1581217145.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T02:59:05.896Z,1581217145.896 [Default:CheckIn:D] Running Loop=1
2020-02-09T02:59:06.319Z,1581217146.319 [Default:CheckIn:D] Stopped
2020-02-09T02:59:06.319Z,1581217146.319 [Default:CheckIn:E] Running Loop=1
2020-02-09T02:59:06.665Z,1581217146.665 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T02:59:06.715Z,1581217146.715 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.036857 min 
2020-02-09T02:59:06.715Z,1581217146.715 [Default:CheckIn:E] Stopped
2020-02-09T02:59:06.715Z,1581217146.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-02-09T02:59:06.715Z,1581217146.715 [Default:CheckIn] Stopped
2020-02-09T02:59:06.715Z,1581217146.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T02:59:06.716Z,1581217146.716 [Default:CheckIn](INFO): Running loop #24
2020-02-09T02:59:06.716Z,1581217146.716 [Default:CheckIn] Running Loop=24
2020-02-09T02:59:06.716Z,1581217146.716 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-02-09T02:59:06.716Z,1581217146.716 [Default:CheckIn:Read_GPS] Running Loop=1
2020-02-09T02:59:08.686Z,1581217148.686 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,025852.00,A,4450.22655,N,06733.87754,W,0.078,196.31,090220,,,A*75
2020-02-09T02:59:08.688Z,1581217148.688 [NAL9602](INFO): GPS fix at 20200209T025852: (44.837109, -67.564626)
2020-02-09T02:59:08.733Z,1581217148.733 [Default:CheckIn:Read_GPS] Stopped
2020-02-09T02:59:08.733Z,1581217148.733 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-02-09T02:59:16.798Z,1581217156.798 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200209T004935/Courier0069.lzma
2020-02-09T02:59:18.806Z,1581217158.806 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Courier0069.lzma.bak
2020-02-09T02:59:18.806Z,1581217158.806 [DataOverHttps](INFO): SBD MOMSN=12239081
2020-02-09T02:59:36.203Z,1581217176.203 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20200209T004935/Express0070.lzma
2020-02-09T02:59:38.208Z,1581217178.208 [DataOverHttps](INFO): Moved sent file to Logs/20200209T004935/Express0070.lzma.bak
2020-02-09T02:59:38.209Z,1581217178.209 [DataOverHttps](INFO): SBD MOMSN=12239084
2020-02-09T02:59:39.389Z,1581217179.389 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-09T02:59:39.463Z,1581217179.463 [NAL9602](FAULT): received: 
+CSQ:0

OK626, 0, 0, 0, 0

OK
2020-02-09T02:59:39.463Z,1581217179.463 [NAL9602] Data Fault, FailCount= 1
2020-02-09T02:59:39.463Z,1581217179.463 [NAL9602](ERROR): Data Fault
2020-02-09T02:59:39.623Z,1581217179.623 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-09T02:59:39.789Z,1581217179.789 [NAL9602](INFO): Powering down
2020-02-09T02:59:40.253Z,1581217180.253 [Default:CheckIn:Read_Iridium] Stopped
2020-02-09T02:59:40.253Z,1581217180.253 [Default:CheckIn:C.Wait] Running Loop=1
2020-02-09T02:59:40.253Z,1581217180.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-09T02:59:40.672Z,1581217180.672 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-09T02:59:40.672Z,1581217180.672 [NAL9602] No Fault, FailCount= 1
2020-02-09T02:59:59.205Z,1581217199.205 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T02:59:59.205Z,1581217199.205 [Micromodem](INFO): Have incoming data
2020-02-09T02:59:59.208Z,1581217199.208 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T02:59:59.209Z,1581217199.209 [Micromodem](INFO): Received command:failc
2020-02-09T02:59:59.299Z,1581217199.299 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T02:59:59.299Z,1581217199.299 [CommandLine](IMPORTANT): Failed components:
2020-02-09T02:59:59.299Z,1581217199.299 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T02:59:59.588Z,1581217199.588 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209025955.853322,06,2193,33,0186,0150,59,02,02,00,04,1,010,001,0,3,1,0,150,19.4,6.00,00,-3.00,-01,0.01,29,14500,2000*5D

2020-02-09T03:00:09.694Z,1581217209.694 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T03:00:09.694Z,1581217209.694 [Micromodem](INFO): Have incoming data
2020-02-09T03:00:09.698Z,1581217209.698 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T03:00:09.699Z,1581217209.699 [Micromodem](INFO): Received command:failc
2020-02-09T03:00:09.707Z,1581217209.707 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T03:00:09.707Z,1581217209.707 [CommandLine](IMPORTANT): Failed components:
2020-02-09T03:00:09.707Z,1581217209.707 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T03:00:10.092Z,1581217210.092 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209030006.555769,06,2469,33,0206,0150,59,02,02,00,04,1,010,001,0,3,1,0,150,19.4,6.00,00,-3.00,-01,0.01,29,14500,2000*5D

2020-02-09T03:00:10.093Z,1581217210.093 [NAL9602](INFO): Powering up NAL9602
2020-02-09T03:00:20.997Z,1581217220.997 [NAL9602](INFO): NAL9602 initialized
2020-02-09T03:00:52.109Z,1581217252.109 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-09T03:01:53.158Z,1581217313.158 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-09T03:01:53.158Z,1581217313.158 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,32768,V
2020-02-09T03:02:14.144Z,1581217334.144 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T03:02:14.144Z,1581217334.144 [Micromodem](INFO): Have incoming data
2020-02-09T03:02:14.179Z,1581217334.179 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T03:02:14.180Z,1581217334.180 [Micromodem](INFO): Received command:failc
2020-02-09T03:02:14.203Z,1581217334.203 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T03:02:14.203Z,1581217334.203 [CommandLine](IMPORTANT): Failed components:
2020-02-09T03:02:14.203Z,1581217334.203 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T03:02:14.524Z,1581217334.524 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209030212.339345,06,2959,34,0216,0150,29,01,01,00,02,1,010,001,0,3,1,0,150,19.9,5.00,00,-2.00,-01,0.02,24,14500,4000*52

2020-02-09T03:02:24.621Z,1581217344.621 [Micromodem](INFO): Nmea in: $CACYC,1,10,1,1,0,1*6A

2020-02-09T03:02:24.621Z,1581217344.621 [Micromodem](INFO): Have incoming data
2020-02-09T03:02:24.637Z,1581217344.636 [Micromodem](INFO): Nmea in: $CARXD,10,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*50

2020-02-09T03:02:24.637Z,1581217344.637 [Micromodem](INFO): Received command:failc
2020-02-09T03:02:24.663Z,1581217344.663 [CommandLine](IMPORTANT): got command failComponent
2020-02-09T03:02:24.664Z,1581217344.664 [CommandLine](IMPORTANT): Failed components:
2020-02-09T03:02:24.664Z,1581217344.664 [CommandLine](IMPORTANT): No failed Components.
2020-02-09T03:02:25.036Z,1581217345.036 [Micromodem](INFO): Nmea in: $CACST,6,0,20200209030222.779365,06,1902,32,0143,0150,59,02,02,00,04,1,010,001,0,3,1,0,150,18.6,5.00,00,-2.00,-01,-0.06,30,14500,4000*78

2020-02-09T03:02:30.324Z,1581217350.324 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-09T03:02:30.324Z,1581217350.324 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,     ,   0.00,826.97
2020-02-09T03:02:33.535Z,1581217353.535 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-09T03:02:33.535Z,1581217353.535 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-02-09T03:03:13.244Z,1581217393.244 [CommandLine](IMPORTANT): got command restart application
2020-02-09T03:03:14.247Z,1581217394.247 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-02-09T03:03:14.247Z,1581217394.247 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:14.247Z,1581217394.247 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.263Z,1581217394.263 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-09T03:03:14.263Z,1581217394.263 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.263Z,1581217394.263 [CommandLine](INFO): Join timeout helper Thread ID is 1650
2020-02-09T03:03:14.264Z,1581217394.264 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-09T03:03:14.264Z,1581217394.264 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.264Z,1581217394.264 [NavChartDb](INFO): Join timeout helper Thread ID is 1651
2020-02-09T03:03:14.369Z,1581217394.369 [MissionManager](INFO): MissionManager is completed.
2020-02-09T03:03:14.369Z,1581217394.369 [MissionManager](INFO): Uninitializing Mission Default
2020-02-09T03:03:14.369Z,1581217394.369 [Default] Stopped
2020-02-09T03:03:14.369Z,1581217394.369 [Default](DEBUG): Aggregate::uninitialize Default
2020-02-09T03:03:14.369Z,1581217394.369 [Default:B.GoToSurface] Stopped
2020-02-09T03:03:14.369Z,1581217394.369 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-09T03:03:14.369Z,1581217394.369 [Default:CheckIn] Stopped
2020-02-09T03:03:14.369Z,1581217394.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-02-09T03:03:14.369Z,1581217394.369 [Default:CheckIn:C.Wait] Stopped
2020-02-09T03:03:14.369Z,1581217394.369 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T03:03:14.451Z,1581217394.451 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:14.451Z,1581217394.451 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.459Z,1581217394.459 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-02-09T03:03:14.459Z,1581217394.459 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.459Z,1581217394.459 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1652
2020-02-09T03:03:14.519Z,1581217394.519 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:14.519Z,1581217394.519 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-09T03:03:14.520Z,1581217394.520 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.543Z,1581217394.543 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-09T03:03:14.543Z,1581217394.543 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.543Z,1581217394.543 [Radio_Surface](INFO): Join timeout helper Thread ID is 1653
2020-02-09T03:03:14.763Z,1581217394.763 [MissionManager](IMPORTANT): Started mission DefaultUnder
2020-02-09T03:03:14.763Z,1581217394.763 [DefaultUnder] Running Loop=1
2020-02-09T03:03:14.763Z,1581217394.763 [DefaultUnder](DEBUG): Aggregate::initialize DefaultUnder
2020-02-09T03:03:14.763Z,1581217394.763 [DefaultUnder:D.PitchEnvelope] Running Loop=1
2020-02-09T03:03:14.763Z,1581217394.763 [DefaultUnder:D.PitchEnvelope](DEBUG): Initialize PitchEnvelope.
2020-02-09T03:03:14.763Z,1581217394.763 [DefaultUnder:D.PitchEnvelope](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-09T03:03:14.764Z,1581217394.764 [DefaultUnder:D.PitchEnvelope](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-09T03:03:14.764Z,1581217394.764 [DefaultUnder:D.PitchEnvelope](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-09T03:03:14.764Z,1581217394.764 [DefaultUnder:uModemComms] Running Loop=1
2020-02-09T03:03:14.764Z,1581217394.764 [DefaultUnder:uModemComms](DEBUG): Aggregate::initialize DefaultUnder:uModemComms
2020-02-09T03:03:14.764Z,1581217394.764 [DefaultUnder:Notify] Running Loop=1
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify](DEBUG): Aggregate::initialize DefaultUnder:Notify
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify:A] Running Loop=1
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify:A:A] Running Loop=1
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::initialize DefaultUnder:Notify:A:A
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify:A:A:A.Wait] Running Loop=1
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Initialize Wait Component.
2020-02-09T03:03:14.765Z,1581217394.765 [DefaultUnder:TrackAcousticContact] Running Loop=1
2020-02-09T03:03:14.766Z,1581217394.766 [DefaultUnder:TrackAcousticContact](DEBUG): Aggregate::initialize DefaultUnder:TrackAcousticContact
2020-02-09T03:03:14.766Z,1581217394.766 [DefaultUnder:TrackAcousticContact:A.] Running Loop=1
2020-02-09T03:03:14.766Z,1581217394.766 [DefaultUnder:TrackAcousticContact:A.](INFO): Initializing TrackAcousticContact.
2020-02-09T03:03:14.766Z,1581217394.766 [DefaultUnder:A] Running Loop=1
2020-02-09T03:03:14.768Z,1581217394.768 [DefaultUnder:A] Stopped
2020-02-09T03:03:14.768Z,1581217394.768 [DefaultUnder:B] Running Loop=1
2020-02-09T03:03:14.907Z,1581217394.907 [Radio_Surface](INFO): Powering down
2020-02-09T03:03:14.908Z,1581217394.908 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:14.908Z,1581217394.908 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.915Z,1581217394.915 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-09T03:03:14.915Z,1581217394.915 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:14.915Z,1581217394.915 [DataOverHttps](INFO): Join timeout helper Thread ID is 1654
2020-02-09T03:03:15.083Z,1581217395.083 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:15.083Z,1581217395.083 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.097Z,1581217395.097 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-09T03:03:15.098Z,1581217395.098 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.099Z,1581217395.099 [logger](INFO): Join timeout helper Thread ID is 1655
2020-02-09T03:03:15.119Z,1581217395.119 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:15.120Z,1581217395.120 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.141Z,1581217395.141 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-09T03:03:15.142Z,1581217395.142 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.142Z,1581217395.142 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-09T03:03:15.143Z,1581217395.143 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.144Z,1581217395.144 [controlThread](INFO): Join timeout helper Thread ID is 1656
2020-02-09T03:03:15.196Z,1581217395.196 [DefaultUnder:B] Stopped
2020-02-09T03:03:15.196Z,1581217395.196 [DefaultUnder:C] Running Loop=1
2020-02-09T03:03:15.207Z,1581217395.207 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-09T03:03:15.207Z,1581217395.207 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-09T03:03:15.207Z,1581217395.207 [AHRS_M2](INFO): Powering down
2020-02-09T03:03:15.279Z,1581217395.279 [DDM](INFO): Powering down
2020-02-09T03:03:15.351Z,1581217395.351 [DUSBL_Hydroid](INFO): Powering down
2020-02-09T03:03:15.423Z,1581217395.423 [Micromodem](INFO): Powering down
2020-02-09T03:03:15.519Z,1581217395.519 [NAL9602](INFO): Powering down
2020-02-09T03:03:15.591Z,1581217395.591 [RDI_Pathfinder](INFO): Powering down
2020-02-09T03:03:15.592Z,1581217395.592 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-09T03:03:15.593Z,1581217395.593 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-09T03:03:15.594Z,1581217395.594 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-09T03:03:15.594Z,1581217395.594 [MissionManager](INFO): Uninitializing Mission DefaultUnder
2020-02-09T03:03:15.594Z,1581217395.594 [DefaultUnder] Stopped
2020-02-09T03:03:15.594Z,1581217395.594 [DefaultUnder](DEBUG): Aggregate::uninitialize DefaultUnder
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:C] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:D.PitchEnvelope] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:D.PitchEnvelope](DEBUG): Uninitialize PitchEnvelope.
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:uModemComms] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:uModemComms](DEBUG): Aggregate::uninitialize DefaultUnder:uModemComms
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify](DEBUG): Aggregate::uninitialize DefaultUnder:Notify
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify:A] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify:A:A] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify:A:A](DEBUG): Aggregate::uninitialize DefaultUnder:Notify:A:A
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify:A:A:A.Wait] Stopped
2020-02-09T03:03:15.595Z,1581217395.595 [DefaultUnder:Notify:A:A:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-09T03:03:15.596Z,1581217395.596 [DefaultUnder:TrackAcousticContact] Stopped
2020-02-09T03:03:15.596Z,1581217395.596 [DefaultUnder:TrackAcousticContact](DEBUG): Aggregate::uninitialize DefaultUnder:TrackAcousticContact
2020-02-09T03:03:15.596Z,1581217395.596 [DefaultUnder:TrackAcousticContact:A.] Stopped
2020-02-09T03:03:15.628Z,1581217395.628 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-09T03:03:15.628Z,1581217395.628 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-09T03:03:15.628Z,1581217395.628 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-09T03:03:15.629Z,1581217395.629 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-09T03:03:15.629Z,1581217395.629 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-09T03:03:15.629Z,1581217395.629 [BuoyancyServo](INFO): Powering down
2020-02-09T03:03:15.643Z,1581217395.643 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-09T03:03:15.643Z,1581217395.643 [ElevatorServo](INFO): Powering down
2020-02-09T03:03:15.644Z,1581217395.644 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-09T03:03:15.644Z,1581217395.644 [MassServo](INFO): Powering down
2020-02-09T03:03:15.644Z,1581217395.644 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-09T03:03:15.645Z,1581217395.645 [RudderServo](INFO): Powering down
2020-02-09T03:03:15.645Z,1581217395.645 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-09T03:03:15.645Z,1581217395.645 [ThrusterServo](INFO): Powering down
2020-02-09T03:03:15.646Z,1581217395.646 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-09T03:03:15.647Z,1581217395.647 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-09T03:03:15.647Z,1581217395.647 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-09T03:03:15.647Z,1581217395.647 [CBIT](DEBUG): Powering off loads.
2020-02-09T03:03:15.658Z,1581217395.658 [CBIT](DEBUG): Disabling WDT.
2020-02-09T03:03:15.670Z,1581217395.670 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-09T03:03:15.671Z,1581217395.671 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.722Z,1581217395.722 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.733Z,1581217395.733 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.778Z,1581217395.778 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.839Z,1581217395.839 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-09T03:03:15.907Z,1581217395.907 [logger ThreadHandler](INFO): Thread cancelled.