2019-11-01T23:00:57.164Z,1572649257.164 [CommandLine](IMPORTANT): got command restart logs
2019-11-01T23:01:02.670Z,1572649262.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=9804, MT Status=2, MTMSN=0
2019-11-01T23:01:02.671Z,1572649262.671 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:01:24.891Z,1572649284.891 [NAL9602](INFO): SBD MO Status=2, MOMSN=9804, MT Status=2, MTMSN=0
2019-11-01T23:01:24.891Z,1572649284.891 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:01:48.730Z,1572649308.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=9804, MT Status=2, MTMSN=0
2019-11-01T23:01:48.731Z,1572649308.731 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:02:10.951Z,1572649330.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=9804, MT Status=2, MTMSN=0
2019-11-01T23:02:10.951Z,1572649330.951 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:02:26.322Z,1572649346.322 [NAL9602](INFO): SBD MO Status=2, MOMSN=9804, MT Status=2, MTMSN=0
2019-11-01T23:02:26.322Z,1572649346.322 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:02:51.754Z,1572649371.754 [NAL9602](INFO): SBD MO Status=2, MOMSN=9804, MT Status=2, MTMSN=0
2019-11-01T23:02:51.755Z,1572649371.755 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:03:12.358Z,1572649392.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=9804, MT Status=0, MTMSN=0
2019-11-01T23:03:12.359Z,1572649392.359 [NAL9602](INFO): No messages in MT queue
2019-11-01T23:03:43.057Z,1572649423.057 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:05:39.847Z,1572649539.847 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-11-01T23:05:43.497Z,1572649543.497 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:05:43.498Z,1572649543.498 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:05:43.498Z,1572649543.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:05:43.498Z,1572649543.498 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:05:43.885Z,1572649543.885 [Default:CheckIn:D] Stopped
2019-11-01T23:05:43.885Z,1572649543.885 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:05:44.333Z,1572649544.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.443498 min 
2019-11-01T23:05:44.333Z,1572649544.333 [Default:CheckIn:E] Stopped
2019-11-01T23:05:44.333Z,1572649544.333 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:05:44.333Z,1572649544.333 [Default:CheckIn] Stopped
2019-11-01T23:05:44.333Z,1572649544.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:05:44.334Z,1572649544.334 [Default:CheckIn](INFO): Running loop #4
2019-11-01T23:05:44.334Z,1572649544.334 [Default:CheckIn] Running Loop=4
2019-11-01T23:05:44.334Z,1572649544.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:05:44.334Z,1572649544.334 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:05:46.323Z,1572649546.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230545.00,A,3648.65338,N,12149.20639,W,0.389,356.29,011119,,,D*7F
2019-11-01T23:05:46.329Z,1572649546.329 [NAL9602](INFO): GPS fix at 20191101T230545: (36.810890, -121.820106)
2019-11-01T23:05:46.401Z,1572649546.401 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:05:46.401Z,1572649546.401 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:05:53.380Z,1572649553.380 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20191101T230057/Courier0000.lzma
2019-11-01T23:05:55.388Z,1572649555.388 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Courier0000.lzma.bak
2019-11-01T23:05:55.389Z,1572649555.389 [DataOverHttps](INFO): SBD MOMSN=11965941
2019-11-01T23:06:09.197Z,1572649569.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=9805, MT Status=0, MTMSN=0
2019-11-01T23:06:09.197Z,1572649569.197 [NAL9602](INFO): No messages in MT queue
2019-11-01T23:06:14.494Z,1572649574.494 [CBIT](IMPORTANT): Beginning ground fault scan
2019-11-01T23:06:24.975Z,1572649584.975 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20191101T225133/Express0010.lzma
2019-11-01T23:06:25.402Z,1572649585.402 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.020241
CHAN A1 (24V): -0.025510
CHAN A2 (12V): -0.006835
CHAN A3 (5V): -0.002225
CHAN B0 (3.3V): -0.000225
CHAN B1 (3.15aV): 0.000233
CHAN B2 (3.15bV): 0.000166
CHAN B3 (GND): 0.002168
OPEN: 0.005365
Full Scale Calc: 4.765 mA, -1.589 mA
2019-11-01T23:06:26.980Z,1572649586.980 [DataOverHttps](INFO): Moved sent file to Logs/20191101T225133/Express0010.lzma.bak
2019-11-01T23:06:26.981Z,1572649586.981 [DataOverHttps](INFO): SBD MOMSN=11965945
2019-11-01T23:06:34.675Z,1572649594.675 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-01T23:06:34.675Z,1572649594.675 [RDI_Pathfinder](ERROR): Failed to parse:
:TS6181832,35.0, -0.1,   0.0,1448.9,  0
2019-11-01T23:06:39.901Z,1572649599.901 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:06:52.551Z,1572649612.551 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20191101T230057/Express0001.lzma
2019-11-01T23:06:54.556Z,1572649614.556 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0001.lzma.bak
2019-11-01T23:06:54.557Z,1572649614.557 [DataOverHttps](INFO): SBD MOMSN=11965949
2019-11-01T23:07:18.901Z,1572649638.901 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191101T230057/Express0004.lzma
2019-11-01T23:07:20.901Z,1572649640.901 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0004.lzma.bak
2019-11-01T23:07:20.901Z,1572649640.901 [DataOverHttps](INFO): SBD MOMSN=11965953
2019-11-01T23:07:36.537Z,1572649656.537 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:07:36.538Z,1572649656.538 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-01T23:07:36.538Z,1572649656.538 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-01T23:07:56.387Z,1572649676.387 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-11-01T23:07:56.387Z,1572649676.387 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-11-01T23:07:56.387Z,1572649676.387 [RDI_Pathfinder](ERROR): Communications Fault
2019-11-01T23:07:56.387Z,1572649676.387 [RDI_Pathfinder](ERROR): Failed to parse:
2019-11-01T23:07:56.430Z,1572649676.430 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-11-01T23:07:56.751Z,1572649676.751 [RDI_Pathfinder](INFO): Powering down
2019-11-01T23:07:57.513Z,1572649677.513 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-11-01T23:07:57.513Z,1572649677.513 [RDI_Pathfinder] No Fault, FailCount= 1
2019-11-01T23:08:10.824Z,1572649690.824 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-11-01T23:08:26.181Z,1572649706.181 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-01T23:08:26.181Z,1572649706.181 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19110116200979,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-11-01T23:12:37.100Z,1572649957.100 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:12:37.100Z,1572649957.100 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:12:37.101Z,1572649957.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:12:37.101Z,1572649957.101 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:12:37.500Z,1572649957.500 [Default:CheckIn:D] Stopped
2019-11-01T23:12:37.500Z,1572649957.500 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:12:37.938Z,1572649957.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.337081 min 
2019-11-01T23:12:37.938Z,1572649957.938 [Default:CheckIn:E] Stopped
2019-11-01T23:12:37.955Z,1572649957.955 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:12:37.955Z,1572649957.955 [Default:CheckIn] Stopped
2019-11-01T23:12:37.955Z,1572649957.955 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:12:37.955Z,1572649957.955 [Default:CheckIn](INFO): Running loop #5
2019-11-01T23:12:37.955Z,1572649957.955 [Default:CheckIn] Running Loop=5
2019-11-01T23:12:37.956Z,1572649957.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:12:37.956Z,1572649957.956 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:12:39.899Z,1572649959.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231239.00,A,3648.66076,N,12149.21606,W,0.389,145.41,011119,,,A*7E
2019-11-01T23:12:39.901Z,1572649959.901 [NAL9602](INFO): GPS fix at 20191101T231239: (36.811013, -121.820268)
2019-11-01T23:12:39.946Z,1572649959.946 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:12:39.946Z,1572649959.946 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:12:49.438Z,1572649969.438 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191101T230057/Courier0006.lzma
2019-11-01T23:12:51.444Z,1572649971.444 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Courier0006.lzma.bak
2019-11-01T23:12:51.445Z,1572649971.445 [DataOverHttps](INFO): SBD MOMSN=11965956
2019-11-01T23:13:16.658Z,1572649996.658 [NAL9602](INFO): SBD MO Status=2, MOMSN=9806, MT Status=2, MTMSN=0
2019-11-01T23:13:16.658Z,1572649996.658 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:13:19.639Z,1572649999.639 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20191101T230057/Express0007.lzma
2019-11-01T23:13:21.644Z,1572650001.644 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0007.lzma.bak
2019-11-01T23:13:21.645Z,1572650001.645 [DataOverHttps](INFO): SBD MOMSN=11965959
2019-11-01T23:13:39.386Z,1572650019.386 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:13:39.386Z,1572650019.386 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-01T23:13:39.386Z,1572650019.386 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-01T23:13:42.542Z,1572650022.542 [NAL9602](INFO): SBD MO Status=2, MOMSN=9806, MT Status=2, MTMSN=0
2019-11-01T23:13:42.543Z,1572650022.543 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:13:59.134Z,1572650039.134 [NAL9602](INFO): SBD MO Status=2, MOMSN=9806, MT Status=2, MTMSN=0
2019-11-01T23:13:59.135Z,1572650039.135 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:14:13.650Z,1572650053.650 [NAL9602](INFO): SBD MO Status=0, MOMSN=9806, MT Status=0, MTMSN=0
2019-11-01T23:14:13.650Z,1572650053.650 [NAL9602](INFO): No messages in MT queue
2019-11-01T23:14:44.349Z,1572650084.349 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:16:38.214Z,1572650198.214 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=63.177670
2019-11-01T23:17:28.415Z,1572650248.415 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:18:03.555Z,1572650283.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:18:26.174Z,1572650306.174 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,614.98
2019-11-01T23:18:38.695Z,1572650318.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:18:39.914Z,1572650319.914 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:18:39.914Z,1572650319.914 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:18:39.914Z,1572650319.914 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:18:39.915Z,1572650319.915 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:18:40.327Z,1572650320.327 [Default:CheckIn:D] Stopped
2019-11-01T23:18:40.327Z,1572650320.327 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.384200 min 
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn:E] Stopped
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn] Stopped
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn](INFO): Running loop #6
2019-11-01T23:18:40.726Z,1572650320.726 [Default:CheckIn] Running Loop=6
2019-11-01T23:18:40.727Z,1572650320.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:18:40.727Z,1572650320.727 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:18:42.709Z,1572650322.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231842.00,A,3648.40385,N,12148.15133,W,26.048,106.09,011119,,,D*40
2019-11-01T23:18:42.712Z,1572650322.712 [NAL9602](INFO): GPS fix at 20191101T231842: (36.806731, -121.802522)
2019-11-01T23:18:42.782Z,1572650322.782 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:18:42.782Z,1572650322.782 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:19:07.758Z,1572650347.758 [NAL9602](INFO): SBD MO Status=2, MOMSN=9807, MT Status=2, MTMSN=0
2019-11-01T23:19:07.759Z,1572650347.759 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:19:13.835Z,1572650353.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:19:24.737Z,1572650364.737 [NAL9602](INFO): SBD MO Status=0, MOMSN=9807, MT Status=0, MTMSN=0
2019-11-01T23:19:24.738Z,1572650364.738 [NAL9602](INFO): No messages in MT queue
2019-11-01T23:19:48.991Z,1572650388.991 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:19:55.433Z,1572650395.433 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:20:24.143Z,1572650424.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:20:45.145Z,1572650445.145 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-01T23:20:45.145Z,1572650445.145 [RDI_Pathfinder](ERROR): Failed to parse:
:TS6322879,35.0, -0.1,   0.0,1448.9,  0
2019-11-01T23:20:59.299Z,1572650459.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:21:34.409Z,1572650494.409 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003715
2019-11-01T23:21:45.350Z,1572650505.350 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191101T230057/Courier0009.lzma
2019-11-01T23:21:47.357Z,1572650507.357 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Courier0009.lzma.bak
2019-11-01T23:21:47.357Z,1572650507.357 [DataOverHttps](INFO): SBD MOMSN=11965969
2019-11-01T23:22:12.063Z,1572650532.063 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20191101T230057/Express0010.lzma
2019-11-01T23:22:14.069Z,1572650534.069 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0010.lzma.bak
2019-11-01T23:22:14.069Z,1572650534.069 [DataOverHttps](INFO): SBD MOMSN=11965972
2019-11-01T23:22:21.718Z,1572650541.718 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:22:21.723Z,1572650541.723 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-01T23:22:21.723Z,1572650541.723 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-01T23:23:44.151Z,1572650624.151 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-11-01T23:23:44.151Z,1572650624.151 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2019-11-01T23:25:06.171Z,1572650706.171 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-01T23:25:06.171Z,1572650706.171 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-01T23:27:22.374Z,1572650842.374 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:27:22.374Z,1572650842.374 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:27:22.374Z,1572650842.374 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:27:22.375Z,1572650842.375 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:27:22.784Z,1572650842.784 [Default:CheckIn:D] Stopped
2019-11-01T23:27:22.784Z,1572650842.784 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:27:23.166Z,1572650843.166 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.091809 min 
2019-11-01T23:27:23.166Z,1572650843.166 [Default:CheckIn:E] Stopped
2019-11-01T23:27:23.166Z,1572650843.166 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:27:23.166Z,1572650843.166 [Default:CheckIn] Stopped
2019-11-01T23:27:23.167Z,1572650843.167 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:27:23.171Z,1572650843.171 [Default:CheckIn](INFO): Running loop #7
2019-11-01T23:27:23.171Z,1572650843.171 [Default:CheckIn] Running Loop=7
2019-11-01T23:27:23.171Z,1572650843.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:27:23.171Z,1572650843.171 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:27:25.135Z,1572650845.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232724.00,A,3648.15495,N,12147.21458,W,1.030,226.32,011119,,,A*7C
2019-11-01T23:27:25.137Z,1572650845.137 [NAL9602](INFO): GPS fix at 20191101T232724: (36.802582, -121.786910)
2019-11-01T23:27:25.217Z,1572650845.217 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:27:25.218Z,1572650845.218 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:27:41.202Z,1572650861.202 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191101T230057/Courier0012.lzma
2019-11-01T23:27:43.216Z,1572650863.216 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Courier0012.lzma.bak
2019-11-01T23:27:43.217Z,1572650863.217 [DataOverHttps](INFO): SBD MOMSN=11965975
2019-11-01T23:28:07.639Z,1572650887.639 [NAL9602](INFO): SBD MO Status=2, MOMSN=9808, MT Status=2, MTMSN=0
2019-11-01T23:28:07.639Z,1572650887.639 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:28:07.754Z,1572650887.754 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20191101T230057/Express0013.lzma
2019-11-01T23:28:09.760Z,1572650889.760 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0013.lzma.bak
2019-11-01T23:28:09.761Z,1572650889.761 [DataOverHttps](INFO): SBD MOMSN=11965978
2019-11-01T23:28:20.210Z,1572650900.210 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:28:20.210Z,1572650900.210 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-01T23:28:20.210Z,1572650900.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-01T23:28:34.304Z,1572650914.304 [NAL9602](INFO): SBD MO Status=2, MOMSN=9808, MT Status=2, MTMSN=0
2019-11-01T23:28:34.305Z,1572650914.305 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:28:43.594Z,1572650923.594 [NAL9602](INFO): SBD MO Status=0, MOMSN=9808, MT Status=0, MTMSN=0
2019-11-01T23:28:43.594Z,1572650923.594 [NAL9602](INFO): No messages in MT queue
2019-11-01T23:29:14.293Z,1572650954.293 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:29:34.135Z,1572650974.135 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-11-01T23:29:34.136Z,1572650974.136 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19110116411779,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2019-11-01T23:33:20.839Z,1572651200.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:33:20.840Z,1572651200.840 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:33:20.840Z,1572651200.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:33:20.840Z,1572651200.840 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:33:21.207Z,1572651201.207 [Default:CheckIn:D] Stopped
2019-11-01T23:33:21.207Z,1572651201.207 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:33:21.634Z,1572651201.634 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.065535 min 
2019-11-01T23:33:21.635Z,1572651201.635 [Default:CheckIn:E] Stopped
2019-11-01T23:33:21.663Z,1572651201.663 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:33:21.663Z,1572651201.663 [Default:CheckIn] Stopped
2019-11-01T23:33:21.663Z,1572651201.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:33:21.664Z,1572651201.664 [Default:CheckIn](INFO): Running loop #8
2019-11-01T23:33:21.664Z,1572651201.664 [Default:CheckIn] Running Loop=8
2019-11-01T23:33:21.664Z,1572651201.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:33:21.664Z,1572651201.664 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:33:23.591Z,1572651203.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233323.00,A,3648.15177,N,12147.22229,W,0.253,319.14,011119,,,A*7B
2019-11-01T23:33:23.593Z,1572651203.593 [NAL9602](INFO): GPS fix at 20191101T233323: (36.802529, -121.787038)
2019-11-01T23:33:23.679Z,1572651203.679 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:33:23.679Z,1572651203.679 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:33:39.190Z,1572651219.190 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191101T230057/Courier0015.lzma
2019-11-01T23:33:41.208Z,1572651221.208 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Courier0015.lzma.bak
2019-11-01T23:33:41.209Z,1572651221.209 [DataOverHttps](INFO): SBD MOMSN=11965983
2019-11-01T23:33:49.935Z,1572651229.935 [NAL9602](INFO): SBD MO Status=0, MOMSN=9809, MT Status=0, MTMSN=0
2019-11-01T23:33:49.935Z,1572651229.935 [NAL9602](INFO): No messages in MT queue
2019-11-01T23:34:06.218Z,1572651246.218 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191101T230057/Express0016.lzma
2019-11-01T23:34:08.224Z,1572651248.224 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0016.lzma.bak
2019-11-01T23:34:08.225Z,1572651248.225 [DataOverHttps](INFO): SBD MOMSN=11965986
2019-11-01T23:34:16.226Z,1572651256.226 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:34:16.226Z,1572651256.226 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-01T23:34:16.227Z,1572651256.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-01T23:34:20.633Z,1572651260.633 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:36:31.171Z,1572651391.171 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2019-11-01T23:38:52.165Z,1572651532.165 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-11-01T23:38:52.165Z,1572651532.165 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-11-01T23:39:16.844Z,1572651556.844 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:39:16.844Z,1572651556.844 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:39:16.844Z,1572651556.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:39:16.844Z,1572651556.844 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:39:17.223Z,1572651557.223 [Default:CheckIn:D] Stopped
2019-11-01T23:39:17.223Z,1572651557.223 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:39:17.613Z,1572651557.613 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.999137 min 
2019-11-01T23:39:17.613Z,1572651557.613 [Default:CheckIn:E] Stopped
2019-11-01T23:39:17.613Z,1572651557.613 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:39:17.613Z,1572651557.613 [Default:CheckIn] Stopped
2019-11-01T23:39:17.614Z,1572651557.614 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:39:17.614Z,1572651557.614 [Default:CheckIn](INFO): Running loop #9
2019-11-01T23:39:17.614Z,1572651557.614 [Default:CheckIn] Running Loop=9
2019-11-01T23:39:17.614Z,1572651557.614 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:39:17.614Z,1572651557.614 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:39:19.607Z,1572651559.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233919.00,A,3648.17717,N,12147.26243,W,0.233,194.47,011119,,,A*75
2019-11-01T23:39:19.609Z,1572651559.609 [NAL9602](INFO): GPS fix at 20191101T233919: (36.802953, -121.787707)
2019-11-01T23:39:19.659Z,1572651559.659 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:39:19.659Z,1572651559.659 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:39:34.584Z,1572651574.584 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191101T230057/Courier0018.lzma
2019-11-01T23:39:36.624Z,1572651576.624 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Courier0018.lzma.bak
2019-11-01T23:39:36.624Z,1572651576.624 [DataOverHttps](INFO): SBD MOMSN=11965991
2019-11-01T23:39:36.715Z,1572651576.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=9810, MT Status=2, MTMSN=0
2019-11-01T23:39:36.720Z,1572651576.720 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-11-01T23:40:03.854Z,1572651603.854 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20191101T230057/Express0019.lzma
2019-11-01T23:40:05.861Z,1572651605.861 [DataOverHttps](INFO): Moved sent file to Logs/20191101T230057/Express0019.lzma.bak
2019-11-01T23:40:05.861Z,1572651605.861 [DataOverHttps](INFO): SBD MOMSN=11965994
2019-11-01T23:40:13.939Z,1572651613.939 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:40:13.940Z,1572651613.940 [Default:CheckIn:C.Wait] Running Loop=1
2019-11-01T23:40:13.940Z,1572651613.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-11-01T23:40:48.225Z,1572651648.225 [NAL9602](FAULT): Timeout waiting for SBDI response.
2019-11-01T23:40:48.225Z,1572651648.225 [NAL9602] Data Fault, FailCount= 1
2019-11-01T23:40:48.225Z,1572651648.225 [NAL9602](ERROR): Data Fault
2019-11-01T23:40:48.293Z,1572651648.293 [CBIT](ERROR): Data Fault in component: NAL9602
2019-11-01T23:40:48.635Z,1572651648.635 [NAL9602](INFO): Powering down
2019-11-01T23:40:49.505Z,1572651649.505 [CBIT](INFO): Clearing failed state for component NAL9602
2019-11-01T23:40:49.505Z,1572651649.505 [NAL9602] No Fault, FailCount= 1
2019-11-01T23:41:18.925Z,1572651678.925 [NAL9602](INFO): Powering up NAL9602
2019-11-01T23:41:29.841Z,1572651689.841 [NAL9602](INFO): NAL9602 initialized
2019-11-01T23:42:00.949Z,1572651720.949 [NAL9602](INFO): Not Powering down - fast GPS
2019-11-01T23:42:21.998Z,1572651741.998 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-11-01T23:45:14.498Z,1572651914.498 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-11-01T23:45:14.498Z,1572651914.498 [Default:CheckIn:C.Wait] Stopped
2019-11-01T23:45:14.498Z,1572651914.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-11-01T23:45:14.498Z,1572651914.498 [Default:CheckIn:D] Running Loop=1
2019-11-01T23:45:14.917Z,1572651914.917 [Default:CheckIn:D] Stopped
2019-11-01T23:45:14.917Z,1572651914.917 [Default:CheckIn:E] Running Loop=1
2019-11-01T23:45:15.311Z,1572651915.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.960689 min 
2019-11-01T23:45:15.311Z,1572651915.311 [Default:CheckIn:E] Stopped
2019-11-01T23:45:15.311Z,1572651915.311 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-11-01T23:45:15.312Z,1572651915.312 [Default:CheckIn] Stopped
2019-11-01T23:45:15.312Z,1572651915.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:45:15.312Z,1572651915.312 [Default:CheckIn](INFO): Running loop #10
2019-11-01T23:45:15.312Z,1572651915.312 [Default:CheckIn] Running Loop=10
2019-11-01T23:45:15.312Z,1572651915.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-11-01T23:45:15.312Z,1572651915.312 [Default:CheckIn:Read_GPS] Running Loop=1
2019-11-01T23:45:18.909Z,1572651918.909 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-11-01T23:46:54.428Z,1572652014.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:47:03.464Z,1572652023.464 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239635
2019-11-01T23:48:04.707Z,1572652084.707 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:48:39.847Z,1572652119.847 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:49:14.999Z,1572652154.999 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:49:50.147Z,1572652190.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-11-01T23:49:51.240Z,1572652191.240 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-11-01T23:49:51.242Z,1572652191.242 [BPC1](INFO): Received data from all battery sticks.
2019-11-01T23:50:09.424Z,1572652209.424 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003658
2019-11-01T23:50:15.502Z,1572652215.502 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-11-01T23:45:15.3Z
2019-11-01T23:50:15.502Z,1572652215.502 [Default:CheckIn:Read_GPS] Stopped
2019-11-01T23:50:15.514Z,1572652215.514 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-11-01T23:50:22.268Z,1572652222.268 [CommandLine](IMPORTANT): got command quit
2019-11-01T23:50:23.271Z,1572652223.271 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-11-01T23:50:23.271Z,1572652223.271 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:23.271Z,1572652223.271 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.351Z,1572652223.351 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-11-01T23:50:23.351Z,1572652223.351 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.352Z,1572652223.352 [CommandLine](INFO): Join timeout helper Thread ID is 4353
2019-11-01T23:50:23.353Z,1572652223.353 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-11-01T23:50:23.353Z,1572652223.353 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.353Z,1572652223.353 [NavChartDb](INFO): Join timeout helper Thread ID is 4354
2019-11-01T23:50:23.707Z,1572652223.707 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:23.707Z,1572652223.707 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.715Z,1572652223.715 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-11-01T23:50:23.716Z,1572652223.716 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.717Z,1572652223.717 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4355
2019-11-01T23:50:23.720Z,1572652223.720 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:23.720Z,1572652223.720 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-11-01T23:50:23.723Z,1572652223.723 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.724Z,1572652223.724 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-11-01T23:50:23.725Z,1572652223.725 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.726Z,1572652223.726 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4356
2019-11-01T23:50:23.748Z,1572652223.748 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:23.748Z,1572652223.748 [CTD_NeilBrown](INFO): Powering down
2019-11-01T23:50:23.763Z,1572652223.763 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.771Z,1572652223.771 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-11-01T23:50:23.771Z,1572652223.771 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:23.772Z,1572652223.772 [Radio_Surface](INFO): Join timeout helper Thread ID is 4357
2019-11-01T23:50:24.087Z,1572652224.087 [Radio_Surface](INFO): Powering down
2019-11-01T23:50:24.088Z,1572652224.088 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:24.088Z,1572652224.088 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.092Z,1572652224.092 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-11-01T23:50:24.092Z,1572652224.092 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.093Z,1572652224.093 [DataOverHttps](INFO): Join timeout helper Thread ID is 4358
2019-11-01T23:50:24.823Z,1572652224.823 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:24.824Z,1572652224.824 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.840Z,1572652224.840 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-11-01T23:50:24.840Z,1572652224.840 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.840Z,1572652224.840 [logger](INFO): Join timeout helper Thread ID is 4359
2019-11-01T23:50:24.863Z,1572652224.863 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:24.863Z,1572652224.863 [logger ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.868Z,1572652224.868 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-11-01T23:50:24.868Z,1572652224.868 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.868Z,1572652224.868 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-11-01T23:50:24.868Z,1572652224.868 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:24.869Z,1572652224.869 [controlThread](INFO): Join timeout helper Thread ID is 4360
2019-11-01T23:50:24.914Z,1572652224.914 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-11-01T23:50:24.914Z,1572652224.914 [controlThread](DEBUG): Uninitializing ControlThread
2019-11-01T23:50:24.915Z,1572652224.915 [AHRS_M2](INFO): Powering down
2019-11-01T23:50:24.988Z,1572652224.988 [DUSBL_Hydroid](INFO): Powering down
2019-11-01T23:50:25.059Z,1572652225.059 [Micromodem](INFO): Powering down
2019-11-01T23:50:25.155Z,1572652225.155 [NAL9602](INFO): Powering down
2019-11-01T23:50:25.227Z,1572652225.227 [RDI_Pathfinder](INFO): Powering down
2019-11-01T23:50:25.229Z,1572652225.229 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-11-01T23:50:25.229Z,1572652225.229 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-11-01T23:50:25.230Z,1572652225.230 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-11-01T23:50:25.230Z,1572652225.230 [MissionManager](INFO): Uninitializing Mission Default
2019-11-01T23:50:25.230Z,1572652225.230 [Default] Stopped
2019-11-01T23:50:25.231Z,1572652225.231 [Default](DEBUG): Aggregate::uninitialize Default
2019-11-01T23:50:25.231Z,1572652225.231 [Default:B.GoToSurface] Stopped
2019-11-01T23:50:25.231Z,1572652225.231 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-11-01T23:50:25.231Z,1572652225.231 [Default:CheckIn] Stopped
2019-11-01T23:50:25.231Z,1572652225.231 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-11-01T23:50:25.231Z,1572652225.231 [Default:CheckIn:Read_Iridium] Stopped
2019-11-01T23:50:25.233Z,1572652225.233 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-11-01T23:50:25.234Z,1572652225.234 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-11-01T23:50:25.234Z,1572652225.234 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-11-01T23:50:25.234Z,1572652225.234 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-11-01T23:50:25.235Z,1572652225.235 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-11-01T23:50:25.235Z,1572652225.235 [BuoyancyServo](INFO): Powering down
2019-11-01T23:50:25.247Z,1572652225.247 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-11-01T23:50:25.247Z,1572652225.247 [ElevatorServo](INFO): Powering down
2019-11-01T23:50:25.248Z,1572652225.248 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-11-01T23:50:25.248Z,1572652225.248 [MassServo](INFO): Powering down
2019-11-01T23:50:25.249Z,1572652225.249 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-11-01T23:50:25.249Z,1572652225.249 [RudderServo](INFO): Powering down
2019-11-01T23:50:25.250Z,1572652225.250 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-11-01T23:50:25.250Z,1572652225.250 [ThrusterServo](INFO): Powering down
2019-11-01T23:50:25.250Z,1572652225.250 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-11-01T23:50:25.251Z,1572652225.251 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-11-01T23:50:25.251Z,1572652225.251 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-11-01T23:50:25.251Z,1572652225.251 [CBIT](DEBUG): Powering off loads.
2019-11-01T23:50:25.263Z,1572652225.263 [CBIT](DEBUG): Disabling WDT.
2019-11-01T23:50:25.275Z,1572652225.275 [CBIT](DEBUG): Opening all GF detection circuits.
2019-11-01T23:50:25.276Z,1572652225.276 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:25.337Z,1572652225.337 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:25.347Z,1572652225.347 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:25.393Z,1572652225.393 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:25.396Z,1572652225.396 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:25.459Z,1572652225.459 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-11-01T23:50:25.532Z,1572652225.532 [logger ThreadHandler](INFO): Thread cancelled.