2019-09-09T18:08:12.301Z,1568052492.301 [Default] Stopped
2019-09-09T18:08:12.302Z,1568052492.302 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-09T18:08:12.302Z,1568052492.302 [Default:B.GoToSurface] Stopped
2019-09-09T18:08:12.302Z,1568052492.302 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-09T18:08:12.367Z,1568052492.367 [CommandLine](IMPORTANT): got command restart logs
2019-09-09T18:08:12.735Z,1568052492.735 [MissionManager](IMPORTANT): Started mission Default
2019-09-09T18:08:12.735Z,1568052492.735 [Default] Running Loop=1
2019-09-09T18:08:12.735Z,1568052492.735 [Default](DEBUG): Aggregate::initialize Default
2019-09-09T18:08:12.735Z,1568052492.735 [Default:B.GoToSurface] Running Loop=1
2019-09-09T18:08:12.735Z,1568052492.735 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-09T18:08:12.735Z,1568052492.735 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-09T18:08:12.736Z,1568052492.736 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-09T18:08:12.736Z,1568052492.736 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-09T18:08:12.736Z,1568052492.736 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-09T18:08:12.737Z,1568052492.737 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-09T18:08:12.737Z,1568052492.737 [Default:A.Wait] Running Loop=1
2019-09-09T18:08:12.737Z,1568052492.737 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-09T18:08:26.035Z,1568052506.035 [Default:A.Wait](INFO): Done Waiting.
2019-09-09T18:08:26.035Z,1568052506.035 [Default:A.Wait] Stopped
2019-09-09T18:08:26.035Z,1568052506.035 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T18:08:26.458Z,1568052506.458 [Default:CheckIn] Running Loop=1
2019-09-09T18:08:26.458Z,1568052506.458 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T18:08:26.459Z,1568052506.459 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T18:08:28.028Z,1568052508.028 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T18:08:28.028Z,1568052508.028 [NAL9602] Data Fault, FailCount= 2
2019-09-09T18:08:28.028Z,1568052508.028 [NAL9602](ERROR): Data Fault
2019-09-09T18:08:28.092Z,1568052508.092 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T18:08:28.436Z,1568052508.436 [NAL9602](INFO): Powering down
2019-09-09T18:08:29.275Z,1568052509.275 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T18:08:29.276Z,1568052509.276 [NAL9602] No Fault, FailCount= 2
2019-09-09T18:08:58.736Z,1568052538.736 [NAL9602](INFO): Powering up NAL9602
2019-09-09T18:09:09.640Z,1568052549.640 [NAL9602](INFO): NAL9602 initialized
2019-09-09T18:09:10.462Z,1568052550.462 [NAL9602](DEBUG): Fix Requested
2019-09-09T18:10:34.499Z,1568052634.499 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:10:39.744Z,1568052639.744 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:10:51.063Z,1568052651.063 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:11:02.388Z,1568052662.388 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:11:13.697Z,1568052673.697 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:11:24.995Z,1568052684.995 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:11:36.310Z,1568052696.310 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:11:47.630Z,1568052707.630 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:11:58.935Z,1568052718.935 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:12:10.662Z,1568052730.662 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:12:22.380Z,1568052742.380 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:12:34.085Z,1568052754.085 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:12:44.987Z,1568052764.987 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:13:26.635Z,1568052806.635 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T18:08:26.5Z
2019-09-09T18:13:26.635Z,1568052806.635 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T18:13:26.635Z,1568052806.635 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T18:13:32.754Z,1568052812.754 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190907T160845/Courier0864.lzma
2019-09-09T18:13:33.560Z,1568052813.560 [DataOverHttps](INFO): Moved sent file to Logs/20190907T160845/Courier0864.lzma.bak
2019-09-09T18:13:33.561Z,1568052813.561 [DataOverHttps](INFO): SBD MOMSN=11757902
2019-09-09T18:13:43.647Z,1568052823.647 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:13:43.647Z,1568052823.647 [DVL_micro] No Fault, FailCount= 5
2019-09-09T18:13:43.998Z,1568052823.998 [DVL_micro](INFO): Initializing
2019-09-09T18:13:45.627Z,1568052825.627 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190909T180812/Courier0000.lzma
2019-09-09T18:13:46.433Z,1568052826.433 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0000.lzma.bak
2019-09-09T18:13:46.433Z,1568052826.433 [DataOverHttps](INFO): SBD MOMSN=11757907
2019-09-09T18:13:48.028Z,1568052828.028 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T18:13:48.028Z,1568052828.028 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T18:13:48.028Z,1568052828.028 [DVL_micro](ERROR): Data Fault
2019-09-09T18:13:48.123Z,1568052828.123 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T18:13:48.503Z,1568052828.503 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:13:49.441Z,1568052829.441 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:13:49.441Z,1568052829.441 [DVL_micro] No Fault, FailCount= 1
2019-09-09T18:13:49.649Z,1568052829.649 [DVL_micro](INFO): Initializing
2019-09-09T18:13:59.555Z,1568052839.555 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20190907T160845/Express0865.lzma
2019-09-09T18:14:00.360Z,1568052840.360 [DataOverHttps](INFO): Moved sent file to Logs/20190907T160845/Express0865.lzma.bak
2019-09-09T18:14:00.361Z,1568052840.361 [DataOverHttps](INFO): SBD MOMSN=11757913
2019-09-09T18:14:03.380Z,1568052843.380 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:14:03.380Z,1568052843.380 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T18:14:03.380Z,1568052843.380 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:14:03.456Z,1568052843.456 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:14:03.855Z,1568052843.855 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:14:04.640Z,1568052844.640 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:14:04.640Z,1568052844.640 [DVL_micro] No Fault, FailCount= 2
2019-09-09T18:14:05.002Z,1568052845.002 [DVL_micro](INFO): Initializing
2019-09-09T18:14:12.679Z,1568052852.679 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T18:14:13.319Z,1568052853.319 [DataOverHttps](INFO): Sending 350 bytes from file Logs/20190909T180812/Express0001.lzma
2019-09-09T18:14:14.124Z,1568052854.124 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0001.lzma.bak
2019-09-09T18:14:14.124Z,1568052854.124 [DataOverHttps](INFO): SBD MOMSN=11757918
2019-09-09T18:14:16.473Z,1568052856.473 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T18:14:16.473Z,1568052856.473 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T18:14:16.473Z,1568052856.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T18:14:18.732Z,1568052858.732 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:14:18.732Z,1568052858.732 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T18:14:18.732Z,1568052858.732 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:14:18.795Z,1568052858.795 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:14:19.207Z,1568052859.207 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:14:20.034Z,1568052860.034 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:14:20.034Z,1568052860.034 [DVL_micro] No Fault, FailCount= 3
2019-09-09T18:14:20.353Z,1568052860.353 [DVL_micro](INFO): Initializing
2019-09-09T18:14:34.105Z,1568052874.105 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:14:34.106Z,1568052874.106 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T18:14:34.106Z,1568052874.106 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:14:34.139Z,1568052874.139 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:14:34.575Z,1568052874.575 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:14:35.368Z,1568052875.368 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:14:35.368Z,1568052875.368 [DVL_micro] No Fault, FailCount= 4
2019-09-09T18:14:35.725Z,1568052875.725 [DVL_micro](INFO): Initializing
2019-09-09T18:14:43.390Z,1568052883.390 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T18:14:49.449Z,1568052889.449 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:14:49.449Z,1568052889.449 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T18:14:49.449Z,1568052889.449 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:14:49.483Z,1568052889.483 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:14:49.484Z,1568052889.484 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T18:14:49.931Z,1568052889.931 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:14:49.982Z,1568052889.982 [CBIT](INFO): Critical error at 20190909T181449
2019-09-09T18:19:16.927Z,1568053156.927 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T18:19:16.927Z,1568053156.927 [Default:CheckIn:C.Wait] Stopped
2019-09-09T18:19:16.927Z,1568053156.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T18:19:16.927Z,1568053156.927 [Default:CheckIn:D] Running Loop=1
2019-09-09T18:19:17.331Z,1568053157.331 [Default:CheckIn:D] Stopped
2019-09-09T18:19:17.331Z,1568053157.331 [Default:CheckIn:E] Running Loop=1
2019-09-09T18:19:17.731Z,1568053157.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.076606 min 
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn:E] Stopped
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn] Stopped
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn](INFO): Running loop #2
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn] Running Loop=2
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T18:19:17.732Z,1568053157.732 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T18:19:19.328Z,1568053159.328 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T18:19:19.328Z,1568053159.328 [NAL9602] Data Fault, FailCount= 3
2019-09-09T18:19:19.328Z,1568053159.328 [NAL9602](ERROR): Data Fault
2019-09-09T18:19:19.360Z,1568053159.360 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T18:19:19.732Z,1568053159.732 [NAL9602](INFO): Powering down
2019-09-09T18:19:20.578Z,1568053160.578 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T18:19:20.578Z,1568053160.578 [NAL9602] No Fault, FailCount= 3
2019-09-09T18:19:50.032Z,1568053190.032 [NAL9602](INFO): Powering up NAL9602
2019-09-09T18:20:00.940Z,1568053200.940 [NAL9602](INFO): NAL9602 initialized
2019-09-09T18:20:01.759Z,1568053201.759 [NAL9602](DEBUG): Fix Requested
2019-09-09T18:20:13.069Z,1568053213.069 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:20:24.809Z,1568053224.809 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:20:36.122Z,1568053236.122 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:20:47.023Z,1568053247.023 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:20:58.740Z,1568053258.740 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:21:10.044Z,1568053270.044 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:21:21.362Z,1568053281.362 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:21:33.085Z,1568053293.085 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:21:44.401Z,1568053304.401 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:21:55.703Z,1568053315.703 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:22:07.015Z,1568053327.015 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:22:17.916Z,1568053337.916 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:22:29.635Z,1568053349.635 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:24:17.920Z,1568053457.920 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T18:19:17.7Z
2019-09-09T18:24:17.920Z,1568053457.920 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T18:24:17.920Z,1568053457.920 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T18:24:26.306Z,1568053466.306 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20190909T180812/Courier0003.lzma
2019-09-09T18:24:27.112Z,1568053467.112 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0003.lzma.bak
2019-09-09T18:24:27.112Z,1568053467.112 [DataOverHttps](INFO): SBD MOMSN=11757938
2019-09-09T18:24:39.919Z,1568053479.919 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20190909T180812/Express0004.lzma
2019-09-09T18:24:40.724Z,1568053480.724 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0004.lzma.bak
2019-09-09T18:24:40.724Z,1568053480.724 [DataOverHttps](INFO): SBD MOMSN=11757941
2019-09-09T18:24:43.012Z,1568053483.012 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T18:24:43.012Z,1568053483.012 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T18:24:43.012Z,1568053483.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T18:24:49.851Z,1568053489.851 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:24:49.852Z,1568053489.852 [DVL_micro] No Fault, FailCount= 5
2019-09-09T18:24:50.221Z,1568053490.221 [DVL_micro](INFO): Initializing
2019-09-09T18:24:54.252Z,1568053494.252 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T18:24:54.252Z,1568053494.252 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T18:24:54.252Z,1568053494.252 [DVL_micro](ERROR): Data Fault
2019-09-09T18:24:54.328Z,1568053494.328 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T18:24:54.727Z,1568053494.727 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:24:55.501Z,1568053495.501 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:24:55.501Z,1568053495.501 [DVL_micro] No Fault, FailCount= 1
2019-09-09T18:24:55.878Z,1568053495.878 [DVL_micro](INFO): Initializing
2019-09-09T18:25:03.946Z,1568053503.946 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T18:25:09.601Z,1568053509.601 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:25:09.601Z,1568053509.601 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T18:25:09.601Z,1568053509.601 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:25:09.664Z,1568053509.664 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:25:10.083Z,1568053510.083 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:25:10.855Z,1568053510.855 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:25:10.855Z,1568053510.855 [DVL_micro] No Fault, FailCount= 2
2019-09-09T18:25:11.228Z,1568053511.228 [DVL_micro](INFO): Initializing
2019-09-09T18:25:24.960Z,1568053524.960 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:25:24.960Z,1568053524.960 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T18:25:24.960Z,1568053524.960 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:25:24.994Z,1568053524.994 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:25:25.435Z,1568053525.435 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:25:26.244Z,1568053526.244 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:25:26.244Z,1568053526.244 [DVL_micro] No Fault, FailCount= 3
2019-09-09T18:25:26.578Z,1568053526.578 [DVL_micro](INFO): Initializing
2019-09-09T18:25:34.652Z,1568053534.652 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T18:25:40.305Z,1568053540.305 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:25:40.305Z,1568053540.305 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T18:25:40.305Z,1568053540.305 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:25:40.344Z,1568053540.344 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:25:40.787Z,1568053540.787 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:25:41.572Z,1568053541.572 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:25:41.572Z,1568053541.572 [DVL_micro] No Fault, FailCount= 4
2019-09-09T18:25:41.941Z,1568053541.941 [DVL_micro](INFO): Initializing
2019-09-09T18:25:55.657Z,1568053555.657 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:25:55.657Z,1568053555.657 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T18:25:55.657Z,1568053555.657 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:25:55.695Z,1568053555.695 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:25:55.696Z,1568053555.696 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T18:25:56.139Z,1568053556.139 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:25:56.178Z,1568053556.178 [CBIT](INFO): Critical error at 20190909T182555
2019-09-09T18:29:43.542Z,1568053783.542 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T18:29:43.543Z,1568053783.543 [Default:CheckIn:C.Wait] Stopped
2019-09-09T18:29:43.543Z,1568053783.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T18:29:43.543Z,1568053783.543 [Default:CheckIn:D] Running Loop=1
2019-09-09T18:29:43.950Z,1568053783.950 [Default:CheckIn:D] Stopped
2019-09-09T18:29:43.950Z,1568053783.950 [Default:CheckIn:E] Running Loop=1
2019-09-09T18:29:44.360Z,1568053784.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.520249 min 
2019-09-09T18:29:44.360Z,1568053784.360 [Default:CheckIn:E] Stopped
2019-09-09T18:29:44.360Z,1568053784.360 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T18:29:44.360Z,1568053784.360 [Default:CheckIn] Stopped
2019-09-09T18:29:44.360Z,1568053784.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T18:29:44.360Z,1568053784.360 [Default:CheckIn](INFO): Running loop #3
2019-09-09T18:29:44.361Z,1568053784.361 [Default:CheckIn] Running Loop=3
2019-09-09T18:29:44.361Z,1568053784.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T18:29:44.361Z,1568053784.361 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T18:29:45.951Z,1568053785.951 [NAL9602](DEBUG): Fix Requested
2019-09-09T18:29:45.965Z,1568053785.965 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:29:51.615Z,1568053791.615 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:30:03.341Z,1568053803.341 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:30:14.662Z,1568053814.662 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:30:25.958Z,1568053825.958 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:30:38.070Z,1568053838.070 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:30:49.380Z,1568053849.380 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:31:00.692Z,1568053860.692 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:31:12.021Z,1568053872.021 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:31:23.727Z,1568053883.727 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:31:35.035Z,1568053895.035 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:31:46.347Z,1568053906.347 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:31:57.664Z,1568053917.664 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:32:08.974Z,1568053928.974 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:34:15.443Z,1568054055.443 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2019-09-09T18:34:15.445Z,1568054055.445 [BPC1](FAULT): Failed to receive data from 1 sticks prior to timeout. Missing stick IDs are: 25.
2019-09-09T18:34:15.445Z,1568054055.445 [BPC1] Data Fault, FailCount= 3
2019-09-09T18:34:15.445Z,1568054055.445 [BPC1](ERROR): Data Fault
2019-09-09T18:34:15.480Z,1568054055.480 [CBIT](ERROR): Data Fault in component: BPC1
2019-09-09T18:34:44.519Z,1568054084.519 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T18:29:44.4Z
2019-09-09T18:34:44.519Z,1568054084.519 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T18:34:44.519Z,1568054084.519 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T18:34:48.132Z,1568054088.132 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T18:34:50.695Z,1568054090.695 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20190909T180812/Courier0006.lzma
2019-09-09T18:34:51.500Z,1568054091.500 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0006.lzma.bak
2019-09-09T18:34:51.500Z,1568054091.500 [DataOverHttps](INFO): SBD MOMSN=11757959
2019-09-09T18:35:04.187Z,1568054104.187 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20190909T180812/Express0007.lzma
2019-09-09T18:35:04.992Z,1568054104.992 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0007.lzma.bak
2019-09-09T18:35:04.992Z,1568054104.992 [DataOverHttps](INFO): SBD MOMSN=11757962
2019-09-09T18:35:07.142Z,1568054107.142 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T18:35:07.143Z,1568054107.143 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T18:35:07.143Z,1568054107.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T18:35:18.839Z,1568054118.839 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T18:35:56.034Z,1568054156.034 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:35:56.034Z,1568054156.034 [DVL_micro] No Fault, FailCount= 5
2019-09-09T18:35:56.418Z,1568054156.418 [DVL_micro](INFO): Initializing
2019-09-09T18:36:00.445Z,1568054160.445 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T18:36:00.445Z,1568054160.445 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T18:36:00.445Z,1568054160.445 [DVL_micro](ERROR): Data Fault
2019-09-09T18:36:00.479Z,1568054160.479 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T18:36:00.927Z,1568054160.927 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:36:01.712Z,1568054161.712 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:36:01.712Z,1568054161.712 [DVL_micro] No Fault, FailCount= 1
2019-09-09T18:36:02.068Z,1568054162.068 [DVL_micro](INFO): Initializing
2019-09-09T18:36:15.804Z,1568054175.804 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:36:15.804Z,1568054175.804 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T18:36:15.804Z,1568054175.804 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:36:15.838Z,1568054175.838 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:36:16.279Z,1568054176.279 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:36:17.048Z,1568054177.048 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:36:17.048Z,1568054177.048 [DVL_micro] No Fault, FailCount= 2
2019-09-09T18:36:17.421Z,1568054177.421 [DVL_micro](INFO): Initializing
2019-09-09T18:36:31.149Z,1568054191.149 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:36:31.149Z,1568054191.149 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T18:36:31.149Z,1568054191.149 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:36:31.201Z,1568054191.201 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:36:31.631Z,1568054191.631 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:36:32.400Z,1568054192.400 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:36:32.400Z,1568054192.400 [DVL_micro] No Fault, FailCount= 3
2019-09-09T18:36:32.778Z,1568054192.778 [DVL_micro](INFO): Initializing
2019-09-09T18:36:46.501Z,1568054206.501 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:36:46.501Z,1568054206.501 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T18:36:46.501Z,1568054206.501 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:36:46.560Z,1568054206.560 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:36:46.983Z,1568054206.983 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:36:47.744Z,1568054207.744 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:36:47.744Z,1568054207.744 [DVL_micro] No Fault, FailCount= 4
2019-09-09T18:36:48.123Z,1568054208.123 [DVL_micro](INFO): Initializing
2019-09-09T18:37:01.856Z,1568054221.856 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:37:01.856Z,1568054221.856 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T18:37:01.856Z,1568054221.856 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:37:01.911Z,1568054221.911 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:37:01.911Z,1568054221.911 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T18:37:02.331Z,1568054222.331 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:37:02.394Z,1568054222.394 [CBIT](INFO): Critical error at 20190909T183701
2019-09-09T18:40:07.727Z,1568054407.727 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T18:40:07.727Z,1568054407.727 [Default:CheckIn:C.Wait] Stopped
2019-09-09T18:40:07.727Z,1568054407.727 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T18:40:07.727Z,1568054407.727 [Default:CheckIn:D] Running Loop=1
2019-09-09T18:40:08.127Z,1568054408.127 [Default:CheckIn:D] Stopped
2019-09-09T18:40:08.127Z,1568054408.127 [Default:CheckIn:E] Running Loop=1
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.923197 min 
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn:E] Stopped
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn] Stopped
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn](INFO): Running loop #4
2019-09-09T18:40:08.531Z,1568054408.531 [Default:CheckIn] Running Loop=4
2019-09-09T18:40:08.532Z,1568054408.532 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T18:40:08.532Z,1568054408.532 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T18:40:10.120Z,1568054410.120 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T18:40:10.120Z,1568054410.120 [NAL9602] Data Fault, FailCount= 4
2019-09-09T18:40:10.120Z,1568054410.120 [NAL9602](ERROR): Data Fault
2019-09-09T18:40:10.152Z,1568054410.152 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T18:40:10.524Z,1568054410.524 [NAL9602](INFO): Powering down
2019-09-09T18:40:11.360Z,1568054411.360 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T18:40:11.360Z,1568054411.360 [NAL9602] No Fault, FailCount= 4
2019-09-09T18:40:40.830Z,1568054440.830 [NAL9602](INFO): Powering up NAL9602
2019-09-09T18:40:51.737Z,1568054451.737 [NAL9602](INFO): NAL9602 initialized
2019-09-09T18:40:52.559Z,1568054452.559 [NAL9602](DEBUG): Fix Requested
2019-09-09T18:44:16.615Z,1568054656.615 [CBIT](INFO): Clearing failed state for component BPC1
2019-09-09T18:44:16.615Z,1568054656.615 [BPC1] No Fault, FailCount= 3
2019-09-09T18:45:08.723Z,1568054708.723 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T18:40:08.5Z
2019-09-09T18:45:08.723Z,1568054708.723 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T18:45:08.723Z,1568054708.723 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T18:45:15.078Z,1568054715.078 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190909T180812/Courier0009.lzma
2019-09-09T18:45:15.884Z,1568054715.884 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0009.lzma.bak
2019-09-09T18:45:15.884Z,1568054715.884 [DataOverHttps](INFO): SBD MOMSN=11757982
2019-09-09T18:45:29.591Z,1568054729.591 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20190909T180812/Express0010.lzma
2019-09-09T18:45:30.396Z,1568054730.396 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0010.lzma.bak
2019-09-09T18:45:30.396Z,1568054730.396 [DataOverHttps](INFO): SBD MOMSN=11757985
2019-09-09T18:45:32.579Z,1568054732.579 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T18:45:32.579Z,1568054732.579 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T18:45:32.579Z,1568054732.579 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T18:45:54.753Z,1568054754.753 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T18:46:25.466Z,1568054785.466 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T18:46:45.280Z,1568054805.280 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-09-09T18:47:02.258Z,1568054822.258 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:47:02.259Z,1568054822.259 [DVL_micro] No Fault, FailCount= 5
2019-09-09T18:47:02.631Z,1568054822.631 [DVL_micro](INFO): Initializing
2019-09-09T18:47:06.672Z,1568054826.672 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T18:47:06.672Z,1568054826.672 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T18:47:06.672Z,1568054826.672 [DVL_micro](ERROR): Data Fault
2019-09-09T18:47:06.723Z,1568054826.723 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T18:47:07.143Z,1568054827.143 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:47:07.917Z,1568054827.917 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:47:07.917Z,1568054827.917 [DVL_micro] No Fault, FailCount= 1
2019-09-09T18:47:08.295Z,1568054828.295 [DVL_micro](INFO): Initializing
2019-09-09T18:47:22.017Z,1568054842.017 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:47:22.017Z,1568054842.017 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T18:47:22.017Z,1568054842.017 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:47:22.081Z,1568054842.081 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:47:22.499Z,1568054842.499 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:47:23.272Z,1568054843.272 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:47:23.272Z,1568054843.272 [DVL_micro] No Fault, FailCount= 2
2019-09-09T18:47:23.646Z,1568054843.646 [DVL_micro](INFO): Initializing
2019-09-09T18:47:37.376Z,1568054857.376 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:47:37.376Z,1568054857.376 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T18:47:37.376Z,1568054857.376 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:47:37.410Z,1568054857.410 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:47:37.851Z,1568054857.851 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:47:38.650Z,1568054858.650 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:47:38.650Z,1568054858.650 [DVL_micro] No Fault, FailCount= 3
2019-09-09T18:47:38.992Z,1568054858.992 [DVL_micro](INFO): Initializing
2019-09-09T18:47:52.728Z,1568054872.728 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:47:52.728Z,1568054872.728 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T18:47:52.728Z,1568054872.728 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:47:52.823Z,1568054872.823 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:47:53.201Z,1568054873.201 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:47:53.972Z,1568054873.972 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:47:53.973Z,1568054873.973 [DVL_micro] No Fault, FailCount= 4
2019-09-09T18:47:54.350Z,1568054874.350 [DVL_micro](INFO): Initializing
2019-09-09T18:48:08.080Z,1568054888.080 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:48:08.080Z,1568054888.080 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T18:48:08.080Z,1568054888.080 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:48:08.148Z,1568054888.148 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:48:08.148Z,1568054888.148 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T18:48:08.555Z,1568054888.555 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:48:08.593Z,1568054888.593 [CBIT](INFO): Critical error at 20190909T184808
2019-09-09T18:50:33.222Z,1568055033.222 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T18:50:33.223Z,1568055033.223 [Default:CheckIn:C.Wait] Stopped
2019-09-09T18:50:33.223Z,1568055033.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T18:50:33.223Z,1568055033.223 [Default:CheckIn:D] Running Loop=1
2019-09-09T18:50:33.571Z,1568055033.571 [Default:CheckIn:D] Stopped
2019-09-09T18:50:33.571Z,1568055033.571 [Default:CheckIn:E] Running Loop=1
2019-09-09T18:50:34.043Z,1568055034.043 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.347266 min 
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn:E] Stopped
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn] Stopped
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn](INFO): Running loop #5
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn] Running Loop=5
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T18:50:34.044Z,1568055034.044 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T18:50:35.575Z,1568055035.575 [NAL9602](DEBUG): Fix Requested
2019-09-09T18:51:40.221Z,1568055100.221 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:51:51.537Z,1568055111.537 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:52:02.843Z,1568055122.843 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:52:14.555Z,1568055134.555 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:52:25.459Z,1568055145.459 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:52:36.770Z,1568055156.770 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:52:48.087Z,1568055168.087 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:52:59.405Z,1568055179.405 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:53:10.721Z,1568055190.721 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:53:22.019Z,1568055202.019 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:53:32.931Z,1568055212.931 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:53:44.243Z,1568055224.243 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:53:55.551Z,1568055235.551 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T18:55:34.147Z,1568055334.147 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T18:50:34.0Z
2019-09-09T18:55:34.147Z,1568055334.147 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T18:55:34.147Z,1568055334.147 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T18:55:37.748Z,1568055337.748 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T18:55:40.242Z,1568055340.242 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190909T180812/Courier0012.lzma
2019-09-09T18:55:41.048Z,1568055341.048 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0012.lzma.bak
2019-09-09T18:55:41.048Z,1568055341.048 [DataOverHttps](INFO): SBD MOMSN=11758009
2019-09-09T18:55:53.831Z,1568055353.831 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20190909T180812/Express0013.lzma
2019-09-09T18:55:54.636Z,1568055354.636 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0013.lzma.bak
2019-09-09T18:55:54.636Z,1568055354.636 [DataOverHttps](INFO): SBD MOMSN=11758012
2019-09-09T18:55:56.792Z,1568055356.792 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T18:55:56.792Z,1568055356.792 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T18:55:56.792Z,1568055356.792 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T18:56:08.456Z,1568055368.455 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T18:58:08.485Z,1568055488.485 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:58:08.485Z,1568055488.485 [DVL_micro] No Fault, FailCount= 5
2019-09-09T18:58:08.847Z,1568055488.847 [DVL_micro](INFO): Initializing
2019-09-09T18:58:12.881Z,1568055492.881 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T18:58:12.881Z,1568055492.881 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T18:58:12.881Z,1568055492.881 [DVL_micro](ERROR): Data Fault
2019-09-09T18:58:12.919Z,1568055492.919 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T18:58:13.363Z,1568055493.363 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:58:14.144Z,1568055494.144 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:58:14.144Z,1568055494.144 [DVL_micro] No Fault, FailCount= 1
2019-09-09T18:58:14.519Z,1568055494.519 [DVL_micro](INFO): Initializing
2019-09-09T18:58:28.236Z,1568055508.236 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:58:28.236Z,1568055508.236 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T18:58:28.236Z,1568055508.236 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:58:28.331Z,1568055508.331 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:58:28.711Z,1568055508.711 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:58:29.484Z,1568055509.484 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:58:29.484Z,1568055509.484 [DVL_micro] No Fault, FailCount= 2
2019-09-09T18:58:29.862Z,1568055509.862 [DVL_micro](INFO): Initializing
2019-09-09T18:58:43.585Z,1568055523.585 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:58:43.585Z,1568055523.585 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T18:58:43.585Z,1568055523.585 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:58:43.654Z,1568055523.654 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:58:44.059Z,1568055524.059 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:58:44.892Z,1568055524.892 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:58:44.892Z,1568055524.892 [DVL_micro] No Fault, FailCount= 3
2019-09-09T18:58:45.225Z,1568055525.225 [DVL_micro](INFO): Initializing
2019-09-09T18:58:58.940Z,1568055538.940 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:58:58.940Z,1568055538.940 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T18:58:58.940Z,1568055538.940 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:58:58.991Z,1568055538.991 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:58:59.422Z,1568055539.422 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:59:00.184Z,1568055540.184 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T18:59:00.184Z,1568055540.184 [DVL_micro] No Fault, FailCount= 4
2019-09-09T18:59:00.562Z,1568055540.562 [DVL_micro](INFO): Initializing
2019-09-09T18:59:14.290Z,1568055554.290 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T18:59:14.290Z,1568055554.290 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T18:59:14.290Z,1568055554.290 [DVL_micro](ERROR): Communications Fault
2019-09-09T18:59:14.352Z,1568055554.352 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T18:59:14.352Z,1568055554.352 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T18:59:14.767Z,1568055554.767 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T18:59:14.806Z,1568055554.806 [CBIT](INFO): Critical error at 20190909T185914
2019-09-09T19:00:57.332Z,1568055657.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T19:00:57.332Z,1568055657.332 [Default:CheckIn:C.Wait] Stopped
2019-09-09T19:00:57.332Z,1568055657.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T19:00:57.332Z,1568055657.332 [Default:CheckIn:D] Running Loop=1
2019-09-09T19:00:57.739Z,1568055657.739 [Default:CheckIn:D] Stopped
2019-09-09T19:00:57.739Z,1568055657.739 [Default:CheckIn:E] Running Loop=1
2019-09-09T19:00:58.148Z,1568055658.148 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.750069 min 
2019-09-09T19:00:58.148Z,1568055658.148 [Default:CheckIn:E] Stopped
2019-09-09T19:00:58.148Z,1568055658.148 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T19:00:58.148Z,1568055658.148 [Default:CheckIn] Stopped
2019-09-09T19:00:58.148Z,1568055658.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T19:00:58.149Z,1568055658.149 [Default:CheckIn](INFO): Running loop #6
2019-09-09T19:00:58.149Z,1568055658.149 [Default:CheckIn] Running Loop=6
2019-09-09T19:00:58.149Z,1568055658.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T19:00:58.149Z,1568055658.149 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T19:00:59.740Z,1568055659.740 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T19:00:59.740Z,1568055659.740 [NAL9602] Data Fault, FailCount= 5
2019-09-09T19:00:59.740Z,1568055659.740 [NAL9602](ERROR): Data Fault
2019-09-09T19:00:59.819Z,1568055659.819 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T19:00:59.819Z,1568055659.819 [CBIT](CRITICAL): Data Fault in component: NAL9602
2019-09-09T19:01:00.144Z,1568055660.144 [NAL9602](INFO): Powering down
2019-09-09T19:01:00.181Z,1568055660.181 [CBIT](INFO): Critical error at 20190909T190059
2019-09-09T19:01:11.875Z,1568055671.875 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:01:17.126Z,1568055677.126 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:01:28.438Z,1568055688.438 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:01:39.743Z,1568055699.743 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:01:51.074Z,1568055711.074 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:02.370Z,1568055722.370 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:13.685Z,1568055733.685 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:25.400Z,1568055745.400 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:36.306Z,1568055756.306 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:48.020Z,1568055768.020 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:58.927Z,1568055778.927 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:02:59.776Z,1568055779.776 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T19:02:59.776Z,1568055779.776 [NAL9602] No Fault, FailCount= 5
2019-09-09T19:03:00.125Z,1568055780.125 [NAL9602](INFO): Powering up NAL9602
2019-09-09T19:03:09.847Z,1568055789.847 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:03:11.036Z,1568055791.036 [NAL9602](INFO): NAL9602 initialized
2019-09-09T19:03:11.854Z,1568055791.854 [NAL9602](DEBUG): Fix Requested
2019-09-09T19:03:21.155Z,1568055801.155 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:03:32.059Z,1568055812.059 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:05:58.337Z,1568055958.337 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T19:00:58.1Z
2019-09-09T19:05:58.338Z,1568055958.338 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T19:05:58.338Z,1568055958.338 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T19:06:04.718Z,1568055964.718 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20190909T180812/Courier0015.lzma
2019-09-09T19:06:05.524Z,1568055965.524 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0015.lzma.bak
2019-09-09T19:06:05.524Z,1568055965.524 [DataOverHttps](INFO): SBD MOMSN=11758025
2019-09-09T19:06:25.275Z,1568055985.275 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20190909T180812/Express0016.lzma
2019-09-09T19:06:26.080Z,1568055986.080 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0016.lzma.bak
2019-09-09T19:06:26.080Z,1568055986.080 [DataOverHttps](INFO): SBD MOMSN=11758033
2019-09-09T19:06:29.858Z,1568055989.858 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T19:06:29.858Z,1568055989.858 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T19:06:29.858Z,1568055989.858 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T19:08:14.037Z,1568056094.037 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T19:08:44.752Z,1568056124.752 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T19:09:14.695Z,1568056154.695 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:09:14.696Z,1568056154.696 [DVL_micro] No Fault, FailCount= 5
2019-09-09T19:09:15.065Z,1568056155.065 [DVL_micro](INFO): Initializing
2019-09-09T19:09:19.092Z,1568056159.092 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T19:09:19.092Z,1568056159.092 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T19:09:19.092Z,1568056159.092 [DVL_micro](ERROR): Data Fault
2019-09-09T19:09:19.146Z,1568056159.146 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T19:09:19.567Z,1568056159.567 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:09:20.335Z,1568056160.335 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:09:20.335Z,1568056160.335 [DVL_micro] No Fault, FailCount= 1
2019-09-09T19:09:20.714Z,1568056160.714 [DVL_micro](INFO): Initializing
2019-09-09T19:09:34.452Z,1568056174.452 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:09:34.452Z,1568056174.452 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T19:09:34.452Z,1568056174.452 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:09:34.524Z,1568056174.524 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:09:34.931Z,1568056174.931 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:09:35.735Z,1568056175.735 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:09:35.735Z,1568056175.735 [DVL_micro] No Fault, FailCount= 2
2019-09-09T19:09:36.063Z,1568056176.063 [DVL_micro](INFO): Initializing
2019-09-09T19:09:49.796Z,1568056189.796 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:09:49.796Z,1568056189.796 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T19:09:49.796Z,1568056189.796 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:09:49.848Z,1568056189.848 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:09:50.275Z,1568056190.275 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:09:51.049Z,1568056191.049 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:09:51.049Z,1568056191.049 [DVL_micro] No Fault, FailCount= 3
2019-09-09T19:09:51.418Z,1568056191.418 [DVL_micro](INFO): Initializing
2019-09-09T19:10:05.148Z,1568056205.148 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:10:05.148Z,1568056205.148 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T19:10:05.148Z,1568056205.148 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:10:05.220Z,1568056205.220 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:10:05.619Z,1568056205.619 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:10:06.403Z,1568056206.403 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:10:06.403Z,1568056206.403 [DVL_micro] No Fault, FailCount= 4
2019-09-09T19:10:06.769Z,1568056206.769 [DVL_micro](INFO): Initializing
2019-09-09T19:10:20.500Z,1568056220.500 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:10:20.500Z,1568056220.500 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T19:10:20.500Z,1568056220.500 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:10:20.572Z,1568056220.572 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:10:20.573Z,1568056220.573 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T19:10:20.971Z,1568056220.971 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:10:21.065Z,1568056221.065 [CBIT](INFO): Critical error at 20190909T191020
2019-09-09T19:10:49.240Z,1568056249.240 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:10:54.854Z,1568056254.854 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:11:07.783Z,1568056267.783 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:11:20.706Z,1568056280.706 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:11:30.438Z,1568056290.438 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T19:11:30.438Z,1568056290.438 [Default:CheckIn:C.Wait] Stopped
2019-09-09T19:11:30.438Z,1568056290.438 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T19:11:30.438Z,1568056290.438 [Default:CheckIn:D] Running Loop=1
2019-09-09T19:11:30.831Z,1568056290.831 [Default:CheckIn:D] Stopped
2019-09-09T19:11:30.832Z,1568056290.832 [Default:CheckIn:E] Running Loop=1
2019-09-09T19:11:31.222Z,1568056291.222 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.301611 min 
2019-09-09T19:11:31.222Z,1568056291.222 [Default:CheckIn:E] Stopped
2019-09-09T19:11:31.222Z,1568056291.222 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T19:11:31.223Z,1568056291.223 [Default:CheckIn] Stopped
2019-09-09T19:11:31.223Z,1568056291.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T19:11:31.223Z,1568056291.223 [Default:CheckIn](INFO): Running loop #7
2019-09-09T19:11:31.223Z,1568056291.223 [Default:CheckIn] Running Loop=7
2019-09-09T19:11:31.223Z,1568056291.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T19:11:31.223Z,1568056291.223 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T19:11:32.827Z,1568056292.827 [NAL9602](DEBUG): Fix Requested
2019-09-09T19:11:33.645Z,1568056293.645 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:11:45.772Z,1568056305.772 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:11:58.283Z,1568056318.283 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:12:11.221Z,1568056331.221 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:12:24.148Z,1568056344.148 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:12:36.658Z,1568056356.658 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:12:49.584Z,1568056369.584 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:13:02.512Z,1568056382.512 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:15:17.531Z,1568056517.531 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2019-09-09T19:15:17.534Z,1568056517.534 [BPC1](FAULT): Failed to receive data from 1 sticks prior to timeout. Missing stick IDs are: 25.
2019-09-09T19:15:17.534Z,1568056517.534 [BPC1] Data Fault, FailCount= 4
2019-09-09T19:15:17.534Z,1568056517.534 [BPC1](ERROR): Data Fault
2019-09-09T19:15:17.592Z,1568056517.592 [CBIT](ERROR): Data Fault in component: BPC1
2019-09-09T19:16:31.399Z,1568056591.399 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T19:11:31.2Z
2019-09-09T19:16:31.399Z,1568056591.399 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T19:16:31.400Z,1568056591.400 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T19:16:35.008Z,1568056595.008 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T19:16:37.734Z,1568056597.734 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20190909T180812/Courier0018.lzma
2019-09-09T19:16:38.540Z,1568056598.540 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0018.lzma.bak
2019-09-09T19:16:38.540Z,1568056598.540 [DataOverHttps](INFO): SBD MOMSN=11758047
2019-09-09T19:16:50.862Z,1568056610.862 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20190909T180812/Express0019.lzma
2019-09-09T19:16:51.668Z,1568056611.668 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0019.lzma.bak
2019-09-09T19:16:51.668Z,1568056611.668 [DataOverHttps](INFO): SBD MOMSN=11758055
2019-09-09T19:16:54.035Z,1568056614.035 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T19:16:54.035Z,1568056614.035 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T19:16:54.035Z,1568056614.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T19:17:05.709Z,1568056625.709 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T19:18:50.759Z,1568056730.759 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-09-09T19:18:50.759Z,1568056730.759 [AHRS_M2] Data Fault, FailCount= 1
2019-09-09T19:18:50.759Z,1568056730.759 [AHRS_M2](ERROR): Data Fault
2019-09-09T19:18:50.798Z,1568056730.798 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-09-09T19:18:51.143Z,1568056731.143 [AHRS_M2](INFO): Powering down
2019-09-09T19:18:51.547Z,1568056731.547 [AHRS_M2](FAULT): LCB fault:  Software Overcurrent.
2019-09-09T19:18:51.547Z,1568056731.547 [AHRS_M2] Hardware Fault, FailCount= 1
2019-09-09T19:18:51.547Z,1568056731.547 [AHRS_M2](ERROR): Hardware Fault
2019-09-09T19:18:51.999Z,1568056731.999 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:18:51.999Z,1568056731.999 [AHRS_M2] No Fault, FailCount= 1
2019-09-09T19:18:52.355Z,1568056732.355 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:18:55.911Z,1568056735.911 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:56.307Z,1568056736.307 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:56.719Z,1568056736.719 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:57.115Z,1568056737.115 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:57.527Z,1568056737.527 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:57.931Z,1568056737.931 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:58.335Z,1568056738.335 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:58.755Z,1568056738.755 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:59.147Z,1568056739.147 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:59.547Z,1568056739.547 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:18:59.943Z,1568056739.943 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:00.347Z,1568056740.347 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:00.759Z,1568056740.759 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:01.171Z,1568056741.171 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:01.587Z,1568056741.587 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:01.987Z,1568056741.987 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:02.383Z,1568056742.383 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:02.779Z,1568056742.779 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:03.191Z,1568056743.191 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:03.595Z,1568056743.595 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:03.999Z,1568056743.999 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:04.399Z,1568056744.399 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:04.807Z,1568056744.807 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:05.223Z,1568056745.223 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:05.223Z,1568056745.223 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-09T19:19:05.223Z,1568056745.223 [AHRS_M2] Communications Fault, FailCount= 2
2019-09-09T19:19:05.223Z,1568056745.223 [AHRS_M2](ERROR): Communications Fault
2019-09-09T19:19:05.267Z,1568056745.267 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-09T19:19:05.311Z,1568056745.311 [AHRS_M2](INFO): Powering down
2019-09-09T19:19:06.142Z,1568056746.142 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:19:06.142Z,1568056746.142 [AHRS_M2] No Fault, FailCount= 2
2019-09-09T19:19:06.503Z,1568056746.503 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:19:10.059Z,1568056750.059 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:10.455Z,1568056750.455 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:10.867Z,1568056750.867 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:11.271Z,1568056751.271 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:11.675Z,1568056751.675 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:12.091Z,1568056752.091 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:12.491Z,1568056752.491 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:12.887Z,1568056752.887 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:13.287Z,1568056753.287 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:13.695Z,1568056753.695 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:14.091Z,1568056754.091 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:14.503Z,1568056754.503 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:14.907Z,1568056754.907 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:15.311Z,1568056755.311 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:15.735Z,1568056755.735 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:16.127Z,1568056756.127 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:16.523Z,1568056756.523 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:16.919Z,1568056756.919 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:17.327Z,1568056757.327 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:17.735Z,1568056757.735 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:18.139Z,1568056758.139 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:18.555Z,1568056758.555 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:18.963Z,1568056758.963 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:19.351Z,1568056759.351 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:19.351Z,1568056759.351 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-09T19:19:19.351Z,1568056759.351 [AHRS_M2] Communications Fault, FailCount= 3
2019-09-09T19:19:19.351Z,1568056759.351 [AHRS_M2](ERROR): Communications Fault
2019-09-09T19:19:19.384Z,1568056759.384 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-09T19:19:19.431Z,1568056759.431 [AHRS_M2](INFO): Powering down
2019-09-09T19:19:20.278Z,1568056760.278 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:19:20.278Z,1568056760.278 [AHRS_M2] No Fault, FailCount= 3
2019-09-09T19:19:20.643Z,1568056760.643 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:19:24.199Z,1568056764.199 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:24.591Z,1568056764.591 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:25.011Z,1568056765.011 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:25.439Z,1568056765.439 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:25.831Z,1568056765.831 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:26.247Z,1568056766.247 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:26.651Z,1568056766.651 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:27.043Z,1568056767.043 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:27.447Z,1568056767.447 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:27.851Z,1568056767.851 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:28.255Z,1568056768.255 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:28.659Z,1568056768.659 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:29.063Z,1568056769.063 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:29.475Z,1568056769.475 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:29.887Z,1568056769.887 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:30.271Z,1568056770.271 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:30.671Z,1568056770.671 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:31.075Z,1568056771.075 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:31.487Z,1568056771.487 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:31.891Z,1568056771.891 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:32.295Z,1568056772.295 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:32.707Z,1568056772.707 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:33.115Z,1568056773.115 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:33.515Z,1568056773.515 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:33.515Z,1568056773.515 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-09T19:19:33.515Z,1568056773.515 [AHRS_M2] Communications Fault, FailCount= 4
2019-09-09T19:19:33.515Z,1568056773.515 [AHRS_M2](ERROR): Communications Fault
2019-09-09T19:19:33.547Z,1568056773.547 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-09T19:19:33.587Z,1568056773.587 [AHRS_M2](INFO): Powering down
2019-09-09T19:19:34.434Z,1568056774.434 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:19:34.434Z,1568056774.434 [AHRS_M2] No Fault, FailCount= 4
2019-09-09T19:19:34.799Z,1568056774.799 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:19:38.347Z,1568056778.347 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:38.759Z,1568056778.759 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:39.171Z,1568056779.171 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:39.575Z,1568056779.575 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:39.999Z,1568056779.999 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:40.375Z,1568056780.375 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:40.779Z,1568056780.779 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:41.179Z,1568056781.179 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:41.587Z,1568056781.587 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:41.991Z,1568056781.991 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:42.395Z,1568056782.395 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:42.799Z,1568056782.799 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:43.211Z,1568056783.211 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:43.619Z,1568056783.619 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:44.011Z,1568056784.011 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:44.415Z,1568056784.415 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:44.819Z,1568056784.819 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:45.223Z,1568056785.223 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:45.627Z,1568056785.627 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:46.031Z,1568056786.031 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:46.447Z,1568056786.447 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:46.855Z,1568056786.855 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:47.235Z,1568056787.235 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:47.639Z,1568056787.639 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-09-09T19:19:47.639Z,1568056787.639 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-09-09T19:19:47.639Z,1568056787.639 [AHRS_M2] Communications Fault, FailCount= 5
2019-09-09T19:19:47.639Z,1568056787.639 [AHRS_M2](ERROR): Communications Fault
2019-09-09T19:19:47.699Z,1568056787.699 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-09-09T19:19:47.700Z,1568056787.700 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-09-09T19:19:47.727Z,1568056787.727 [AHRS_M2](INFO): Powering down
2019-09-09T19:19:47.843Z,1568056787.843 [CBIT](INFO): Critical error at 20190909T191947
2019-09-09T19:20:20.506Z,1568056820.506 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:20:20.506Z,1568056820.506 [DVL_micro] No Fault, FailCount= 5
2019-09-09T19:20:20.866Z,1568056820.866 [DVL_micro](INFO): Initializing
2019-09-09T19:20:24.904Z,1568056824.904 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T19:20:24.904Z,1568056824.904 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T19:20:24.904Z,1568056824.904 [DVL_micro](ERROR): Data Fault
2019-09-09T19:20:24.952Z,1568056824.952 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T19:20:25.375Z,1568056825.375 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:20:26.142Z,1568056826.142 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:20:26.142Z,1568056826.142 [DVL_micro] No Fault, FailCount= 1
2019-09-09T19:20:26.526Z,1568056826.526 [DVL_micro](INFO): Initializing
2019-09-09T19:20:40.252Z,1568056840.252 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:20:40.252Z,1568056840.252 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T19:20:40.252Z,1568056840.252 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:20:40.291Z,1568056840.291 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:20:40.729Z,1568056840.729 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:20:41.496Z,1568056841.496 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:20:41.496Z,1568056841.496 [DVL_micro] No Fault, FailCount= 2
2019-09-09T19:20:41.878Z,1568056841.878 [DVL_micro](INFO): Initializing
2019-09-09T19:20:55.608Z,1568056855.608 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:20:55.608Z,1568056855.608 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T19:20:55.608Z,1568056855.608 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:20:55.655Z,1568056855.655 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:20:56.079Z,1568056856.079 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:20:56.851Z,1568056856.851 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:20:56.851Z,1568056856.851 [DVL_micro] No Fault, FailCount= 3
2019-09-09T19:20:57.226Z,1568056857.226 [DVL_micro](INFO): Initializing
2019-09-09T19:21:10.956Z,1568056870.956 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:21:10.956Z,1568056870.956 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T19:21:10.956Z,1568056870.956 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:21:10.988Z,1568056870.988 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:21:11.435Z,1568056871.435 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:21:12.195Z,1568056872.195 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:21:12.195Z,1568056872.195 [DVL_micro] No Fault, FailCount= 4
2019-09-09T19:21:12.579Z,1568056872.579 [DVL_micro](INFO): Initializing
2019-09-09T19:21:26.308Z,1568056886.308 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:21:26.308Z,1568056886.308 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T19:21:26.308Z,1568056886.308 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:21:26.363Z,1568056886.363 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:21:26.363Z,1568056886.363 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T19:21:26.783Z,1568056886.783 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:21:26.821Z,1568056886.821 [CBIT](INFO): Critical error at 20190909T192126
2019-09-09T19:21:54.609Z,1568056914.609 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T19:21:54.609Z,1568056914.609 [Default:CheckIn:C.Wait] Stopped
2019-09-09T19:21:54.609Z,1568056914.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T19:21:54.610Z,1568056914.610 [Default:CheckIn:D] Running Loop=1
2019-09-09T19:21:55.018Z,1568056915.018 [Default:CheckIn:D] Stopped
2019-09-09T19:21:55.018Z,1568056915.018 [Default:CheckIn:E] Running Loop=1
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.704720 min 
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn:E] Stopped
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn] Stopped
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn](INFO): Running loop #8
2019-09-09T19:21:55.418Z,1568056915.418 [Default:CheckIn] Running Loop=8
2019-09-09T19:21:55.419Z,1568056915.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T19:21:55.419Z,1568056915.419 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T19:21:57.009Z,1568056917.009 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T19:21:57.009Z,1568056917.009 [NAL9602] Data Fault, FailCount= 1
2019-09-09T19:21:57.009Z,1568056917.009 [NAL9602](ERROR): Data Fault
2019-09-09T19:21:57.083Z,1568056917.083 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T19:21:57.416Z,1568056917.416 [NAL9602](INFO): Powering down
2019-09-09T19:21:58.251Z,1568056918.251 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T19:21:58.251Z,1568056918.251 [NAL9602] No Fault, FailCount= 1
2019-09-09T19:22:27.744Z,1568056947.744 [NAL9602](INFO): Powering up NAL9602
2019-09-09T19:22:38.648Z,1568056958.648 [NAL9602](INFO): NAL9602 initialized
2019-09-09T19:22:39.467Z,1568056959.467 [NAL9602](DEBUG): Fix Requested
2019-09-09T19:23:25.943Z,1568057005.943 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-09-09T19:23:25.943Z,1568057005.943 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-09T19:23:25.943Z,1568057005.943 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-09T19:23:25.955Z,1568057005.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-09T19:23:26.353Z,1568057006.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-09T19:23:26.353Z,1568057006.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0
2019-09-09T19:24:47.000Z,1568057088.000 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:24:47.000Z,1568057088.000 [AHRS_M2] No Fault, FailCount= 5
2019-09-09T19:24:48.355Z,1568057088.355 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:25:01.706Z,1568057101.706 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-09-09T19:25:01.707Z,1568057101.707 [AHRS_M2] Data Fault, FailCount= 1
2019-09-09T19:25:01.707Z,1568057101.707 [AHRS_M2](ERROR): Data Fault
2019-09-09T19:25:01.747Z,1568057101.747 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-09-09T19:25:02.091Z,1568057102.091 [AHRS_M2](INFO): Powering down
2019-09-09T19:25:02.955Z,1568057102.955 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:25:02.955Z,1568057102.955 [AHRS_M2] No Fault, FailCount= 1
2019-09-09T19:25:03.303Z,1568057103.303 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:25:16.655Z,1568057116.655 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-09-09T19:25:16.655Z,1568057116.655 [AHRS_M2] Data Fault, FailCount= 2
2019-09-09T19:25:16.655Z,1568057116.655 [AHRS_M2](ERROR): Data Fault
2019-09-09T19:25:16.727Z,1568057116.727 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-09-09T19:25:17.039Z,1568057117.039 [AHRS_M2](INFO): Powering down
2019-09-09T19:25:17.887Z,1568057117.887 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:25:17.887Z,1568057117.887 [AHRS_M2] No Fault, FailCount= 2
2019-09-09T19:25:18.251Z,1568057118.251 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:25:18.714Z,1568057118.714 [CBIT](INFO): Clearing failed state for component BPC1
2019-09-09T19:25:18.714Z,1568057118.714 [BPC1] No Fault, FailCount= 4
2019-09-09T19:25:31.602Z,1568057131.602 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-09-09T19:25:31.603Z,1568057131.603 [AHRS_M2] Data Fault, FailCount= 3
2019-09-09T19:25:31.603Z,1568057131.603 [AHRS_M2](ERROR): Data Fault
2019-09-09T19:25:31.635Z,1568057131.635 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-09-09T19:25:31.987Z,1568057131.987 [AHRS_M2](INFO): Powering down
2019-09-09T19:25:32.831Z,1568057132.831 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:25:32.831Z,1568057132.831 [AHRS_M2] No Fault, FailCount= 3
2019-09-09T19:25:33.199Z,1568057133.199 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:25:46.550Z,1568057146.550 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-09-09T19:25:46.551Z,1568057146.551 [AHRS_M2] Data Fault, FailCount= 4
2019-09-09T19:25:46.551Z,1568057146.551 [AHRS_M2](ERROR): Data Fault
2019-09-09T19:25:46.591Z,1568057146.591 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-09-09T19:25:46.935Z,1568057146.935 [AHRS_M2](INFO): Powering down
2019-09-09T19:25:47.803Z,1568057147.803 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:25:47.803Z,1568057147.803 [AHRS_M2] No Fault, FailCount= 4
2019-09-09T19:25:48.147Z,1568057148.147 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:26:01.498Z,1568057161.498 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-09-09T19:26:01.499Z,1568057161.499 [AHRS_M2] Data Fault, FailCount= 5
2019-09-09T19:26:01.499Z,1568057161.499 [AHRS_M2](ERROR): Data Fault
2019-09-09T19:26:01.567Z,1568057161.567 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-09-09T19:26:01.567Z,1568057161.567 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-09-09T19:26:01.883Z,1568057161.883 [AHRS_M2](INFO): Powering down
2019-09-09T19:26:02.003Z,1568057162.003 [CBIT](INFO): Critical error at 20190909T192601
2019-09-09T19:26:55.647Z,1568057215.647 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T19:21:55.4Z
2019-09-09T19:26:55.647Z,1568057215.647 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T19:26:55.647Z,1568057215.647 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T19:27:01.526Z,1568057221.526 [DataOverHttps](INFO): Sending 110 bytes from file Logs/20190909T180812/Courier0021.lzma
2019-09-09T19:27:02.332Z,1568057222.332 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0021.lzma.bak
2019-09-09T19:27:02.333Z,1568057222.333 [DataOverHttps](INFO): SBD MOMSN=11758070
2019-09-09T19:27:14.867Z,1568057234.867 [DataOverHttps](INFO): Sending 472 bytes from file Logs/20190909T180812/Express0022.lzma
2019-09-09T19:27:15.672Z,1568057235.672 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0022.lzma.bak
2019-09-09T19:27:15.672Z,1568057235.672 [DataOverHttps](INFO): SBD MOMSN=11758081
2019-09-09T19:27:17.893Z,1568057237.893 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T19:27:17.893Z,1568057237.893 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T19:27:17.893Z,1568057237.893 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T19:27:41.680Z,1568057261.680 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T19:28:01.537Z,1568057281.537 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-09-09T19:28:01.538Z,1568057281.538 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-09T19:28:01.538Z,1568057281.538 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-09T19:28:01.549Z,1568057281.549 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-09T19:28:01.946Z,1568057281.946 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-09T19:28:01.947Z,1568057281.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-09T19:28:12.384Z,1568057292.384 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T19:29:21.480Z,1568057361.480 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-09-09T19:30:02.307Z,1568057402.307 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-09-09T19:30:02.307Z,1568057402.307 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-09-09T19:30:02.307Z,1568057402.307 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-09T19:30:02.318Z,1568057402.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-09T19:30:02.723Z,1568057402.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-09T19:30:02.723Z,1568057402.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-09-09T19:31:01.698Z,1568057461.698 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-09-09T19:31:01.698Z,1568057461.698 [AHRS_M2] No Fault, FailCount= 5
2019-09-09T19:31:02.055Z,1568057462.055 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-09T19:31:05.713Z,1568057465.713 [AHRS_M2](INFO): Failure count cleared after critical for AHRS_M2
2019-09-09T19:31:26.337Z,1568057486.337 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:31:26.337Z,1568057486.337 [DVL_micro] No Fault, FailCount= 5
2019-09-09T19:31:26.718Z,1568057486.718 [DVL_micro](INFO): Initializing
2019-09-09T19:31:30.756Z,1568057490.756 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T19:31:30.756Z,1568057490.756 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T19:31:30.756Z,1568057490.756 [DVL_micro](ERROR): Data Fault
2019-09-09T19:31:30.790Z,1568057490.790 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T19:31:31.235Z,1568057491.235 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:31:32.143Z,1568057492.143 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:31:32.143Z,1568057492.143 [DVL_micro] No Fault, FailCount= 1
2019-09-09T19:31:32.378Z,1568057492.378 [DVL_micro](INFO): Initializing
2019-09-09T19:31:46.104Z,1568057506.104 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:31:46.104Z,1568057506.104 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T19:31:46.104Z,1568057506.104 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:31:46.176Z,1568057506.176 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:31:46.579Z,1568057506.579 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:31:47.351Z,1568057507.351 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:31:47.351Z,1568057507.351 [DVL_micro] No Fault, FailCount= 2
2019-09-09T19:31:47.734Z,1568057507.734 [DVL_micro](INFO): Initializing
2019-09-09T19:32:01.453Z,1568057521.453 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:32:01.454Z,1568057521.454 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T19:32:01.454Z,1568057521.454 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:32:01.518Z,1568057521.518 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:32:01.947Z,1568057521.947 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:32:02.775Z,1568057522.775 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:32:02.775Z,1568057522.775 [DVL_micro] No Fault, FailCount= 3
2019-09-09T19:32:03.081Z,1568057523.081 [DVL_micro](INFO): Initializing
2019-09-09T19:32:16.812Z,1568057536.812 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:32:16.812Z,1568057536.812 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T19:32:16.812Z,1568057536.812 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:32:16.863Z,1568057536.863 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:32:17.279Z,1568057537.279 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:32:18.082Z,1568057538.082 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:32:18.082Z,1568057538.082 [DVL_micro] No Fault, FailCount= 4
2019-09-09T19:32:18.427Z,1568057538.427 [DVL_micro](INFO): Initializing
2019-09-09T19:32:18.452Z,1568057538.452 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T19:32:18.453Z,1568057538.453 [Default:CheckIn:C.Wait] Stopped
2019-09-09T19:32:18.453Z,1568057538.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T19:32:18.453Z,1568057538.453 [Default:CheckIn:D] Running Loop=1
2019-09-09T19:32:18.858Z,1568057538.858 [Default:CheckIn:D] Stopped
2019-09-09T19:32:18.858Z,1568057538.858 [Default:CheckIn:E] Running Loop=1
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.102059 min 
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn:E] Stopped
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn] Stopped
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn](INFO): Running loop #9
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn] Running Loop=9
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T19:32:19.260Z,1568057539.260 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T19:32:20.866Z,1568057540.866 [NAL9602](DEBUG): Fix Requested
2019-09-09T19:32:32.160Z,1568057552.160 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:32:32.160Z,1568057552.160 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T19:32:32.160Z,1568057552.160 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:32:32.199Z,1568057552.199 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:32:32.200Z,1568057552.200 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T19:32:32.635Z,1568057552.635 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:32:32.686Z,1568057552.686 [CBIT](INFO): Critical error at 20190909T193232
2019-09-09T19:32:41.875Z,1568057561.875 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:32:53.596Z,1568057573.596 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:33:04.902Z,1568057584.902 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:33:16.230Z,1568057596.230 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:33:27.537Z,1568057607.537 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:33:38.838Z,1568057618.838 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:33:50.151Z,1568057630.151 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:34:01.456Z,1568057641.456 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:34:12.775Z,1568057652.775 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:34:24.093Z,1568057664.093 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:34:35.412Z,1568057675.412 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:34:46.710Z,1568057686.710 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:34:58.019Z,1568057698.019 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:37:19.511Z,1568057839.511 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T19:32:19.3Z
2019-09-09T19:37:19.512Z,1568057839.512 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T19:37:19.512Z,1568057839.512 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T19:37:23.928Z,1568057843.928 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T19:37:26.070Z,1568057846.070 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190909T180812/Courier0024.lzma
2019-09-09T19:37:26.876Z,1568057846.876 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0024.lzma.bak
2019-09-09T19:37:26.876Z,1568057846.876 [DataOverHttps](INFO): SBD MOMSN=11758171
2019-09-09T19:37:39.555Z,1568057859.555 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20190909T180812/Express0025.lzma
2019-09-09T19:37:40.360Z,1568057860.360 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0025.lzma.bak
2019-09-09T19:37:40.360Z,1568057860.360 [DataOverHttps](INFO): SBD MOMSN=11758179
2019-09-09T19:37:42.969Z,1568057862.969 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T19:37:42.969Z,1568057862.969 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T19:37:42.969Z,1568057862.969 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T19:37:54.635Z,1568057874.635 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T19:39:20.355Z,1568057960.355 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:39:47.019Z,1568057987.019 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:41:08.228Z,1568058068.228 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:42:08.385Z,1568058128.385 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:42:16.452Z,1568058136.452 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:42:30.591Z,1568058150.591 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:42:32.304Z,1568058152.304 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:42:32.304Z,1568058152.304 [DVL_micro] No Fault, FailCount= 5
2019-09-09T19:42:32.594Z,1568058152.594 [DVL_micro](INFO): Initializing
2019-09-09T19:42:33.177Z,1568058153.177 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:42:36.628Z,1568058156.628 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T19:42:36.628Z,1568058156.628 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T19:42:36.628Z,1568058156.628 [DVL_micro](ERROR): Data Fault
2019-09-09T19:42:36.789Z,1568058156.789 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T19:42:37.111Z,1568058157.111 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:42:37.951Z,1568058157.951 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:42:37.951Z,1568058157.951 [DVL_micro] No Fault, FailCount= 1
2019-09-09T19:42:38.249Z,1568058158.249 [DVL_micro](INFO): Initializing
2019-09-09T19:42:43.530Z,1568058163.530 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T19:42:43.530Z,1568058163.530 [Default:CheckIn:C.Wait] Stopped
2019-09-09T19:42:43.530Z,1568058163.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T19:42:43.535Z,1568058163.535 [Default:CheckIn:D] Running Loop=1
2019-09-09T19:42:43.950Z,1568058163.950 [Default:CheckIn:D] Stopped
2019-09-09T19:42:43.955Z,1568058163.955 [Default:CheckIn:E] Running Loop=1
2019-09-09T19:42:44.355Z,1568058164.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.520256 min 
2019-09-09T19:42:44.355Z,1568058164.355 [Default:CheckIn:E] Stopped
2019-09-09T19:42:44.355Z,1568058164.355 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T19:42:44.356Z,1568058164.356 [Default:CheckIn] Stopped
2019-09-09T19:42:44.356Z,1568058164.356 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T19:42:44.356Z,1568058164.356 [Default:CheckIn](INFO): Running loop #10
2019-09-09T19:42:44.356Z,1568058164.356 [Default:CheckIn] Running Loop=10
2019-09-09T19:42:44.356Z,1568058164.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T19:42:44.356Z,1568058164.356 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T19:42:45.533Z,1568058165.533 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:42:45.920Z,1568058165.920 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T19:42:45.920Z,1568058165.920 [NAL9602] Data Fault, FailCount= 2
2019-09-09T19:42:45.921Z,1568058165.921 [NAL9602](ERROR): Data Fault
2019-09-09T19:42:46.042Z,1568058166.042 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T19:42:46.324Z,1568058166.324 [NAL9602](INFO): Powering down
2019-09-09T19:42:47.207Z,1568058167.207 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T19:42:47.207Z,1568058167.207 [NAL9602] No Fault, FailCount= 2
2019-09-09T19:42:51.980Z,1568058171.980 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:42:51.980Z,1568058171.980 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T19:42:51.980Z,1568058171.980 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:42:52.073Z,1568058172.073 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:42:52.459Z,1568058172.459 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:42:53.255Z,1568058173.255 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:42:53.255Z,1568058173.255 [DVL_micro] No Fault, FailCount= 2
2019-09-09T19:42:53.602Z,1568058173.602 [DVL_micro](INFO): Initializing
2019-09-09T19:43:00.077Z,1568058180.077 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:43:07.332Z,1568058187.332 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:43:07.332Z,1568058187.332 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T19:43:07.332Z,1568058187.332 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:43:07.388Z,1568058187.388 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:43:07.819Z,1568058187.819 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:43:08.639Z,1568058188.639 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:43:08.639Z,1568058188.639 [DVL_micro] No Fault, FailCount= 3
2019-09-09T19:43:08.953Z,1568058188.953 [DVL_micro](INFO): Initializing
2019-09-09T19:43:14.222Z,1568058194.222 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:43:16.624Z,1568058196.624 [NAL9602](INFO): Powering up NAL9602
2019-09-09T19:43:22.696Z,1568058202.696 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:43:22.696Z,1568058202.696 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T19:43:22.696Z,1568058202.696 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:43:22.816Z,1568058202.816 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:43:23.163Z,1568058203.163 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:43:23.967Z,1568058203.967 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:43:23.967Z,1568058203.967 [DVL_micro] No Fault, FailCount= 4
2019-09-09T19:43:24.318Z,1568058204.318 [DVL_micro](INFO): Initializing
2019-09-09T19:43:27.533Z,1568058207.533 [NAL9602](INFO): NAL9602 initialized
2019-09-09T19:43:28.355Z,1568058208.355 [NAL9602](DEBUG): Fix Requested
2019-09-09T19:43:28.388Z,1568058208.388 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:43:38.036Z,1568058218.036 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:43:38.037Z,1568058218.037 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T19:43:38.037Z,1568058218.037 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:43:38.140Z,1568058218.140 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:43:38.140Z,1568058218.140 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T19:43:38.503Z,1568058218.503 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:43:38.585Z,1568058218.585 [CBIT](INFO): Critical error at 20190909T194338
2019-09-09T19:43:42.900Z,1568058222.900 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:43:57.053Z,1568058237.053 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:44:11.789Z,1568058251.789 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:44:12.017Z,1568058252.017 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:44:26.127Z,1568058266.127 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:45:07.057Z,1568058307.057 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:45:31.362Z,1568058331.362 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:45:43.808Z,1568058343.808 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:45:57.180Z,1568058357.180 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:47:31.686Z,1568058451.686 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:47:44.524Z,1568058464.524 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T19:42:44.4Z
2019-09-09T19:47:44.524Z,1568058464.524 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T19:47:44.524Z,1568058464.524 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T19:47:56.638Z,1568058476.638 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190909T180812/Courier0027.lzma
2019-09-09T19:47:57.444Z,1568058477.444 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0027.lzma.bak
2019-09-09T19:47:57.444Z,1568058477.444 [DataOverHttps](INFO): SBD MOMSN=11758190
2019-09-09T19:48:12.465Z,1568058492.465 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:48:22.139Z,1568058502.139 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20190909T180812/Express0028.lzma
2019-09-09T19:48:22.945Z,1568058502.945 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0028.lzma.bak
2019-09-09T19:48:22.946Z,1568058502.946 [DataOverHttps](INFO): SBD MOMSN=11758198
2019-09-09T19:48:27.727Z,1568058507.727 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T19:48:27.727Z,1568058507.727 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T19:48:27.727Z,1568058507.727 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T19:48:30.510Z,1568058510.510 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T19:49:01.212Z,1568058541.212 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T19:49:06.606Z,1568058546.606 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:50:00.422Z,1568058600.422 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:50:18.922Z,1568058618.922 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:50:54.100Z,1568058654.100 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:51:06.642Z,1568058666.642 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:51:19.923Z,1568058679.923 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:51:55.763Z,1568058715.763 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:52:09.504Z,1568058729.504 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:52:11.199Z,1568058731.199 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:52:23.632Z,1568058743.632 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:52:37.368Z,1568058757.368 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:52:51.507Z,1568058771.507 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:53:06.460Z,1568058786.460 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:53:21.419Z,1568058801.419 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:53:28.331Z,1568058808.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-09-09T19:53:28.331Z,1568058808.331 [Default:CheckIn:C.Wait] Stopped
2019-09-09T19:53:28.331Z,1568058808.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T19:53:28.331Z,1568058808.331 [Default:CheckIn:D] Running Loop=1
2019-09-09T19:53:28.740Z,1568058808.740 [Default:CheckIn:D] Stopped
2019-09-09T19:53:28.740Z,1568058808.740 [Default:CheckIn:E] Running Loop=1
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.266756 min 
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn:E] Stopped
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn] Stopped
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn](INFO): Running loop #11
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn] Running Loop=11
2019-09-09T19:53:29.108Z,1568058809.108 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-09T19:53:29.109Z,1568058809.109 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-09T19:53:30.680Z,1568058810.680 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-09-09T19:53:30.680Z,1568058810.680 [NAL9602] Data Fault, FailCount= 3
2019-09-09T19:53:30.680Z,1568058810.680 [NAL9602](ERROR): Data Fault
2019-09-09T19:53:30.783Z,1568058810.783 [CBIT](ERROR): Data Fault in component: NAL9602
2019-09-09T19:53:31.084Z,1568058811.084 [NAL9602](INFO): Powering down
2019-09-09T19:53:31.945Z,1568058811.945 [CBIT](INFO): Clearing failed state for component NAL9602
2019-09-09T19:53:31.945Z,1568058811.945 [NAL9602] No Fault, FailCount= 3
2019-09-09T19:53:35.140Z,1568058815.140 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:53:38.533Z,1568058818.533 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:53:38.533Z,1568058818.533 [DVL_micro] No Fault, FailCount= 5
2019-09-09T19:53:38.766Z,1568058818.766 [DVL_micro](INFO): Initializing
2019-09-09T19:53:42.804Z,1568058822.804 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-09-09T19:53:42.804Z,1568058822.804 [DVL_micro] Data Fault, FailCount= 1
2019-09-09T19:53:42.804Z,1568058822.804 [DVL_micro](ERROR): Data Fault
2019-09-09T19:53:42.868Z,1568058822.868 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-09-09T19:53:43.279Z,1568058823.279 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:53:44.088Z,1568058824.088 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:53:44.088Z,1568058824.088 [DVL_micro] No Fault, FailCount= 1
2019-09-09T19:53:44.422Z,1568058824.422 [DVL_micro](INFO): Initializing
2019-09-09T19:53:49.688Z,1568058829.688 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:53:58.152Z,1568058838.152 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:53:58.152Z,1568058838.152 [DVL_micro] Communications Fault, FailCount= 2
2019-09-09T19:53:58.152Z,1568058838.152 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:53:58.235Z,1568058838.235 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:53:58.627Z,1568058838.627 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:53:59.436Z,1568058839.436 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:53:59.436Z,1568058839.436 [DVL_micro] No Fault, FailCount= 2
2019-09-09T19:53:59.774Z,1568058839.774 [DVL_micro](INFO): Initializing
2019-09-09T19:54:01.399Z,1568058841.399 [NAL9602](INFO): Powering up NAL9602
2019-09-09T19:54:04.637Z,1568058844.637 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2019-09-09T19:54:12.292Z,1568058852.292 [NAL9602](INFO): NAL9602 initialized
2019-09-09T19:54:13.116Z,1568058853.116 [NAL9602](DEBUG): Fix Requested
2019-09-09T19:54:13.517Z,1568058853.517 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:54:13.517Z,1568058853.517 [DVL_micro] Communications Fault, FailCount= 3
2019-09-09T19:54:13.517Z,1568058853.517 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:54:13.616Z,1568058853.616 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:54:13.979Z,1568058853.979 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:54:14.785Z,1568058854.785 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:54:14.785Z,1568058854.785 [DVL_micro] No Fault, FailCount= 3
2019-09-09T19:54:15.126Z,1568058855.126 [DVL_micro](INFO): Initializing
2019-09-09T19:54:28.868Z,1568058868.868 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:54:28.868Z,1568058868.868 [DVL_micro] Communications Fault, FailCount= 4
2019-09-09T19:54:28.868Z,1568058868.868 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:54:28.960Z,1568058868.960 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:54:29.335Z,1568058869.335 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:54:30.131Z,1568058870.131 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-09-09T19:54:30.131Z,1568058870.131 [DVL_micro] No Fault, FailCount= 4
2019-09-09T19:54:30.478Z,1568058870.478 [DVL_micro](INFO): Initializing
2019-09-09T19:54:32.228Z,1568058872.228 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-09-09T19:54:44.208Z,1568058884.208 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-09-09T19:54:44.208Z,1568058884.208 [DVL_micro] Communications Fault, FailCount= 5
2019-09-09T19:54:44.208Z,1568058884.208 [DVL_micro](ERROR): Communications Fault
2019-09-09T19:54:44.276Z,1568058884.276 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-09-09T19:54:44.277Z,1568058884.277 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-09-09T19:54:44.687Z,1568058884.687 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T19:54:44.767Z,1568058884.767 [CBIT](INFO): Critical error at 20190909T195444
2019-09-09T19:54:52.059Z,1568058892.059 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-09T19:56:19.571Z,1568058979.571 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2019-09-09T19:56:19.574Z,1568058979.574 [BPC1](FAULT): Failed to receive data from 1 sticks prior to timeout. Missing stick IDs are: 25.
2019-09-09T19:56:19.574Z,1568058979.574 [BPC1] Data Fault, FailCount= 5
2019-09-09T19:56:19.574Z,1568058979.574 [BPC1](ERROR): Data Fault
2019-09-09T19:56:19.640Z,1568058979.640 [CBIT](ERROR): Data Fault in component: BPC1
2019-09-09T19:58:29.297Z,1568059109.297 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-09-09T19:53:29.1Z
2019-09-09T19:58:29.298Z,1568059109.298 [Default:CheckIn:Read_GPS] Stopped
2019-09-09T19:58:29.298Z,1568059109.298 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-09-09T19:58:41.171Z,1568059121.171 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20190909T180812/Courier0030.lzma
2019-09-09T19:58:41.976Z,1568059121.976 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Courier0030.lzma.bak
2019-09-09T19:58:41.977Z,1568059121.977 [DataOverHttps](INFO): SBD MOMSN=11758213
2019-09-09T19:59:06.563Z,1568059146.563 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20190909T180812/Express0031.lzma
2019-09-09T19:59:07.368Z,1568059147.368 [DataOverHttps](INFO): Moved sent file to Logs/20190909T180812/Express0031.lzma.bak
2019-09-09T19:59:07.369Z,1568059147.369 [DataOverHttps](INFO): SBD MOMSN=11758216
2019-09-09T19:59:11.666Z,1568059151.666 [Default:CheckIn:Read_Iridium] Stopped
2019-09-09T19:59:11.667Z,1568059151.667 [Default:CheckIn:C.Wait] Running Loop=1
2019-09-09T19:59:11.667Z,1568059151.667 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-09-09T19:59:15.260Z,1568059155.260 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-09-09T19:59:45.978Z,1568059185.978 [NAL9602](INFO): Not Powering down - fast GPS
2019-09-09T20:02:53.033Z,1568059373.033 [CommandLine](IMPORTANT): got command failComponent
2019-09-09T20:02:53.033Z,1568059373.033 [CommandLine](IMPORTANT): Failed components:
2019-09-09T20:02:53.033Z,1568059373.033 [CommandLine](IMPORTANT): DVL_micro: Communications Fault
2019-09-09T20:02:53.034Z,1568059373.034 [CommandLine](IMPORTANT): BPC1: Data Fault
2019-09-09T20:02:55.424Z,1568059375.424 [CommandLine](IMPORTANT): got command quit
2019-09-09T20:02:56.451Z,1568059376.451 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:02:56.451Z,1568059376.451 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:56.567Z,1568059376.567 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-09-09T20:02:56.567Z,1568059376.567 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:56.568Z,1568059376.568 [CommandLine](INFO): Join timeout helper Thread ID is 1159
2019-09-09T20:02:56.579Z,1568059376.579 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-09T20:02:56.579Z,1568059376.579 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:56.579Z,1568059376.579 [NavChartDb](INFO): Join timeout helper Thread ID is 1160
2019-09-09T20:02:56.711Z,1568059376.711 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:02:56.711Z,1568059376.711 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:56.715Z,1568059376.715 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-09T20:02:56.715Z,1568059376.715 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:56.715Z,1568059376.715 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1161
2019-09-09T20:02:57.151Z,1568059377.151 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:02:57.151Z,1568059377.151 [WetLabsBB2FL](INFO): Powering down
2019-09-09T20:02:57.152Z,1568059377.152 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:57.167Z,1568059377.167 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-09T20:02:57.167Z,1568059377.167 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:57.167Z,1568059377.167 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1162
2019-09-09T20:02:57.871Z,1568059377.871 [CTD_Seabird](INFO): Powering down
2019-09-09T20:02:57.890Z,1568059377.890 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:02:57.891Z,1568059377.891 [CTD_Seabird](INFO): Powering down
2019-09-09T20:02:57.903Z,1568059377.903 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:57.911Z,1568059377.911 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-09-09T20:02:57.911Z,1568059377.911 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:57.911Z,1568059377.911 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1163
2019-09-09T20:02:57.995Z,1568059377.995 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:02:57.995Z,1568059377.995 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:57.997Z,1568059377.997 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-09T20:02:57.997Z,1568059377.997 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:57.997Z,1568059377.997 [Radio_Surface](INFO): Join timeout helper Thread ID is 1164
2019-09-09T20:02:58.187Z,1568059378.187 [Radio_Surface](INFO): Powering down
2019-09-09T20:02:58.188Z,1568059378.188 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:02:58.188Z,1568059378.188 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:58.199Z,1568059378.199 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-09T20:02:58.200Z,1568059378.200 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:02:58.200Z,1568059378.200 [DataOverHttps](INFO): Join timeout helper Thread ID is 1165
2019-09-09T20:03:02.280Z,1568059382.280 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:03:02.283Z,1568059382.283 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.300Z,1568059382.300 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-09T20:03:02.300Z,1568059382.300 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.300Z,1568059382.300 [logger](INFO): Join timeout helper Thread ID is 1166
2019-09-09T20:03:02.339Z,1568059382.339 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:03:02.339Z,1568059382.339 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.347Z,1568059382.347 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-09T20:03:02.348Z,1568059382.348 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.348Z,1568059382.348 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-09T20:03:02.348Z,1568059382.348 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.348Z,1568059382.348 [controlThread](INFO): Join timeout helper Thread ID is 1167
2019-09-09T20:03:02.699Z,1568059382.699 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-09T20:03:02.699Z,1568059382.699 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-09T20:03:02.699Z,1568059382.699 [AHRS_M2](INFO): Powering down
2019-09-09T20:03:02.771Z,1568059382.771 [DVL_micro](INFO): uninitialize:Powering down
2019-09-09T20:03:02.772Z,1568059382.772 [NAL9602](INFO): Powering down
2019-09-09T20:03:02.774Z,1568059382.774 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-09T20:03:02.775Z,1568059382.775 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-09T20:03:02.775Z,1568059382.775 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-09T20:03:02.776Z,1568059382.776 [MissionManager](INFO): Uninitializing Mission Default
2019-09-09T20:03:02.776Z,1568059382.776 [Default] Stopped
2019-09-09T20:03:02.776Z,1568059382.776 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-09T20:03:02.776Z,1568059382.776 [Default:B.GoToSurface] Stopped
2019-09-09T20:03:02.776Z,1568059382.776 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-09T20:03:02.776Z,1568059382.776 [Default:CheckIn] Stopped
2019-09-09T20:03:02.777Z,1568059382.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-09T20:03:02.777Z,1568059382.777 [Default:CheckIn:C.Wait] Stopped
2019-09-09T20:03:02.777Z,1568059382.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-09-09T20:03:02.779Z,1568059382.779 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-09T20:03:02.780Z,1568059382.780 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-09T20:03:02.780Z,1568059382.780 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-09T20:03:02.780Z,1568059382.780 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-09T20:03:02.780Z,1568059382.780 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-09T20:03:02.780Z,1568059382.780 [BuoyancyServo](INFO): Powering down
2019-09-09T20:03:02.795Z,1568059382.795 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-09T20:03:02.795Z,1568059382.795 [ElevatorServo](INFO): Powering down
2019-09-09T20:03:02.796Z,1568059382.796 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-09T20:03:02.796Z,1568059382.796 [MassServo](INFO): Powering down
2019-09-09T20:03:02.796Z,1568059382.796 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-09T20:03:02.797Z,1568059382.797 [RudderServo](INFO): Powering down
2019-09-09T20:03:02.797Z,1568059382.797 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-09T20:03:02.797Z,1568059382.797 [ThrusterServo](INFO): Powering down
2019-09-09T20:03:02.798Z,1568059382.798 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-09T20:03:02.799Z,1568059382.799 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-09T20:03:02.799Z,1568059382.799 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-09T20:03:02.799Z,1568059382.799 [CBIT](DEBUG): Powering off loads.
2019-09-09T20:03:02.810Z,1568059382.810 [CBIT](DEBUG): Disabling WDT.
2019-09-09T20:03:02.822Z,1568059382.822 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-09T20:03:02.823Z,1568059382.823 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.892Z,1568059382.892 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.901Z,1568059382.901 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.942Z,1568059382.942 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.945Z,1568059382.945 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.952Z,1568059382.952 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:02.983Z,1568059382.983 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-09T20:03:03.048Z,1568059383.048 [logger ThreadHandler](INFO): Thread cancelled.