2020-01-09T21:56:14.051Z,1578606974.051 [Supervisor](DEBUG): Initializing supervisor.
2020-01-09T21:56:14.055Z,1578606974.055 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-09T21:56:14.055Z,1578606974.055 [SyncHandler](INFO): Protected caller Thread ID is 3698
2020-01-09T21:56:14.056Z,1578606974.056 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-09T21:56:14.057Z,1578606974.057 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-09T21:56:14.057Z,1578606974.057 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3699
2020-01-09T21:56:14.060Z,1578606974.060 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-09T21:56:14.072Z,1578606974.072 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-09T21:56:14.073Z,1578606974.073 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-09T21:56:14.073Z,1578606974.073 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3700
2020-01-09T21:56:14.074Z,1578606974.074 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-09T21:56:14.075Z,1578606974.075 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-09T21:56:14.075Z,1578606974.075 [logger ThreadHandler](INFO): Protected caller Thread ID is 3701
2020-01-09T21:56:14.077Z,1578606974.077 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-09T21:56:14.078Z,1578606974.078 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-09T21:56:14.079Z,1578606974.079 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-09T21:56:14.495Z,1578606974.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-09T21:56:14.495Z,1578606974.495 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-09T21:56:14.589Z,1578606974.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-09T21:56:14.590Z,1578606974.590 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-09T21:56:14.899Z,1578606974.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-09T21:56:14.900Z,1578606974.900 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-09T21:56:15.034Z,1578606975.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-09T21:56:15.034Z,1578606975.034 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-09T21:56:15.215Z,1578606975.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-09T21:56:15.216Z,1578606975.216 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-09T21:56:15.649Z,1578606975.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-09T21:56:15.649Z,1578606975.649 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-09T21:56:15.850Z,1578606975.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-09T21:56:15.850Z,1578606975.850 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-09T21:56:15.990Z,1578606975.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-09T21:56:15.990Z,1578606975.990 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-09T21:56:16.169Z,1578606976.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-09T21:56:16.170Z,1578606976.170 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-09T21:56:16.263Z,1578606976.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-09T21:56:16.264Z,1578606976.264 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-09T21:56:16.553Z,1578606976.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-09T21:56:16.553Z,1578606976.553 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-09T21:56:16.662Z,1578606976.662 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-09T21:56:16.853Z,1578606976.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-09T21:56:16.854Z,1578606976.854 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-09T21:56:17.813Z,1578606977.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-09T21:56:17.813Z,1578606977.813 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-09T21:56:18.213Z,1578606978.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-09T21:56:18.215Z,1578606978.215 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-09T21:56:18.216Z,1578606978.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-09T21:56:18.413Z,1578606978.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-09T21:56:18.508Z,1578606978.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-09T21:56:18.603Z,1578606978.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-09T21:56:18.827Z,1578606978.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-09T21:56:18.827Z,1578606978.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-09T21:56:18.910Z,1578606978.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-09T21:56:18.000Z,1578606979.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-09T21:56:19.093Z,1578606979.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-09T21:56:19.173Z,1578606979.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-09T21:56:19.277Z,1578606979.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-09T21:56:19.451Z,1578606979.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-09T21:56:19.576Z,1578606979.576 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-09T21:56:19.576Z,1578606979.576 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-09T21:56:19.599Z,1578606979.599 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-09T21:56:19.916Z,1578606979.916 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-09T21:56:19.921Z,1578606979.921 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-09T21:56:19.923Z,1578606979.923 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-09T21:56:19.928Z,1578606979.928 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-09T21:56:19.929Z,1578606979.929 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-09T21:56:19.934Z,1578606979.934 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-09T21:56:19.934Z,1578606979.934 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-09T21:56:19.940Z,1578606979.940 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-09T21:56:20.010Z,1578606980.010 [AHRS_M2] Loaded
2020-01-09T21:56:20.010Z,1578606980.010 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-09T21:56:20.087Z,1578606980.087 [DataOverHttps] Loaded
2020-01-09T21:56:20.087Z,1578606980.087 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-09T21:56:20.088Z,1578606980.088 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-09T21:56:20.088Z,1578606980.088 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3780
2020-01-09T21:56:20.112Z,1578606980.112 [DDM] Loaded
2020-01-09T21:56:20.113Z,1578606980.113 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-09T21:56:20.126Z,1578606980.126 [Depth_Keller] Loaded
2020-01-09T21:56:20.126Z,1578606980.126 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-09T21:56:20.131Z,1578606980.131 [DropWeight] Loaded
2020-01-09T21:56:20.131Z,1578606980.131 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-09T21:56:20.191Z,1578606980.191 [DUSBL_Hydroid] Loaded
2020-01-09T21:56:20.192Z,1578606980.192 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-09T21:56:20.238Z,1578606980.238 [Micromodem] Loaded
2020-01-09T21:56:20.238Z,1578606980.238 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-09T21:56:20.335Z,1578606980.335 [NAL9602] Loaded
2020-01-09T21:56:20.335Z,1578606980.335 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-09T21:56:20.372Z,1578606980.372 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-09T21:56:20.372Z,1578606980.372 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-09T21:56:20.373Z,1578606980.373 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-09T21:56:20.374Z,1578606980.374 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-09T21:56:20.374Z,1578606980.374 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-09T21:56:20.375Z,1578606980.375 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-09T21:56:20.375Z,1578606980.375 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-09T21:56:20.376Z,1578606980.376 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-09T21:56:20.376Z,1578606980.376 [Onboard] Loaded
2020-01-09T21:56:20.376Z,1578606980.376 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-09T21:56:20.382Z,1578606980.382 [PowerOnly] Loaded
2020-01-09T21:56:20.382Z,1578606980.382 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-09T21:56:20.389Z,1578606980.389 [Radio_Surface] Loaded
2020-01-09T21:56:20.389Z,1578606980.389 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-09T21:56:20.390Z,1578606980.390 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-09T21:56:20.390Z,1578606980.390 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3781
2020-01-09T21:56:20.433Z,1578606980.433 [RDI_Pathfinder] Loaded
2020-01-09T21:56:20.434Z,1578606980.434 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-09T21:56:22.013Z,1578606982.013 [BPC1] Loaded
2020-01-09T21:56:22.013Z,1578606982.013 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-09T21:56:22.013Z,1578606982.013 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-09T21:56:22.014Z,1578606982.014 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-09T21:56:22.035Z,1578606982.035 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-09T21:56:22.035Z,1578606982.035 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-09T21:56:22.219Z,1578606982.219 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-09T21:56:22.219Z,1578606982.219 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-09T21:56:22.239Z,1578606982.239 [NavChart] Loaded
2020-01-09T21:56:22.239Z,1578606982.239 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-09T21:56:22.243Z,1578606982.243 [UniversalFixResidualReporter] Loaded
2020-01-09T21:56:22.243Z,1578606982.243 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-09T21:56:22.244Z,1578606982.244 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-09T21:56:22.244Z,1578606982.244 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-09T21:56:22.534Z,1578606982.534 [BuoyancyServo] Loaded
2020-01-09T21:56:22.535Z,1578606982.535 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-09T21:56:22.550Z,1578606982.550 [ElevatorServo] Loaded
2020-01-09T21:56:22.550Z,1578606982.550 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-09T21:56:22.564Z,1578606982.564 [MassServo] Loaded
2020-01-09T21:56:22.565Z,1578606982.565 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-09T21:56:22.579Z,1578606982.579 [RudderServo] Loaded
2020-01-09T21:56:22.579Z,1578606982.579 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-09T21:56:22.594Z,1578606982.594 [ThrusterServo] Loaded
2020-01-09T21:56:22.594Z,1578606982.594 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-09T21:56:22.595Z,1578606982.595 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-09T21:56:22.595Z,1578606982.595 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-09T21:56:22.848Z,1578606982.848 [CTD_NeilBrown] Loaded
2020-01-09T21:56:22.848Z,1578606982.848 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-09T21:56:22.849Z,1578606982.849 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-09T21:56:22.850Z,1578606982.850 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3782
2020-01-09T21:56:22.864Z,1578606982.864 [PAR_Licor] Loaded
2020-01-09T21:56:22.864Z,1578606982.864 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-09T21:56:22.907Z,1578606982.907 [WetLabsSeaOWL_UV_A] Loaded
2020-01-09T21:56:22.907Z,1578606982.907 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-09T21:56:22.908Z,1578606982.908 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-09T21:56:22.909Z,1578606982.909 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 3783
2020-01-09T21:56:22.909Z,1578606982.909 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-09T21:56:22.910Z,1578606982.910 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-09T21:56:23.214Z,1578606983.214 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-09T21:56:23.214Z,1578606983.214 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-09T21:56:23.254Z,1578606983.254 [DepthRateCalculator] Loaded
2020-01-09T21:56:23.254Z,1578606983.254 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-09T21:56:23.260Z,1578606983.260 [PitchRateCalculator] Loaded
2020-01-09T21:56:23.260Z,1578606983.260 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-09T21:56:23.272Z,1578606983.272 [SpeedCalculator] Loaded
2020-01-09T21:56:23.272Z,1578606983.272 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-09T21:56:23.293Z,1578606983.293 [TempGradientCalculator] Loaded
2020-01-09T21:56:23.293Z,1578606983.293 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-09T21:56:23.299Z,1578606983.299 [YawRateCalculator] Loaded
2020-01-09T21:56:23.299Z,1578606983.299 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-09T21:56:23.339Z,1578606983.339 [ElevatorOffsetCalculator] Loaded
2020-01-09T21:56:23.339Z,1578606983.339 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-09T21:56:23.339Z,1578606983.339 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-09T21:56:23.340Z,1578606983.340 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-09T21:56:23.473Z,1578606983.473 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-09T21:56:23.495Z,1578606983.495 [SBIT] Loaded
2020-01-09T21:56:23.495Z,1578606983.495 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-09T21:56:23.496Z,1578606983.496 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-09T21:56:23.508Z,1578606983.508 [IBIT] Loaded
2020-01-09T21:56:23.508Z,1578606983.508 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-09T21:56:23.511Z,1578606983.511 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-09T21:56:23.649Z,1578606983.649 [CBIT] Loaded
2020-01-09T21:56:23.650Z,1578606983.650 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-09T21:56:23.650Z,1578606983.650 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-09T21:56:23.651Z,1578606983.651 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-09T21:56:23.731Z,1578606983.731 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-09T21:56:23.732Z,1578606983.732 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-09T21:56:23.826Z,1578606983.826 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-09T21:56:23.826Z,1578606983.826 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-09T21:56:23.874Z,1578606983.874 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-09T21:56:23.956Z,1578606983.956 [VerticalControl] Loaded
2020-01-09T21:56:23.956Z,1578606983.956 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-09T21:56:23.957Z,1578606983.957 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-09T21:56:24.026Z,1578606984.026 [HorizontalControl] Loaded
2020-01-09T21:56:24.026Z,1578606984.026 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-09T21:56:24.027Z,1578606984.027 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-09T21:56:24.029Z,1578606984.029 [SpeedControl] Loaded
2020-01-09T21:56:24.029Z,1578606984.029 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-09T21:56:24.030Z,1578606984.030 [LoopControl](DEBUG): Construct LoopControl.
2020-01-09T21:56:24.030Z,1578606984.030 [LoopControl] Loaded
2020-01-09T21:56:24.030Z,1578606984.030 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-09T21:56:24.031Z,1578606984.031 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-09T21:56:24.031Z,1578606984.031 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-09T21:56:24.063Z,1578606984.063 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-09T21:56:24.066Z,1578606984.066 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-09T21:56:24.067Z,1578606984.067 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-09T21:56:24.074Z,1578606984.074 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-09T21:56:24.075Z,1578606984.075 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-09T21:56:24.075Z,1578606984.075 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3784
2020-01-09T21:56:24.080Z,1578606984.080 [Supervisor](INFO): Main Thread ID is 3569
2020-01-09T21:56:24.080Z,1578606984.080 [Supervisor](DEBUG): Running supervisor.
2020-01-09T21:56:24.080Z,1578606984.080 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3785
2020-01-09T21:56:24.083Z,1578606984.083 [controlThread ThreadHandler](INFO): Handler Thread ID is 3786
2020-01-09T21:56:24.083Z,1578606984.083 [controlThread](DEBUG): Initializing ControlThread
2020-01-09T21:56:24.092Z,1578606984.092 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-09T21:56:24.092Z,1578606984.092 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-09T21:56:24.097Z,1578606984.097 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-09T21:56:24.097Z,1578606984.097 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-09T21:56:24.098Z,1578606984.098 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-09T21:56:24.098Z,1578606984.098 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-09T21:56:24.098Z,1578606984.098 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-09T21:56:24.099Z,1578606984.099 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-09T21:56:24.099Z,1578606984.099 [SBIT](INFO): Initialize SBIT Component.
2020-01-09T21:56:24.100Z,1578606984.100 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-09T21:56:24.100Z,1578606984.100 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-09T21:56:24.100Z,1578606984.100 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-09T21:56:24.102Z,1578606984.102 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2020-01-09T21:56:24.103Z,1578606984.103 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-09T21:56:24.103Z,1578606984.103 [IBIT](INFO): Initialize IBIT Component.
2020-01-09T21:56:24.104Z,1578606984.104 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-09T21:56:24.105Z,1578606984.105 [logger ThreadHandler](INFO): Handler Thread ID is 3787
2020-01-09T21:56:24.117Z,1578606984.117 [CBIT](DEBUG): Initialized mux pins.
2020-01-09T21:56:24.117Z,1578606984.117 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-09T21:56:24.125Z,1578606984.125 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3788
2020-01-09T21:56:24.126Z,1578606984.126 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-09T21:56:24.137Z,1578606984.137 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3789
2020-01-09T21:56:24.141Z,1578606984.141 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-09T21:56:24.141Z,1578606984.141 [CBIT](DEBUG): Initializing heartbeat.
2020-01-09T21:56:24.149Z,1578606984.149 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3790
2020-01-09T21:56:24.150Z,1578606984.150 [CTD_NeilBrown](INFO): Powering down
2020-01-09T21:56:24.174Z,1578606984.174 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 3791
2020-01-09T21:56:24.175Z,1578606984.175 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T21:56:24.205Z,1578606984.205 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3792
2020-01-09T21:56:24.208Z,1578606984.208 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-09T21:56:24.209Z,1578606984.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-09T21:56:24.209Z,1578606984.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-09T21:56:24.209Z,1578606984.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-09T21:56:24.209Z,1578606984.209 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-09T21:56:24.209Z,1578606984.209 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-09T21:56:24.210Z,1578606984.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-09T21:56:24.210Z,1578606984.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-09T21:56:24.210Z,1578606984.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-09T21:56:24.210Z,1578606984.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-09T21:56:24.211Z,1578606984.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-09T21:56:24.211Z,1578606984.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-09T21:56:24.211Z,1578606984.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-09T21:56:24.211Z,1578606984.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-09T21:56:24.211Z,1578606984.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-09T21:56:24.211Z,1578606984.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-09T21:56:24.213Z,1578606984.213 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-09T21:56:24.213Z,1578606984.213 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-09T21:56:24.249Z,1578606984.249 [CBIT](DEBUG): Backplane powered.
2020-01-09T21:56:24.249Z,1578606984.249 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-09T21:56:24.251Z,1578606984.251 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-09T21:56:24.251Z,1578606984.251 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-09T21:56:24.252Z,1578606984.252 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-09T21:56:24.253Z,1578606984.253 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-09T21:56:24.263Z,1578606984.263 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T21:56:24.289Z,1578606984.289 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-01-09T21:56:24.290Z,1578606984.290 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-09T21:56:24.364Z,1578606984.364 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-09T21:56:24.365Z,1578606984.365 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-09T21:56:24.366Z,1578606984.366 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T21:56:24.399Z,1578606984.399 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-09T21:56:24.425Z,1578606984.425 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-09T21:56:24.431Z,1578606984.431 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-09T21:56:24.451Z,1578606984.451 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-01-09T21:56:24.461Z,1578606984.461 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-09T21:56:24.474Z,1578606984.474 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-09T21:56:24.506Z,1578606984.506 [DDM](INFO): Powering up
2020-01-09T21:56:24.506Z,1578606984.506 [DDM](DEBUG): Initializing DDM.
2020-01-09T21:56:24.531Z,1578606984.531 [DUSBL_Hydroid](INFO): Powering up
2020-01-09T21:56:24.531Z,1578606984.531 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-09T21:56:24.541Z,1578606984.541 [Radio_Surface](INFO): Powering up
2020-01-09T21:56:24.770Z,1578606984.770 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-09T21:56:24.777Z,1578606984.777 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-09T21:56:24.778Z,1578606984.778 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-09T21:56:24.785Z,1578606984.785 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-09T21:56:24.786Z,1578606984.786 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-09T21:56:24.797Z,1578606984.797 [MassServo](DEBUG): Initializing MassServo.
2020-01-09T21:56:24.798Z,1578606984.798 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T21:56:24.805Z,1578606984.805 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T21:56:24.806Z,1578606984.806 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-09T21:56:24.813Z,1578606984.813 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-09T21:56:24.994Z,1578606984.994 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-09T21:56:24.994Z,1578606984.994 [DropWeight] Hardware Fault, FailCount= 1
2020-01-09T21:56:24.994Z,1578606984.994 [DropWeight](ERROR): Hardware Fault
2020-01-09T21:56:24.996Z,1578606984.996 [Micromodem](INFO): Powering up
2020-01-09T21:56:24.996Z,1578606984.996 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-09T21:56:25.066Z,1578606985.066 [CommandLine](FAULT): Scheduling is paused
2020-01-09T21:56:25.067Z,1578606985.067 [CBIT](INFO): Critical error at 20200109T215624
2020-01-09T21:56:25.067Z,1578606985.067 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-09T21:56:25.069Z,1578606985.069 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-09T21:56:25.070Z,1578606985.070 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-09T21:56:25.669Z,1578606985.669 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-09T21:56:25.669Z,1578606985.669 [RudderServo](FAULT): Rudder failed to initialize
2020-01-09T21:56:25.669Z,1578606985.669 [RudderServo] Communications Fault, FailCount= 1
2020-01-09T21:56:25.669Z,1578606985.669 [RudderServo](ERROR): Communications Fault
2020-01-09T21:56:25.778Z,1578606985.778 [CBIT](INFO): Critical error at 20200109T215625
2020-01-09T21:56:25.781Z,1578606985.781 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-09T21:56:25.982Z,1578606985.982 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T21:56:25.982Z,1578606985.982 [RudderServo](INFO): Powering down
2020-01-09T21:56:26.655Z,1578606986.655 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T21:56:26.774Z,1578606986.774 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T21:56:26.778Z,1578606986.778 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-09T21:56:26.778Z,1578606986.778 [RudderServo] No Fault, FailCount= 1
2020-01-09T21:56:29.869Z,1578606989.869 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-09T21:56:30.272Z,1578606990.272 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T21:56:29.868392Z,RTC,RTC*5D

2020-01-09T21:56:30.272Z,1578606990.272 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-09T21:56:29.868392Z,RTC,RTC*5D

2020-01-09T21:56:30.675Z,1578606990.675 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-09T21:56:30.675Z,1578606990.675 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-09T21:56:31.075Z,1578606991.075 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-09T21:56:31.075Z,1578606991.075 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-09T21:56:31.487Z,1578606991.487 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-09T21:56:31.487Z,1578606991.487 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-09T21:56:31.887Z,1578606991.887 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-09T21:56:31.887Z,1578606991.887 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-09T21:56:32.291Z,1578606992.291 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-09T21:56:32.291Z,1578606992.291 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-09T21:56:32.691Z,1578606992.691 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-09T21:56:32.691Z,1578606992.691 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-09T21:56:33.111Z,1578606993.111 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-09T21:56:33.111Z,1578606993.111 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-09T21:56:33.475Z,1578606993.475 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-09T21:56:33.476Z,1578606993.476 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,09,21,56,34*4B

2020-01-09T21:56:33.887Z,1578606993.887 [Micromodem](INFO): Nmea in: $CACLK,2020,1,9,21,56,34*49

2020-01-09T21:56:34.279Z,1578606994.279 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-09T21:56:35Z*78

2020-01-09T21:56:34.280Z,1578606994.280 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T21:56:35.028966Z,USER_CMD,RTC*1C

2020-01-09T21:56:37.965Z,1578606997.965 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-09T21:56:42.362Z,1578607002.362 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-09T21:56:44.774Z,1578607004.774 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 42F
2020-01-09T21:56:44.774Z,1578607004.774 [DDM] Communications Fault, FailCount= 1
2020-01-09T21:56:44.774Z,1578607004.774 [DDM](ERROR): Communications Fault
2020-01-09T21:56:44.879Z,1578607004.879 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-09T21:56:45.177Z,1578607005.177 [DDM](INFO): Powering down
2020-01-09T21:56:46.113Z,1578607006.113 [CBIT](INFO): Clearing failed state for component DDM
2020-01-09T21:56:46.113Z,1578607006.113 [DDM] No Fault, FailCount= 1
2020-01-09T21:56:48.409Z,1578607008.409 [DDM](INFO): Powering up
2020-01-09T21:56:48.409Z,1578607008.409 [DDM](DEBUG): Initializing DDM.
2020-01-09T21:56:50.435Z,1578607010.435 [NAL9602](INFO): Powering up NAL9602
2020-01-09T21:56:58.918Z,1578607018.918 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-09T21:57:01.343Z,1578607021.343 [NAL9602](INFO): NAL9602 initialized
2020-01-09T21:57:03.843Z,1578607023.843 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-09T21:57:03.867Z,1578607023.867 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-09T21:57:13.973Z,1578607033.973 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-09T21:57:13.973Z,1578607033.973 [Micromodem](INFO): Have incoming data
2020-01-09T21:57:13.976Z,1578607033.976 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T21:57:14.010Z,1578607034.010 [Micromodem](INFO): FrameSize_:4
2020-01-09T21:57:14.010Z,1578607034.010 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-09T21:57:14.011Z,1578607034.011 [Micromodem](INFO): Outer loop. Size:4
2020-01-09T21:57:14.011Z,1578607034.011 [Micromodem](INFO): Inner loop. C:61
2020-01-09T21:57:14.011Z,1578607034.011 [Micromodem](INFO): Inner loop. C:144
2020-01-09T21:57:14.012Z,1578607034.012 [Micromodem](INFO): Inner loop. C:184
2020-01-09T21:57:14.012Z,1578607034.012 [Micromodem](INFO): Inner loop. C:209
2020-01-09T21:57:14.026Z,1578607034.026 [Micromodem](CRITICAL): CRC Error 0x-2E476FC3 != computed 0x0
2020-01-09T21:57:14.027Z,1578607034.027 [Micromodem](CRITICAL): Failed to parse uplink message:3D90B8D1
2020-01-09T21:57:14.243Z,1578607034.243 [CBIT](INFO): Critical error at 20200109T215714
2020-01-09T21:57:14.389Z,1578607034.389 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215713.075556,06,1618,32,0136,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,25.8,7.00,00,-4.00,-01,-0.03,25,10000,4000*73

2020-01-09T21:57:14.988Z,1578607034.988 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.020527
CHAN A1 (24V): -0.028893
CHAN A2 (12V): -0.007026
CHAN A3 (5V): -0.002116
CHAN B0 (3.3V): 0.000055
CHAN B1 (3.15aV): 0.000579
CHAN B2 (3.15bV): 0.000384
CHAN B3 (GND): 0.002709
OPEN: 0.005103
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-09T21:57:16.915Z,1578607036.915 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.993424
2020-01-09T21:57:28.714Z,1578607048.714 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T21:57:28.714Z,1578607048.714 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-2768,-32768,V
2020-01-09T21:57:29.077Z,1578607049.077 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T21:57:29.077Z,1578607049.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T21:57:43.715Z,1578607063.715 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-15.002222
2020-01-09T21:57:43.715Z,1578607063.715 [SBIT](FAULT): Control surface position failure.
2020-01-09T21:57:51.049Z,1578607071.049 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T21:57:51.050Z,1578607071.050 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010915104118,35.0, -0.1,   0.0,10
2020-01-09T21:57:57.538Z,1578607077.538 [SBIT](CRITICAL): SBIT FAILED
2020-01-09T21:57:57.559Z,1578607077.559 [CommandLine](IMPORTANT): got command configSet list
2020-01-09T21:57:57.559Z,1578607077.559 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-09T21:57:57.560Z,1578607077.560 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-09T21:57:57.561Z,1578607077.561 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-09T21:57:57.562Z,1578607077.562 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-09T21:57:57.563Z,1578607077.563 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-09T21:57:57.566Z,1578607077.566 [CBIT](INFO): Critical error at 20200109T215757
2020-01-09T21:57:57.943Z,1578607077.943 [MissionManager](IMPORTANT): Started mission Startup
2020-01-09T21:57:57.943Z,1578607077.943 [Startup] Running Loop=1
2020-01-09T21:57:57.943Z,1578607077.943 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-09T21:57:57.943Z,1578607077.943 [Startup:A.GoToSurface] Running Loop=1
2020-01-09T21:57:57.943Z,1578607077.943 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T21:57:57.944Z,1578607077.944 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T21:57:57.944Z,1578607077.944 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T21:57:57.949Z,1578607077.949 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T21:57:57.953Z,1578607077.953 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T21:57:57.954Z,1578607077.954 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T21:57:57.955Z,1578607077.955 [Startup:StartupSatComms] Running Loop=1
2020-01-09T21:57:57.955Z,1578607077.955 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-09T21:57:57.955Z,1578607077.955 [Startup:StartupSatComms:A] Running Loop=1
2020-01-09T21:57:58.338Z,1578607078.338 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-09T21:58:51.699Z,1578607131.699 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T21:58:51.699Z,1578607131.699 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T21:58:53.331Z,1578607133.331 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005024
2020-01-09T21:58:58.193Z,1578607138.193 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-09T21:57:57.0Z
2020-01-09T21:58:58.193Z,1578607138.193 [Startup:StartupSatComms:A] Stopped
2020-01-09T21:58:58.193Z,1578607138.193 [Startup:StartupSatComms:B] Running Loop=1
2020-01-09T21:58:58.574Z,1578607138.574 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T21:58:59.410Z,1578607139.410 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 029B175E040001005D00000400890400000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60
2020-01-09T21:58:59.410Z,1578607139.410 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 7A177777B103597E45F2B7F8DA2DA31058F2FBCDD4DCD89694CC4A2C8DFC4C7DD1E08A88E77AEB22ED11FB6B7DCD01258B695928E847F31A98C964857669A1E5
2020-01-09T21:58:59.411Z,1578607139.411 [Micromodem](INFO): Outgoing frame #3, 64 bytes: E5F43F2D97B8A7C92CA53E80F5BB300DED2AA82F24D215AC3530D9DE8DBCBCE65642226DE6244C3911DA8A8934C5D8EACCDE3BB63DE947E9AAE119212EFBBBF9
2020-01-09T21:58:59.411Z,1578607139.411 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T21:58:59.627Z,1578607139.627 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T21:58:59.991Z,1578607139.991 [Micromodem](INFO): Nmea in: $CADRQ,215900,1,12,0,64,1*7B

2020-01-09T21:58:59.991Z,1578607139.991 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,029B175E040001005D00000400890400000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60*36

2020-01-09T21:59:00.399Z,1578607140.399 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:00.799Z,1578607140.799 [Micromodem](INFO): Nmea in: $CADRQ,215901,1,12,0,64,2*79

2020-01-09T21:59:00.799Z,1578607140.799 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,7A177777B103597E45F2B7F8DA2DA31058F2FBCDD4DCD89694CC4A2C8DFC4C7DD1E08A88E77AEB22ED11FB6B7DCD01258B695928E847F31A98C964857669A1E5*4A

2020-01-09T21:59:01.207Z,1578607141.207 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:01.615Z,1578607141.615 [Micromodem](INFO): Nmea in: $CADRQ,215901,1,12,0,64,3*78

2020-01-09T21:59:01.615Z,1578607141.615 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E5F43F2D97B8A7C92CA53E80F5BB300DED2AA82F24D215AC3530D9DE8DBCBCE65642226DE6244C3911DA8A8934C5D8EACCDE3BB63DE947E9AAE119212EFBBBF9*3A

2020-01-09T21:59:02.011Z,1578607142.011 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:02.415Z,1578607142.415 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T21:59:05.000Z,1578607146.000 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20200109T212834/Courier0004.lzma
2020-01-09T21:59:06.069Z,1578607146.069 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T21:59:06.464Z,1578607146.464 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,215902.739298,3,0,200,4000,10000,1,1,12,1,3,3,3,192*71

2020-01-09T21:59:07.687Z,1578607147.687 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T21:59:08.003Z,1578607148.003 [DataOverHttps](INFO): Moved sent file to Logs/20200109T212834/Courier0004.lzma.bak
2020-01-09T21:59:08.003Z,1578607148.003 [DataOverHttps](INFO): SBD MOMSN=12188457
2020-01-09T21:59:08.083Z,1578607148.083 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T21:59:08.479Z,1578607148.479 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T21:59:08.480Z,1578607148.480 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T212834/Courier0004.lzma.parts
2020-01-09T21:59:08.480Z,1578607148.480 [Micromodem](INFO): Packets left to send: 1
2020-01-09T21:59:08.886Z,1578607148.886 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215907.293402,06,244,23,0108,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,23.5,6.00,14,-3.00,-01,-0.05,28,10000,4000*4A

2020-01-09T21:59:09.776Z,1578607149.776 [Micromodem](INFO): Outgoing frame #1, 64 bytes: D3A0175E000001005D00000400AC0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-09T21:59:09.777Z,1578607149.777 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE36724C
2020-01-09T21:59:09.778Z,1578607149.778 [Micromodem](INFO): Outgoing frame #3, 64 bytes: B3ED518021B7FFDF6A13749C8539CC9CF2A14D09617969808F681CBAB9943CE6DFF8058E2AC3F9556166661B5436304CED63112E055D3CF87844382DD80F9FBF
2020-01-09T21:59:09.778Z,1578607149.778 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T21:59:10.763Z,1578607150.763 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T21:59:11.163Z,1578607151.163 [Micromodem](INFO): Nmea in: $CADRQ,215910,1,12,0,64,1*7A

2020-01-09T21:59:11.164Z,1578607151.164 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,D3A0175E000001005D00000400AC0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*3E

2020-01-09T21:59:11.579Z,1578607151.579 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:11.975Z,1578607151.975 [Micromodem](INFO): Nmea in: $CADRQ,215912,1,12,0,64,2*7B

2020-01-09T21:59:11.975Z,1578607151.975 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE36724C*4F

2020-01-09T21:59:12.375Z,1578607152.375 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:12.783Z,1578607152.783 [Micromodem](INFO): Nmea in: $CADRQ,215913,1,12,0,64,3*7B

2020-01-09T21:59:12.783Z,1578607152.783 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B3ED518021B7FFDF6A13749C8539CC9CF2A14D09617969808F681CBAB9943CE6DFF8058E2AC3F9556166661B5436304CED63112E055D3CF87844382DD80F9FBF*43

2020-01-09T21:59:13.187Z,1578607153.187 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:13.587Z,1578607153.587 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T21:59:16.062Z,1578607156.062 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T21:59:16.062Z,1578607156.062 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32BE,-32768,-32768,-32768,V
2020-01-09T21:59:17.219Z,1578607157.219 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T21:59:17.629Z,1578607157.629 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,215913.905041,3,0,200,4000,10000,1,1,12,1,3,3,3,192*76

2020-01-09T21:59:18.841Z,1578607158.841 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T21:59:19.247Z,1578607159.247 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T21:59:19.679Z,1578607159.679 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T21:59:19.681Z,1578607159.681 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T215323/Courier0000.lzma.parts
2020-01-09T21:59:19.681Z,1578607159.681 [Micromodem](INFO): Packets left to send: 1
2020-01-09T21:59:20.062Z,1578607160.062 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215918.458810,06,235,23,0129,0150,29,02,02,00,02,1,012,001,0,4,1,0,150,22.8,7.00,17,-4.00,-01,-0.03,21,10000,4000*42

2020-01-09T21:59:20.551Z,1578607160.551 [Micromodem](INFO): Outgoing frame #1, 64 bytes: D3A0175E00000000FE393D8D7F96FD5503EB86DE85C4072D9EC3BF877B5615DE533641B604703F0157C96C298C56B2C009E3E706DF54818EF23C6284D0170C52
2020-01-09T21:59:20.552Z,1578607160.552 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 2F11E0373E4244019D9BCE59159ADD86305296BAF2B22AE3A12A25D50F1FA2A2F8BC06F6CE30AB2C54EB3A8055EC841FFBD54482D4CF1A448F3E0AA6F2F29060
2020-01-09T21:59:20.552Z,1578607160.552 [Micromodem](INFO): Outgoing frame #3, 18 bytes: F6E7D1E1977FE5F400984D5FFFC434011F80
2020-01-09T21:59:20.552Z,1578607160.552 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T21:59:21.447Z,1578607161.447 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T21:59:21.851Z,1578607161.851 [Micromodem](INFO): Nmea in: $CADRQ,215921,1,12,0,64,1*78

2020-01-09T21:59:21.851Z,1578607161.851 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,D3A0175E00000000FE393D8D7F96FD5503EB86DE85C4072D9EC3BF877B5615DE533641B604703F0157C96C298C56B2C009E3E706DF54818EF23C6284D0170C52*3F

2020-01-09T21:59:22.263Z,1578607162.263 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:22.661Z,1578607162.661 [Micromodem](INFO): Nmea in: $CADRQ,215922,1,12,0,64,2*78

2020-01-09T21:59:22.662Z,1578607162.662 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2F11E0373E4244019D9BCE59159ADD86305296BAF2B22AE3A12A25D50F1FA2A2F8BC06F6CE30AB2C54EB3A8055EC841FFBD54482D4CF1A448F3E0AA6F2F29060*38

2020-01-09T21:59:23.069Z,1578607163.069 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:23.467Z,1578607163.467 [Micromodem](INFO): Nmea in: $CADRQ,215923,1,12,0,64,3*78

2020-01-09T21:59:23.467Z,1578607163.467 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F6E7D1E1977FE5F400984D5FFFC434011F80*39

2020-01-09T21:59:23.871Z,1578607163.871 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,18*40

2020-01-09T21:59:24.274Z,1578607164.274 [Micromodem](INFO): Nmea in: $CATXP,146*41

2020-01-09T21:59:24.329Z,1578607164.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-09T21:59:24.329Z,1578607164.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T21:59:24.359Z,1578607164.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T21:59:24.747Z,1578607164.747 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T21:59:24.747Z,1578607164.747 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-09T21:59:26.054Z,1578607166.054 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20200109T215323/Courier0000.lzma
2020-01-09T21:59:27.917Z,1578607167.917 [Micromodem](INFO): Nmea in: $CATXF,146*57

2020-01-09T21:59:28.058Z,1578607168.058 [DataOverHttps](INFO): Moved sent file to Logs/20200109T215323/Courier0000.lzma.bak
2020-01-09T21:59:28.058Z,1578607168.058 [DataOverHttps](INFO): SBD MOMSN=12188461
2020-01-09T21:59:28.320Z,1578607168.320 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,215924.537141,3,0,200,4000,10000,1,1,12,1,3,3,3,146*77

2020-01-09T21:59:29.127Z,1578607169.127 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T21:59:29.531Z,1578607169.531 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T21:59:29.945Z,1578607169.945 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T21:59:29.946Z,1578607169.946 [Micromodem](INFO): Sent 138 bytes from file Logs/20200109T215323/Courier0000.lzma.parts
2020-01-09T21:59:29.946Z,1578607169.946 [Micromodem](INFO): Packets left to send: 0
2020-01-09T21:59:30.342Z,1578607170.342 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215929.042127,06,237,23,0108,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,24.3,5.00,10,-2.00,-01,-0.05,27,10000,4000*46

2020-01-09T21:59:30.845Z,1578607170.845 [Micromodem](INFO): Outgoing frame #1, 47 bytes: D3A0175E040000005D00000400140000000000000000151D80030C5669C08C34EE4669D8BC0AEDE448A8E2FA6A2000
2020-01-09T21:59:30.846Z,1578607170.846 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-09T21:59:31.743Z,1578607171.743 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-09T21:59:32.147Z,1578607172.147 [Micromodem](INFO): Nmea in: $CADRQ,215931,1,12,0,64,1*79

2020-01-09T21:59:32.147Z,1578607172.147 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,D3A0175E040000005D00000400140000000000000000151D80030C5669C08C34EE4669D8BC0AEDE448A8E2FA6A2000*44

2020-01-09T21:59:32.551Z,1578607172.551 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-09T21:59:32.954Z,1578607172.954 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-09T21:59:34.187Z,1578607174.187 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-09T21:59:34.576Z,1578607174.576 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,215933.225254,3,0,200,4000,10000,1,1,12,1,1,1,3,47*42

2020-01-09T21:59:35.386Z,1578607175.386 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T21:59:35.387Z,1578607175.387 [Micromodem](INFO): Sent 39 bytes from file Logs/20200109T215323/Courier0004.lzma.parts
2020-01-09T21:59:35.387Z,1578607175.387 [Micromodem](INFO): Packets left to send: 0
2020-01-09T21:59:35.826Z,1578607175.826 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215935.222633,06,206,23,0110,0150,59,01,01,00,02,1,012,001,0,4,1,0,150,21.8,6.00,12,-3.00,-01,-0.06,26,10000,4000*46

2020-01-09T21:59:36.752Z,1578607176.752 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 7EA1175E000001005D00000400DA0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-09T21:59:36.752Z,1578607176.752 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE3666A3
2020-01-09T21:59:36.753Z,1578607176.753 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 8561B5C53CA23B6749859C2D1A60F3C6B74AA9576FA20C49D62660A7BC92115E34F9DBF485C2E7723C415A9576E76DD3226A80D9F95B85806F8BF69060C78DB8
2020-01-09T21:59:36.753Z,1578607176.753 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T21:59:37.667Z,1578607177.667 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T21:59:38.067Z,1578607178.067 [Micromodem](INFO): Nmea in: $CADRQ,215937,1,12,0,64,1*7F

2020-01-09T21:59:38.067Z,1578607178.067 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,7EA1175E000001005D00000400DA0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*3D

2020-01-09T21:59:38.477Z,1578607178.477 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:38.875Z,1578607178.875 [Micromodem](INFO): Nmea in: $CADRQ,215939,1,12,0,64,2*72

2020-01-09T21:59:38.875Z,1578607178.875 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE3666A3*4F

2020-01-09T21:59:39.279Z,1578607179.279 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:39.697Z,1578607179.697 [Micromodem](INFO): Nmea in: $CADRQ,215939,1,12,0,64,3*73

2020-01-09T21:59:39.697Z,1578607179.697 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8561B5C53CA23B6749859C2D1A60F3C6B74AA9576FA20C49D62660A7BC92115E34F9DBF485C2E7723C415A9576E76DD3226A80D9F95B85806F8BF69060C78DB8*46

2020-01-09T21:59:40.087Z,1578607180.087 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:40.494Z,1578607180.494 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T21:59:44.135Z,1578607184.135 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T21:59:44.532Z,1578607184.532 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,215940.821251,3,0,200,4000,10000,1,1,12,1,3,3,3,192*74

2020-01-09T21:59:45.344Z,1578607185.344 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T21:59:45.412Z,1578607185.412 [DataOverHttps](INFO): Sending 345 bytes from file Logs/20200109T215614/Courier0000.lzma
2020-01-09T21:59:45.749Z,1578607185.749 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T21:59:46.151Z,1578607186.151 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T21:59:46.151Z,1578607186.151 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T215614/Courier0000.lzma.parts
2020-01-09T21:59:46.152Z,1578607186.152 [Micromodem](INFO): Packets left to send: 1
2020-01-09T21:59:46.558Z,1578607186.558 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215945.375761,06,500,26,0091,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,24.6,6.00,14,-3.00,-01,-0.05,28,10000,4000*44

2020-01-09T21:59:47.071Z,1578607187.071 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 7EA1175E000000007F3BB9B826E3E3BE66BBD8F5B915A0C5A1ED3C697D505B9A552E9DB79E6C460A08CD5AA00D41B737A79411905F12F4A6CC9AB760EB9F01F7
2020-01-09T21:59:47.071Z,1578607187.071 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AEBF8B10D9DFA4505C2D9BFF533563124EF9AD02B85D829F0F54BA5DB676F9A875B6C2D3D301F5D33ECD0FD166D6A411B295F2EDFAE414DED8D4E1E043020EC0
2020-01-09T21:59:47.072Z,1578607187.072 [Micromodem](INFO): Outgoing frame #3, 41 bytes: E79B902CB8506ACFE469E936996171F1D4C2BCBF59F9E8EF5701AB867B7475D6650801F14575BA5B00
2020-01-09T21:59:47.072Z,1578607187.072 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T21:59:47.414Z,1578607187.414 [DataOverHttps](INFO): Moved sent file to Logs/20200109T215614/Courier0000.lzma.bak
2020-01-09T21:59:47.414Z,1578607187.414 [DataOverHttps](INFO): SBD MOMSN=12188470
2020-01-09T21:59:47.991Z,1578607187.991 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T21:59:48.406Z,1578607188.406 [Micromodem](INFO): Nmea in: $CADRQ,215948,1,12,0,64,1*77

2020-01-09T21:59:48.406Z,1578607188.406 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,7EA1175E000000007F3BB9B826E3E3BE66BBD8F5B915A0C5A1ED3C697D505B9A552E9DB79E6C460A08CD5AA00D41B737A79411905F12F4A6CC9AB760EB9F01F7*36

2020-01-09T21:59:48.801Z,1578607188.801 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:49.213Z,1578607189.213 [Micromodem](INFO): Nmea in: $CADRQ,215949,1,12,0,64,2*75

2020-01-09T21:59:49.214Z,1578607189.214 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AEBF8B10D9DFA4505C2D9BFF533563124EF9AD02B85D829F0F54BA5DB676F9A875B6C2D3D301F5D33ECD0FD166D6A411B295F2EDFAE414DED8D4E1E043020EC0*41

2020-01-09T21:59:49.611Z,1578607189.611 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T21:59:50.015Z,1578607190.015 [Micromodem](INFO): Nmea in: $CADRQ,215950,1,12,0,64,3*7C

2020-01-09T21:59:50.015Z,1578607190.015 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E79B902CB8506ACFE469E936996171F1D4C2BCBF59F9E8EF5701AB867B7475D6650801F14575BA5B00*4D

2020-01-09T21:59:50.421Z,1578607190.421 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,41*4C

2020-01-09T21:59:50.819Z,1578607190.819 [Micromodem](INFO): Nmea in: $CATXP,169*4C

2020-01-09T21:59:54.455Z,1578607194.455 [Micromodem](INFO): Nmea in: $CATXF,169*5A

2020-01-09T21:59:54.876Z,1578607194.876 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,215951.111372,3,0,200,4000,10000,1,1,12,1,3,3,3,169*7A

2020-01-09T21:59:55.672Z,1578607195.672 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T21:59:56.075Z,1578607196.075 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T21:59:56.482Z,1578607196.482 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T21:59:56.483Z,1578607196.483 [Micromodem](INFO): Sent 161 bytes from file Logs/20200109T215614/Courier0000.lzma.parts
2020-01-09T21:59:56.483Z,1578607196.483 [Micromodem](INFO): Packets left to send: 0
2020-01-09T21:59:56.882Z,1578607196.882 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109215955.641675,06,550,27,0104,0150,29,02,02,00,04,1,012,001,0,4,1,0,150,22.7,7.00,17,-4.00,-01,-0.03,20,10000,4000*4B

2020-01-09T21:59:57.443Z,1578607197.443 [Startup:StartupSatComms:B] Stopped
2020-01-09T21:59:57.443Z,1578607197.443 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-09T21:59:57.443Z,1578607197.443 [Startup:StartupSatComms] Stopped
2020-01-09T21:59:57.443Z,1578607197.443 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-09T21:59:57.444Z,1578607197.444 [Startup](INFO): Completed Startup
2020-01-09T21:59:57.444Z,1578607197.444 [MissionManager](INFO): Startup is completed.
2020-01-09T21:59:57.444Z,1578607197.444 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-09T21:59:57.445Z,1578607197.445 [Startup] Stopped
2020-01-09T21:59:57.445Z,1578607197.445 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-09T21:59:57.445Z,1578607197.445 [Startup:A.GoToSurface] Stopped
2020-01-09T21:59:57.445Z,1578607197.445 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T21:59:57.575Z,1578607197.575 [MissionManager](IMPORTANT): Started mission Default
2020-01-09T21:59:57.575Z,1578607197.575 [Default] Running Loop=1
2020-01-09T21:59:57.575Z,1578607197.575 [Default](DEBUG): Aggregate::initialize Default
2020-01-09T21:59:57.575Z,1578607197.575 [Default:B.GoToSurface] Running Loop=1
2020-01-09T21:59:57.575Z,1578607197.575 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T21:59:57.576Z,1578607197.576 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T21:59:57.576Z,1578607197.576 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T21:59:57.576Z,1578607197.576 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T21:59:57.576Z,1578607197.576 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T21:59:57.581Z,1578607197.581 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T21:59:57.581Z,1578607197.581 [Default:A.Wait] Running Loop=1
2020-01-09T21:59:57.581Z,1578607197.581 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-09T22:00:06.237Z,1578607206.237 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20200109T191734/Express0005.lzma
2020-01-09T22:00:08.242Z,1578607208.242 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Express0005.lzma.bak
2020-01-09T22:00:08.242Z,1578607208.242 [DataOverHttps](INFO): SBD MOMSN=12188478
2020-01-09T22:00:10.847Z,1578607210.847 [Default:A.Wait](INFO): Done Waiting.
2020-01-09T22:00:10.847Z,1578607210.847 [Default:A.Wait] Stopped
2020-01-09T22:00:10.847Z,1578607210.847 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T22:00:11.231Z,1578607211.231 [Default:CheckIn] Running Loop=1
2020-01-09T22:00:11.231Z,1578607211.231 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T22:00:11.231Z,1578607211.231 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T22:00:11.643Z,1578607211.643 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-09T22:01:20.753Z,1578607280.753 [CommandLine](IMPORTANT): got command quit
2020-01-09T22:01:21.757Z,1578607281.757 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:21.757Z,1578607281.757 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:21.761Z,1578607281.761 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-09T22:01:21.761Z,1578607281.761 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:21.762Z,1578607281.762 [CommandLine](INFO): Join timeout helper Thread ID is 3812
2020-01-09T22:01:21.762Z,1578607281.762 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-09T22:01:21.763Z,1578607281.763 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:21.763Z,1578607281.763 [NavChartDb](INFO): Join timeout helper Thread ID is 3813
2020-01-09T22:01:21.997Z,1578607281.997 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:21.997Z,1578607281.997 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.001Z,1578607282.001 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-09T22:01:22.001Z,1578607282.001 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.001Z,1578607282.001 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3814
2020-01-09T22:01:22.061Z,1578607282.061 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:22.061Z,1578607282.061 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T22:01:22.062Z,1578607282.062 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.081Z,1578607282.081 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-09T22:01:22.081Z,1578607282.081 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.081Z,1578607282.081 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3815
2020-01-09T22:01:22.169Z,1578607282.169 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:22.170Z,1578607282.170 [CTD_NeilBrown](INFO): Powering down
2020-01-09T22:01:22.181Z,1578607282.181 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.181Z,1578607282.181 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-09T22:01:22.181Z,1578607282.181 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.182Z,1578607282.182 [Radio_Surface](INFO): Join timeout helper Thread ID is 3816
2020-01-09T22:01:22.397Z,1578607282.397 [Radio_Surface](INFO): Powering down
2020-01-09T22:01:22.398Z,1578607282.398 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:22.398Z,1578607282.398 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.410Z,1578607282.410 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-09T22:01:22.410Z,1578607282.410 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.410Z,1578607282.410 [DataOverHttps](INFO): Join timeout helper Thread ID is 3817
2020-01-09T22:01:22.773Z,1578607282.773 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:22.773Z,1578607282.773 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.782Z,1578607282.782 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-09T22:01:22.782Z,1578607282.782 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.782Z,1578607282.782 [logger](INFO): Join timeout helper Thread ID is 3818
2020-01-09T22:01:22.793Z,1578607282.793 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:22.793Z,1578607282.793 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.810Z,1578607282.810 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-09T22:01:22.810Z,1578607282.810 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.810Z,1578607282.810 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-09T22:01:22.810Z,1578607282.810 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:22.810Z,1578607282.810 [controlThread](INFO): Join timeout helper Thread ID is 3819
2020-01-09T22:01:22.832Z,1578607282.832 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T22:01:22.833Z,1578607282.833 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-09T22:01:22.833Z,1578607282.833 [AHRS_M2](INFO): Powering down
2020-01-09T22:01:22.905Z,1578607282.905 [DDM](INFO): Powering down
2020-01-09T22:01:22.978Z,1578607282.978 [DUSBL_Hydroid](INFO): Powering down
2020-01-09T22:01:23.069Z,1578607283.069 [Micromodem](INFO): Powering down
2020-01-09T22:01:23.165Z,1578607283.165 [NAL9602](INFO): Powering down
2020-01-09T22:01:23.237Z,1578607283.237 [RDI_Pathfinder](INFO): Powering down
2020-01-09T22:01:23.239Z,1578607283.239 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-09T22:01:23.240Z,1578607283.240 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-09T22:01:23.240Z,1578607283.240 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-09T22:01:23.241Z,1578607283.241 [MissionManager](INFO): Uninitializing Mission Default
2020-01-09T22:01:23.241Z,1578607283.241 [Default] Stopped
2020-01-09T22:01:23.241Z,1578607283.241 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-09T22:01:23.241Z,1578607283.241 [Default:B.GoToSurface] Stopped
2020-01-09T22:01:23.241Z,1578607283.241 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T22:01:23.241Z,1578607283.241 [Default:CheckIn] Stopped
2020-01-09T22:01:23.242Z,1578607283.242 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T22:01:23.242Z,1578607283.242 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T22:01:23.244Z,1578607283.244 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-09T22:01:23.244Z,1578607283.244 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-09T22:01:23.244Z,1578607283.244 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-09T22:01:23.245Z,1578607283.245 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-09T22:01:23.245Z,1578607283.245 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-09T22:01:23.245Z,1578607283.245 [BuoyancyServo](INFO): Powering down
2020-01-09T22:01:23.257Z,1578607283.257 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-09T22:01:23.257Z,1578607283.257 [ElevatorServo](INFO): Powering down
2020-01-09T22:01:23.258Z,1578607283.258 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-09T22:01:23.258Z,1578607283.258 [MassServo](INFO): Powering down
2020-01-09T22:01:23.258Z,1578607283.258 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T22:01:23.259Z,1578607283.259 [RudderServo](INFO): Powering down
2020-01-09T22:01:23.259Z,1578607283.259 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-09T22:01:23.259Z,1578607283.259 [ThrusterServo](INFO): Powering down
2020-01-09T22:01:23.260Z,1578607283.260 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-09T22:01:23.260Z,1578607283.260 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-09T22:01:23.260Z,1578607283.260 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-09T22:01:23.261Z,1578607283.261 [CBIT](DEBUG): Powering off loads.
2020-01-09T22:01:23.273Z,1578607283.273 [CBIT](DEBUG): Disabling WDT.
2020-01-09T22:01:23.285Z,1578607283.285 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-09T22:01:23.286Z,1578607283.286 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:23.340Z,1578607283.340 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:23.351Z,1578607283.351 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:23.394Z,1578607283.394 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:23.397Z,1578607283.397 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:23.456Z,1578607283.456 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T22:01:23.517Z,1578607283.517 [logger ThreadHandler](INFO): Thread cancelled.