2020-01-13T23:53:26.626Z,1578959606.626 [Supervisor](DEBUG): Initializing supervisor.
2020-01-13T23:53:26.629Z,1578959606.629 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-13T23:53:26.630Z,1578959606.630 [SyncHandler](INFO): Protected caller Thread ID is 4049
2020-01-13T23:53:26.630Z,1578959606.630 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-13T23:53:26.631Z,1578959606.631 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-13T23:53:26.632Z,1578959606.632 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4050
2020-01-13T23:53:26.635Z,1578959606.635 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-13T23:53:26.646Z,1578959606.646 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-13T23:53:26.647Z,1578959606.647 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-13T23:53:26.647Z,1578959606.647 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4051
2020-01-13T23:53:26.648Z,1578959606.648 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-13T23:53:26.649Z,1578959606.649 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-13T23:53:26.649Z,1578959606.649 [logger ThreadHandler](INFO): Protected caller Thread ID is 4052
2020-01-13T23:53:26.651Z,1578959606.651 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-13T23:53:26.652Z,1578959606.652 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-13T23:53:26.653Z,1578959606.653 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-13T23:53:27.077Z,1578959607.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-13T23:53:27.078Z,1578959607.078 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-13T23:53:27.172Z,1578959607.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-13T23:53:27.172Z,1578959607.172 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-13T23:53:27.472Z,1578959607.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-13T23:53:27.473Z,1578959607.473 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-13T23:53:27.606Z,1578959607.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-13T23:53:27.606Z,1578959607.606 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-13T23:53:27.787Z,1578959607.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-13T23:53:27.787Z,1578959607.787 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-13T23:53:28.216Z,1578959608.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-13T23:53:28.216Z,1578959608.216 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-13T23:53:28.419Z,1578959608.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-13T23:53:28.420Z,1578959608.420 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-13T23:53:28.559Z,1578959608.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-13T23:53:28.559Z,1578959608.559 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-13T23:53:28.738Z,1578959608.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-13T23:53:28.739Z,1578959608.739 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-13T23:53:28.831Z,1578959608.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-13T23:53:28.832Z,1578959608.832 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-13T23:53:29.119Z,1578959609.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-13T23:53:29.120Z,1578959609.120 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-13T23:53:29.198Z,1578959609.198 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-13T23:53:29.298Z,1578959609.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-13T23:53:29.299Z,1578959609.299 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-13T23:53:29.912Z,1578959609.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-13T23:53:29.913Z,1578959609.913 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-13T23:53:30.308Z,1578959610.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-13T23:53:30.310Z,1578959610.310 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-13T23:53:30.311Z,1578959610.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-13T23:53:30.507Z,1578959610.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-13T23:53:30.609Z,1578959610.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-13T23:53:30.703Z,1578959610.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-13T23:53:30.924Z,1578959610.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-13T23:53:30.924Z,1578959610.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-13T23:53:31.007Z,1578959611.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-13T23:53:31.097Z,1578959611.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-13T23:53:31.197Z,1578959611.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-13T23:53:31.277Z,1578959611.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-13T23:53:31.392Z,1578959611.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-13T23:53:31.701Z,1578959611.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-13T23:53:31.896Z,1578959611.896 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-13T23:53:31.897Z,1578959611.897 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-13T23:53:31.939Z,1578959611.939 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-13T23:53:32.524Z,1578959612.524 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-13T23:53:32.532Z,1578959612.532 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-13T23:53:32.534Z,1578959612.534 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-13T23:53:32.538Z,1578959612.538 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-13T23:53:32.539Z,1578959612.539 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-13T23:53:32.544Z,1578959612.544 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-13T23:53:32.544Z,1578959612.544 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-13T23:53:32.549Z,1578959612.549 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-13T23:53:32.616Z,1578959612.616 [AHRS_M2] Loaded
2020-01-13T23:53:32.616Z,1578959612.616 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-13T23:53:32.692Z,1578959612.692 [DataOverHttps] Loaded
2020-01-13T23:53:32.693Z,1578959612.693 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-13T23:53:32.694Z,1578959612.694 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-13T23:53:32.694Z,1578959612.694 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4131
2020-01-13T23:53:32.717Z,1578959612.717 [DDM] Loaded
2020-01-13T23:53:32.718Z,1578959612.718 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-13T23:53:32.730Z,1578959612.730 [Depth_Keller] Loaded
2020-01-13T23:53:32.730Z,1578959612.730 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-13T23:53:32.735Z,1578959612.735 [DropWeight] Loaded
2020-01-13T23:53:32.735Z,1578959612.735 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-13T23:53:32.793Z,1578959612.793 [DUSBL_Hydroid] Loaded
2020-01-13T23:53:32.793Z,1578959612.793 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-13T23:53:32.838Z,1578959612.838 [Micromodem] Loaded
2020-01-13T23:53:32.839Z,1578959612.839 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-13T23:53:32.931Z,1578959612.931 [NAL9602] Loaded
2020-01-13T23:53:32.931Z,1578959612.931 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-13T23:53:32.967Z,1578959612.967 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-13T23:53:32.968Z,1578959612.968 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-13T23:53:32.968Z,1578959612.968 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-13T23:53:32.968Z,1578959612.968 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-13T23:53:32.969Z,1578959612.969 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-13T23:53:32.970Z,1578959612.970 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-13T23:53:32.970Z,1578959612.970 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-13T23:53:32.971Z,1578959612.971 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-13T23:53:32.971Z,1578959612.971 [Onboard] Loaded
2020-01-13T23:53:32.971Z,1578959612.971 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-13T23:53:32.977Z,1578959612.977 [PowerOnly] Loaded
2020-01-13T23:53:32.977Z,1578959612.977 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-13T23:53:32.983Z,1578959612.983 [Radio_Surface] Loaded
2020-01-13T23:53:32.983Z,1578959612.983 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-13T23:53:32.984Z,1578959612.984 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-13T23:53:32.985Z,1578959612.985 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4132
2020-01-13T23:53:33.027Z,1578959613.027 [RDI_Pathfinder] Loaded
2020-01-13T23:53:33.027Z,1578959613.027 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-13T23:53:34.439Z,1578959614.439 [BPC1] Loaded
2020-01-13T23:53:34.439Z,1578959614.439 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-13T23:53:34.440Z,1578959614.440 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-13T23:53:34.440Z,1578959614.440 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-13T23:53:34.453Z,1578959614.453 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-13T23:53:34.454Z,1578959614.454 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-13T23:53:34.554Z,1578959614.554 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-13T23:53:34.555Z,1578959614.555 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-13T23:53:34.574Z,1578959614.574 [NavChart] Loaded
2020-01-13T23:53:34.574Z,1578959614.574 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-13T23:53:34.578Z,1578959614.578 [UniversalFixResidualReporter] Loaded
2020-01-13T23:53:34.578Z,1578959614.578 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-13T23:53:34.579Z,1578959614.579 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-13T23:53:34.579Z,1578959614.579 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-13T23:53:34.683Z,1578959614.683 [BuoyancyServo] Loaded
2020-01-13T23:53:34.684Z,1578959614.684 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-13T23:53:34.698Z,1578959614.698 [ElevatorServo] Loaded
2020-01-13T23:53:34.699Z,1578959614.699 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-13T23:53:34.713Z,1578959614.713 [MassServo] Loaded
2020-01-13T23:53:34.713Z,1578959614.713 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-13T23:53:34.727Z,1578959614.727 [RudderServo] Loaded
2020-01-13T23:53:34.728Z,1578959614.728 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-13T23:53:34.742Z,1578959614.742 [ThrusterServo] Loaded
2020-01-13T23:53:34.742Z,1578959614.742 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-13T23:53:34.743Z,1578959614.743 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-13T23:53:34.743Z,1578959614.743 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-13T23:53:34.991Z,1578959614.991 [CTD_NeilBrown] Loaded
2020-01-13T23:53:34.991Z,1578959614.991 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-13T23:53:34.992Z,1578959614.992 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-13T23:53:34.993Z,1578959614.993 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4133
2020-01-13T23:53:35.006Z,1578959615.006 [PAR_Licor] Loaded
2020-01-13T23:53:35.007Z,1578959615.007 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-13T23:53:35.049Z,1578959615.049 [WetLabsSeaOWL_UV_A] Loaded
2020-01-13T23:53:35.049Z,1578959615.049 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-13T23:53:35.050Z,1578959615.050 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-13T23:53:35.050Z,1578959615.050 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 4134
2020-01-13T23:53:35.051Z,1578959615.051 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-13T23:53:35.051Z,1578959615.051 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-13T23:53:35.349Z,1578959615.349 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-13T23:53:35.349Z,1578959615.349 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-13T23:53:35.389Z,1578959615.389 [DepthRateCalculator] Loaded
2020-01-13T23:53:35.389Z,1578959615.389 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-13T23:53:35.395Z,1578959615.395 [PitchRateCalculator] Loaded
2020-01-13T23:53:35.395Z,1578959615.395 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-13T23:53:35.407Z,1578959615.407 [SpeedCalculator] Loaded
2020-01-13T23:53:35.407Z,1578959615.407 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-13T23:53:35.427Z,1578959615.427 [TempGradientCalculator] Loaded
2020-01-13T23:53:35.428Z,1578959615.428 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-13T23:53:35.433Z,1578959615.433 [YawRateCalculator] Loaded
2020-01-13T23:53:35.433Z,1578959615.433 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-13T23:53:35.472Z,1578959615.472 [ElevatorOffsetCalculator] Loaded
2020-01-13T23:53:35.472Z,1578959615.472 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-13T23:53:35.473Z,1578959615.473 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-13T23:53:35.473Z,1578959615.473 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-13T23:53:35.606Z,1578959615.606 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-13T23:53:35.626Z,1578959615.626 [SBIT] Loaded
2020-01-13T23:53:35.627Z,1578959615.627 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-13T23:53:35.627Z,1578959615.627 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-13T23:53:35.640Z,1578959615.640 [IBIT] Loaded
2020-01-13T23:53:35.640Z,1578959615.640 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-13T23:53:35.643Z,1578959615.643 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-13T23:53:35.776Z,1578959615.776 [CBIT] Loaded
2020-01-13T23:53:35.776Z,1578959615.776 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-13T23:53:35.776Z,1578959615.776 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-13T23:53:35.777Z,1578959615.777 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-13T23:53:35.856Z,1578959615.856 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-13T23:53:35.857Z,1578959615.857 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-13T23:53:35.952Z,1578959615.952 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-13T23:53:35.952Z,1578959615.952 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-13T23:53:35.998Z,1578959615.998 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-13T23:53:36.079Z,1578959616.079 [VerticalControl] Loaded
2020-01-13T23:53:36.079Z,1578959616.079 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-13T23:53:36.080Z,1578959616.080 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-13T23:53:36.147Z,1578959616.147 [HorizontalControl] Loaded
2020-01-13T23:53:36.147Z,1578959616.147 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-13T23:53:36.147Z,1578959616.147 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-13T23:53:36.149Z,1578959616.149 [SpeedControl] Loaded
2020-01-13T23:53:36.149Z,1578959616.149 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-13T23:53:36.150Z,1578959616.150 [LoopControl](DEBUG): Construct LoopControl.
2020-01-13T23:53:36.150Z,1578959616.150 [LoopControl] Loaded
2020-01-13T23:53:36.151Z,1578959616.151 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-13T23:53:36.151Z,1578959616.151 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-13T23:53:36.152Z,1578959616.152 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-13T23:53:36.183Z,1578959616.183 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-13T23:53:36.187Z,1578959616.187 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-13T23:53:36.187Z,1578959616.187 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-13T23:53:36.194Z,1578959616.194 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-13T23:53:36.195Z,1578959616.195 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-13T23:53:36.195Z,1578959616.195 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4135
2020-01-13T23:53:36.200Z,1578959616.200 [Supervisor](INFO): Main Thread ID is 4048
2020-01-13T23:53:36.200Z,1578959616.200 [Supervisor](DEBUG): Running supervisor.
2020-01-13T23:53:36.200Z,1578959616.200 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4136
2020-01-13T23:53:36.203Z,1578959616.203 [controlThread ThreadHandler](INFO): Handler Thread ID is 4137
2020-01-13T23:53:36.203Z,1578959616.203 [controlThread](DEBUG): Initializing ControlThread
2020-01-13T23:53:36.212Z,1578959616.212 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-13T23:53:36.212Z,1578959616.212 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-13T23:53:36.217Z,1578959616.217 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-13T23:53:36.218Z,1578959616.218 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-13T23:53:36.218Z,1578959616.218 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-13T23:53:36.218Z,1578959616.218 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-13T23:53:36.219Z,1578959616.219 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-13T23:53:36.219Z,1578959616.219 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-13T23:53:36.220Z,1578959616.220 [SBIT](INFO): Initialize SBIT Component.
2020-01-13T23:53:36.220Z,1578959616.220 [SBIT](IMPORTANT): git: 2019-12-03-34-g1420f60
2020-01-13T23:53:36.220Z,1578959616.220 [SBIT](INFO): git hash: 1420f6075bfd9e2317cf67bc08ebc33a04e592a5
2020-01-13T23:53:36.221Z,1578959616.221 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-13T23:53:36.222Z,1578959616.222 [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-13T23:53:36.223Z,1578959616.223 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-13T23:53:36.224Z,1578959616.224 [IBIT](INFO): Initialize IBIT Component.
2020-01-13T23:53:36.224Z,1578959616.224 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-13T23:53:36.225Z,1578959616.225 [logger ThreadHandler](INFO): Handler Thread ID is 4138
2020-01-13T23:53:36.237Z,1578959616.237 [CBIT](DEBUG): Initialized mux pins.
2020-01-13T23:53:36.237Z,1578959616.237 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-13T23:53:36.245Z,1578959616.245 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4139
2020-01-13T23:53:36.246Z,1578959616.246 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-13T23:53:36.257Z,1578959616.257 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4140
2020-01-13T23:53:36.261Z,1578959616.261 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-13T23:53:36.261Z,1578959616.261 [CBIT](DEBUG): Initializing heartbeat.
2020-01-13T23:53:36.269Z,1578959616.269 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4141
2020-01-13T23:53:36.270Z,1578959616.270 [CTD_NeilBrown](INFO): Powering down
2020-01-13T23:53:36.291Z,1578959616.291 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 4142
2020-01-13T23:53:36.297Z,1578959616.297 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-13T23:53:36.321Z,1578959616.321 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4143
2020-01-13T23:53:36.324Z,1578959616.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-13T23:53:36.324Z,1578959616.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-13T23:53:36.325Z,1578959616.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-13T23:53:36.325Z,1578959616.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-13T23:53:36.325Z,1578959616.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-13T23:53:36.325Z,1578959616.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-13T23:53:36.326Z,1578959616.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-13T23:53:36.326Z,1578959616.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-13T23:53:36.326Z,1578959616.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-13T23:53:36.326Z,1578959616.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-13T23:53:36.326Z,1578959616.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-13T23:53:36.326Z,1578959616.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-13T23:53:36.327Z,1578959616.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-13T23:53:36.327Z,1578959616.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-13T23:53:36.327Z,1578959616.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-13T23:53:36.327Z,1578959616.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-13T23:53:36.333Z,1578959616.333 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-13T23:53:36.333Z,1578959616.333 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-13T23:53:36.369Z,1578959616.369 [CBIT](DEBUG): Backplane powered.
2020-01-13T23:53:36.369Z,1578959616.369 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-13T23:53:36.371Z,1578959616.371 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-13T23:53:36.371Z,1578959616.371 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-13T23:53:36.372Z,1578959616.372 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-13T23:53:36.373Z,1578959616.373 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-13T23:53:36.382Z,1578959616.382 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-13T23:53:36.429Z,1578959616.429 [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-13T23:53:36.429Z,1578959616.429 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-13T23:53:36.578Z,1578959616.578 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-13T23:53:36.579Z,1578959616.579 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-13T23:53:36.581Z,1578959616.581 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-13T23:53:36.616Z,1578959616.616 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-13T23:53:36.634Z,1578959616.634 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-13T23:53:36.640Z,1578959616.640 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-13T23:53:36.655Z,1578959616.655 [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-13T23:53:36.661Z,1578959616.661 [Radio_Surface](INFO): Powering up
2020-01-13T23:53:36.673Z,1578959616.673 [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-13T23:53:36.695Z,1578959616.695 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-13T23:53:36.723Z,1578959616.723 [DDM](INFO): Powering up
2020-01-13T23:53:36.723Z,1578959616.723 [DDM](DEBUG): Initializing DDM.
2020-01-13T23:53:36.747Z,1578959616.747 [DUSBL_Hydroid](INFO): Powering up
2020-01-13T23:53:36.747Z,1578959616.747 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-13T23:53:36.957Z,1578959616.957 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-13T23:53:36.966Z,1578959616.966 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-13T23:53:36.966Z,1578959616.966 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-13T23:53:36.973Z,1578959616.973 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-13T23:53:36.974Z,1578959616.974 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-13T23:53:36.981Z,1578959616.981 [MassServo](DEBUG): Initializing MassServo.
2020-01-13T23:53:36.982Z,1578959616.982 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-13T23:53:36.989Z,1578959616.989 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-13T23:53:36.990Z,1578959616.990 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-13T23:53:36.997Z,1578959616.997 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-13T23:53:37.102Z,1578959617.102 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-13T23:53:37.102Z,1578959617.102 [DropWeight] Hardware Fault, FailCount= 1
2020-01-13T23:53:37.102Z,1578959617.102 [DropWeight](ERROR): Hardware Fault
2020-01-13T23:53:37.104Z,1578959617.104 [Micromodem](INFO): Powering up
2020-01-13T23:53:37.104Z,1578959617.104 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-13T23:53:37.189Z,1578959617.189 [CommandLine](FAULT): Scheduling is paused
2020-01-13T23:53:37.190Z,1578959617.190 [CBIT](INFO): Critical error at 20200113T235337
2020-01-13T23:53:37.190Z,1578959617.190 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-13T23:53:37.192Z,1578959617.192 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-13T23:53:37.193Z,1578959617.193 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-13T23:53:37.849Z,1578959617.849 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-13T23:53:37.849Z,1578959617.849 [RudderServo](FAULT): Rudder failed to initialize
2020-01-13T23:53:37.849Z,1578959617.849 [RudderServo] Communications Fault, FailCount= 1
2020-01-13T23:53:37.849Z,1578959617.849 [RudderServo](ERROR): Communications Fault
2020-01-13T23:53:37.958Z,1578959617.958 [CBIT](INFO): Critical error at 20200113T235337
2020-01-13T23:53:37.961Z,1578959617.961 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-13T23:53:38.166Z,1578959618.166 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-13T23:53:38.166Z,1578959618.166 [RudderServo](INFO): Powering down
2020-01-13T23:53:38.835Z,1578959618.835 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-13T23:53:38.954Z,1578959618.954 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-13T23:53:38.958Z,1578959618.958 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-13T23:53:38.958Z,1578959618.958 [RudderServo] No Fault, FailCount= 1
2020-01-13T23:53:42.049Z,1578959622.049 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-13T23:53:42.451Z,1578959622.451 [Micromodem](INFO): Nmea in: $CATMG,2020-01-13T23:53:42.863515Z,RTC,RTC*5E

2020-01-13T23:53:42.452Z,1578959622.452 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-13T23:53:42.863515Z,RTC,RTC*5E

2020-01-13T23:53:42.859Z,1578959622.859 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-13T23:53:42.859Z,1578959622.859 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-13T23:53:43.263Z,1578959623.263 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-13T23:53:43.263Z,1578959623.263 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-13T23:53:43.667Z,1578959623.667 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-13T23:53:43.667Z,1578959623.667 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,2*2D

2020-01-13T23:53:44.071Z,1578959624.071 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,2*2F

2020-01-13T23:53:44.071Z,1578959624.071 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-13T23:53:44.475Z,1578959624.475 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-13T23:53:44.475Z,1578959624.475 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-13T23:53:44.863Z,1578959624.863 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-13T23:53:44.863Z,1578959624.863 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-13T23:53:45.279Z,1578959625.279 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-13T23:53:45.279Z,1578959625.279 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-13T23:53:45.663Z,1578959625.663 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-13T23:53:45.664Z,1578959625.664 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,13,23,53,46*42

2020-01-13T23:53:46.131Z,1578959626.131 [Micromodem](INFO): Nmea in: $CACLK,2020,1,13,23,53,46*70

2020-01-13T23:53:46.467Z,1578959626.467 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-13T23:53:47Z*71

2020-01-13T23:53:46.469Z,1578959626.469 [Micromodem](INFO): Nmea in: $CATMG,2020-01-13T23:53:47.027660Z,USER_CMD,RTC*13

2020-01-13T23:53:47.296Z,1578959627.296 [DDM](INFO): Dynamic Docking Module:	
!C REMUS Capture Rev 1.1
2020-01-13T23:53:50.120Z,1578959630.120 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-13T23:53:51.951Z,1578959631.951 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004715
2020-01-13T23:53:54.959Z,1578959634.959 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-13T23:54:03.023Z,1578959643.023 [NAL9602](INFO): Powering up NAL9602
2020-01-13T23:54:13.935Z,1578959653.935 [NAL9602](INFO): NAL9602 initialized
2020-01-13T23:54:15.661Z,1578959655.661 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-13T23:54:15.677Z,1578959655.677 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-13T23:54:26.792Z,1578959666.792 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012911
CHAN A1 (24V): -0.028820
CHAN A2 (12V): -0.007233
CHAN A3 (5V): -0.002302
CHAN B0 (3.3V): 0.000093
CHAN B1 (3.15aV): -0.000163
CHAN B2 (3.15bV): 0.000016
CHAN B3 (GND): 0.002074
OPEN: 0.005003
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-13T23:54:28.767Z,1578959668.767 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.997823
2020-01-13T23:54:55.535Z,1578959695.535 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-15.011020
2020-01-13T23:54:55.535Z,1578959695.535 [SBIT](FAULT): Control surface position failure.
2020-01-13T23:54:57.481Z,1578959697.481 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-13T23:54:57.481Z,1578959697.481 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-13T23:55:09.011Z,1578959709.011 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:0.006598
2020-01-13T23:55:09.011Z,1578959709.011 [SBIT](FAULT): Control surface position failure.
2020-01-13T23:55:09.406Z,1578959709.406 [SBIT](CRITICAL): SBIT FAILED
2020-01-13T23:55:09.425Z,1578959709.425 [CBIT](INFO): Critical error at 20200113T235509
2020-01-13T23:55:09.451Z,1578959709.451 [CommandLine](IMPORTANT): got command configSet list
2020-01-13T23:55:09.451Z,1578959709.451 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=25 count;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-13T23:55:09.452Z,1578959709.452 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-13T23:55:09.453Z,1578959709.453 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-13T23:55:09.453Z,1578959709.453 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-13T23:55:09.453Z,1578959709.453 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): Micromodem.pwrampTXLevel=2 enum;

2020-01-13T23:55:09.454Z,1578959709.454 [CommandLine](IMPORTANT): Micromodem.sendExpress=1 bool;

2020-01-13T23:55:09.455Z,1578959709.455 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-13T23:55:09.455Z,1578959709.455 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-13T23:55:09.455Z,1578959709.455 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-13T23:55:09.455Z,1578959709.455 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-13T23:55:09.455Z,1578959709.455 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-13T23:55:09.719Z,1578959709.719 [MissionManager](IMPORTANT): Started mission Startup
2020-01-13T23:55:09.719Z,1578959709.719 [Startup] Running Loop=1
2020-01-13T23:55:09.719Z,1578959709.719 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-13T23:55:09.719Z,1578959709.719 [Startup:A.GoToSurface] Running Loop=1
2020-01-13T23:55:09.720Z,1578959709.720 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-13T23:55:09.720Z,1578959709.720 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-13T23:55:09.721Z,1578959709.721 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-13T23:55:09.721Z,1578959709.721 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-13T23:55:09.721Z,1578959709.721 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-13T23:55:09.722Z,1578959709.722 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-13T23:55:09.723Z,1578959709.723 [Startup:StartupSatComms] Running Loop=1
2020-01-13T23:55:09.723Z,1578959709.723 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-13T23:55:09.723Z,1578959709.723 [Startup:StartupSatComms:A] Running Loop=1
2020-01-13T23:55:10.144Z,1578959710.144 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-13T23:55:49.689Z,1578959749.689 [NAL9602](INFO): SBD MO Status=2, MOMSN=10159, MT Status=2, MTMSN=0
2020-01-13T23:55:49.689Z,1578959749.689 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-13T23:56:09.960Z,1578959769.960 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-13T23:55:09.7Z
2020-01-13T23:56:09.960Z,1578959769.960 [Startup:StartupSatComms:A] Stopped
2020-01-13T23:56:09.960Z,1578959769.960 [Startup:StartupSatComms:B] Running Loop=1
2020-01-13T23:56:10.317Z,1578959770.317 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-13T23:56:10.328Z,1578959770.328 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-13T23:56:11.731Z,1578959771.731 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 55E01C5E070001005D00000400AF0200000000000000151D80030C5669BF3148E2FA22188B6A3173E4BCBB56DD1C843E4F2BF537D70901D6FAF0DE24881418A6
2020-01-13T23:56:11.731Z,1578959771.731 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 0C7AA1B9AE451C786CFEC5A418A44EDD706ECEA6965816560F6B0AD6F9724918A26949A8D83A84D51E30E453EB8F838B8B593BDB04CCC43F2455C740A34D24CF
2020-01-13T23:56:11.732Z,1578959771.732 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 8E6C4B188E8E15C04C9A1D2F25152FD72E79AC0983B2AC04F527C1A041CF50EE7A1A190CB1842067B1BD98B4794C3231E1262F209FE71E29F32BA1423544894A
2020-01-13T23:56:11.732Z,1578959771.732 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-13T23:56:12.671Z,1578959772.671 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-13T23:56:13.067Z,1578959773.067 [Micromodem](INFO): Nmea in: $CADRQ,235612,1,12,0,64,1*75

2020-01-13T23:56:13.067Z,1578959773.067 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,55E01C5E070001005D00000400AF0200000000000000151D80030C5669BF3148E2FA22188B6A3173E4BCBB56DD1C843E4F2BF537D70901D6FAF0DE24881418A6*3D

2020-01-13T23:56:13.514Z,1578959773.514 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T23:56:13.883Z,1578959773.883 [Micromodem](INFO): Nmea in: $CADRQ,235613,1,12,0,64,2*77

2020-01-13T23:56:13.883Z,1578959773.883 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,0C7AA1B9AE451C786CFEC5A418A44EDD706ECEA6965816560F6B0AD6F9724918A26949A8D83A84D51E30E453EB8F838B8B593BDB04CCC43F2455C740A34D24CF*3A

2020-01-13T23:56:14.279Z,1578959774.279 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T23:56:14.695Z,1578959774.695 [Micromodem](INFO): Nmea in: $CADRQ,235614,1,12,0,64,3*71

2020-01-13T23:56:14.695Z,1578959774.695 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8E6C4B188E8E15C04C9A1D2F25152FD72E79AC0983B2AC04F527C1A041CF50EE7A1A190CB1842067B1BD98B4794C3231E1262F209FE71E29F32BA1423544894A*32

2020-01-13T23:56:15.095Z,1578959775.095 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T23:56:15.491Z,1578959775.491 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-13T23:56:18.473Z,1578959778.473 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20200113T212541/Courier0007.lzma
2020-01-13T23:56:19.132Z,1578959779.132 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-13T23:56:19.532Z,1578959779.532 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,235615.679079,3,0,200,4000,10000,1,1,12,1,3,3,3,192*79

2020-01-13T23:56:20.479Z,1578959780.479 [DataOverHttps](INFO): Moved sent file to Logs/20200113T212541/Courier0007.lzma.bak
2020-01-13T23:56:20.479Z,1578959780.479 [DataOverHttps](INFO): SBD MOMSN=12191954
2020-01-13T23:56:22.373Z,1578959782.373 [Micromodem](INFO): Nmea in: $CACST,6,0,20200113235621.903270,06,127,21,0090,0150,89,02,02,00,02,1,012,001,0,4,1,0,150,20.3,5.00,13,-2.00,-01,0.00,28,10000,4000*64

2020-01-13T23:56:29.636Z,1578959789.636 [NAL9602](INFO): SBD MO Status=2, MOMSN=10159, MT Status=2, MTMSN=0
2020-01-13T23:56:29.637Z,1578959789.637 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-13T23:56:36.526Z,1578959796.526 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-13T23:56:36.526Z,1578959796.526 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:56:36.564Z,1578959796.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:56:36.951Z,1578959796.951 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:56:36.951Z,1578959796.951 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-13T23:56:37.737Z,1578959797.737 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20200113T235326/Courier0000.lzma
2020-01-13T23:56:39.743Z,1578959799.743 [DataOverHttps](INFO): Moved sent file to Logs/20200113T235326/Courier0000.lzma.bak
2020-01-13T23:56:39.743Z,1578959799.743 [DataOverHttps](INFO): SBD MOMSN=12191956
2020-01-13T23:56:47.004Z,1578959807.004 [NAL9602](INFO): SBD MO Status=2, MOMSN=10159, MT Status=2, MTMSN=0
2020-01-13T23:56:47.005Z,1578959807.005 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-13T23:57:00.155Z,1578959820.155 [DataOverHttps](INFO): Sending 1507 bytes from file Logs/20200110T180952/Express0001.lzma
2020-01-13T23:57:02.159Z,1578959822.159 [DataOverHttps](INFO): Moved sent file to Logs/20200110T180952/Express0001.lzma.bak
2020-01-13T23:57:02.159Z,1578959822.159 [DataOverHttps](INFO): SBD MOMSN=12191962
2020-01-13T23:57:02.670Z,1578959822.670 [CommandLine](IMPORTANT): got command failComponent
2020-01-13T23:57:02.670Z,1578959822.670 [CommandLine](IMPORTANT): Failed components:
2020-01-13T23:57:02.670Z,1578959822.670 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-01-13T23:57:10.102Z,1578959830.102 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-13T23:56:09.0Z
2020-01-13T23:57:10.102Z,1578959830.102 [Startup:StartupSatComms:B] Stopped
2020-01-13T23:57:10.102Z,1578959830.102 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-13T23:57:10.102Z,1578959830.102 [Startup:StartupSatComms] Stopped
2020-01-13T23:57:10.102Z,1578959830.102 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-13T23:57:10.103Z,1578959830.103 [Startup](INFO): Completed Startup
2020-01-13T23:57:10.103Z,1578959830.103 [MissionManager](INFO): Startup is completed.
2020-01-13T23:57:10.103Z,1578959830.103 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-13T23:57:10.103Z,1578959830.103 [Startup] Stopped
2020-01-13T23:57:10.103Z,1578959830.103 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-13T23:57:10.103Z,1578959830.103 [Startup:A.GoToSurface] Stopped
2020-01-13T23:57:10.103Z,1578959830.103 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-13T23:57:10.471Z,1578959830.471 [MissionManager](IMPORTANT): Started mission Default
2020-01-13T23:57:10.471Z,1578959830.471 [Default] Running Loop=1
2020-01-13T23:57:10.471Z,1578959830.471 [Default](DEBUG): Aggregate::initialize Default
2020-01-13T23:57:10.471Z,1578959830.471 [Default:B.GoToSurface] Running Loop=1
2020-01-13T23:57:10.471Z,1578959830.471 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-13T23:57:10.471Z,1578959830.471 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-13T23:57:10.472Z,1578959830.472 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-13T23:57:10.472Z,1578959830.472 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-13T23:57:10.472Z,1578959830.472 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-13T23:57:10.473Z,1578959830.473 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-13T23:57:10.473Z,1578959830.473 [Default:A.Wait] Running Loop=1
2020-01-13T23:57:10.473Z,1578959830.473 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-13T23:57:11.668Z,1578959831.668 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-01-13T23:57:19.728Z,1578959839.728 [DataOverHttps](INFO): Sending 1423 bytes from file Logs/20200110T181144/Express0001.lzma
2020-01-13T23:57:21.732Z,1578959841.732 [DataOverHttps](INFO): Moved sent file to Logs/20200110T181144/Express0001.lzma.bak
2020-01-13T23:57:21.732Z,1578959841.732 [DataOverHttps](INFO): SBD MOMSN=12192014
2020-01-13T23:57:21.756Z,1578959841.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=10159, MT Status=2, MTMSN=0
2020-01-13T23:57:21.758Z,1578959841.758 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-13T23:57:23.149Z,1578959843.149 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 30.799999 degree
2020-01-13T23:57:23.151Z,1578959843.151 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2020-01-13T23:57:23.152Z,1578959843.152 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2020-01-13T23:57:23.161Z,1578959843.161 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2020-01-13T23:57:23.445Z,1578959843.445 [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,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,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-13T23:57:23.822Z,1578959843.822 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:23.823Z,1578959843.823 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-13T23:57:23.823Z,1578959843.823 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:23.824Z,1578959843.824 [Default:A.Wait](INFO): Done Waiting.
2020-01-13T23:57:23.824Z,1578959843.824 [Default:A.Wait] Stopped
2020-01-13T23:57:23.824Z,1578959843.824 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-13T23:57:23.891Z,1578959843.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:24.232Z,1578959844.232 [Default:CheckIn] Running Loop=1
2020-01-13T23:57:24.232Z,1578959844.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-13T23:57:24.232Z,1578959844.232 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-13T23:57:24.264Z,1578959844.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:24.269Z,1578959844.269 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-13T23:57:24.758Z,1578959844.758 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-13T23:57:25.102Z,1578959845.102 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:25.102Z,1578959845.102 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-13T23:57:25.102Z,1578959845.102 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:25.137Z,1578959845.137 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:25.485Z,1578959845.485 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:25.485Z,1578959845.485 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-13T23:57:26.263Z,1578959846.263 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:26.263Z,1578959846.263 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-13T23:57:26.263Z,1578959846.263 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:26.314Z,1578959846.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:26.802Z,1578959846.802 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:26.802Z,1578959846.802 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-13T23:57:27.491Z,1578959847.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:27.491Z,1578959847.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-01-13T23:57:27.491Z,1578959847.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:27.501Z,1578959847.501 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:27.887Z,1578959847.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:27.887Z,1578959847.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-01-13T23:57:28.659Z,1578959848.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:28.659Z,1578959848.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-01-13T23:57:28.659Z,1578959848.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:28.702Z,1578959848.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:29.090Z,1578959849.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:29.090Z,1578959849.090 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-01-13T23:57:29.863Z,1578959849.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:29.863Z,1578959849.863 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-01-13T23:57:29.863Z,1578959849.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:29.873Z,1578959849.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:30.359Z,1578959850.359 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:30.359Z,1578959850.359 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-01-13T23:57:31.079Z,1578959851.079 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:31.079Z,1578959851.079 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-01-13T23:57:31.079Z,1578959851.079 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:31.089Z,1578959851.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:31.490Z,1578959851.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:31.490Z,1578959851.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-01-13T23:57:32.291Z,1578959852.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:32.291Z,1578959852.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-01-13T23:57:32.291Z,1578959852.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:32.317Z,1578959852.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:32.703Z,1578959852.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:32.704Z,1578959852.704 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-01-13T23:57:33.499Z,1578959853.499 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:33.499Z,1578959853.499 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-01-13T23:57:33.499Z,1578959853.499 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:33.509Z,1578959853.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:33.966Z,1578959853.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:33.966Z,1578959853.966 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-01-13T23:57:34.731Z,1578959854.731 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:34.731Z,1578959854.731 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-01-13T23:57:34.731Z,1578959854.731 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:34.754Z,1578959854.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:35.182Z,1578959855.182 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:35.183Z,1578959855.183 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-01-13T23:57:35.935Z,1578959855.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:35.935Z,1578959855.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-01-13T23:57:35.935Z,1578959855.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:36.034Z,1578959856.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:36.380Z,1578959856.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:36.381Z,1578959856.381 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-01-13T23:57:37.160Z,1578959857.160 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-01-13T23:57:37.160Z,1578959857.160 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-01-13T23:57:37.160Z,1578959857.160 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-13T23:57:37.191Z,1578959857.191 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:37.550Z,1578959857.550 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-13T23:57:37.550Z,1578959857.550 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-01-13T23:57:38.322Z,1578959858.322 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1578959872.000000 second
2020-01-13T23:57:38.402Z,1578959858.402 [Default:CheckIn:Read_GPS] Stopped
2020-01-13T23:57:38.402Z,1578959858.402 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-13T23:57:38.787Z,1578959858.787 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-13T23:57:39.254Z,1578959859.254 [Micromodem](ERROR): Buffer fill timout failure.
2020-01-13T23:57:40.471Z,1578959860.471 [Micromodem](INFO): Outgoing frame #1, 64 bytes: F6021D5E040001005D00000400FD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2020-01-13T23:57:40.471Z,1578959860.471 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31
2020-01-13T23:57:40.472Z,1578959860.472 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416AE25E31B4E18619E5C1F90902C505781770CFDD053F3D92B2892521A612D82BE005FC891846AFDB685B90A9E84997E433AEDF6B399ED6967A92
2020-01-13T23:57:40.472Z,1578959860.472 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-13T23:57:40.711Z,1578959860.711 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-13T23:57:41.066Z,1578959861.066 [Micromodem](INFO): Nmea in: $CADRQ,235741,1,12,0,64,1*72

2020-01-13T23:57:41.067Z,1578959861.067 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F6021D5E040001005D00000400FD0100000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*40

2020-01-13T23:57:41.470Z,1578959861.470 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T23:57:41.883Z,1578959861.883 [Micromodem](INFO): Nmea in: $CADRQ,235741,1,12,0,64,2*71

2020-01-13T23:57:41.883Z,1578959861.883 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*3C

2020-01-13T23:57:42.275Z,1578959862.275 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T23:57:42.679Z,1578959862.679 [Micromodem](INFO): Nmea in: $CADRQ,235742,1,12,0,64,3*73

2020-01-13T23:57:42.679Z,1578959862.679 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6612EED8A8416AE25E31B4E18619E5C1F90902C505781770CFDD053F3D92B2892521A612D82BE005FC891846AFDB685B90A9E84997E433AEDF6B399ED6967A92*4C

2020-01-13T23:57:43.086Z,1578959863.086 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-13T23:57:43.486Z,1578959863.486 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-13T23:57:47.125Z,1578959867.125 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-13T23:57:47.532Z,1578959867.532 [Micromodem](INFO): Nmea in: $CAXST,6,20200113,235743.668375,3,0,200,4000,10000,1,1,12,1,3,3,3,192*74

2020-01-13T23:57:53.815Z,1578959873.815 [CommandLine](IMPORTANT): got command quit
2020-01-13T23:57:54.821Z,1578959874.821 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:54.821Z,1578959874.821 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:54.877Z,1578959874.877 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-13T23:57:54.877Z,1578959874.877 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:54.878Z,1578959874.878 [CommandLine](INFO): Join timeout helper Thread ID is 4166
2020-01-13T23:57:54.893Z,1578959874.893 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-13T23:57:54.894Z,1578959874.894 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:54.895Z,1578959874.895 [NavChartDb](INFO): Join timeout helper Thread ID is 4167
2020-01-13T23:57:55.109Z,1578959875.109 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:55.110Z,1578959875.110 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.125Z,1578959875.125 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-13T23:57:55.125Z,1578959875.125 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.125Z,1578959875.125 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4168
2020-01-13T23:57:55.389Z,1578959875.389 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:55.389Z,1578959875.389 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-13T23:57:55.390Z,1578959875.390 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.405Z,1578959875.405 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-13T23:57:55.405Z,1578959875.405 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.405Z,1578959875.405 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4169
2020-01-13T23:57:55.501Z,1578959875.501 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:55.501Z,1578959875.501 [CTD_NeilBrown](INFO): Powering down
2020-01-13T23:57:55.513Z,1578959875.513 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.531Z,1578959875.531 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-13T23:57:55.531Z,1578959875.531 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.532Z,1578959875.532 [Radio_Surface](INFO): Join timeout helper Thread ID is 4170
2020-01-13T23:57:55.641Z,1578959875.641 [Radio_Surface](INFO): Powering down
2020-01-13T23:57:55.642Z,1578959875.642 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:55.642Z,1578959875.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.654Z,1578959875.654 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-13T23:57:55.654Z,1578959875.654 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:55.654Z,1578959875.654 [DataOverHttps](INFO): Join timeout helper Thread ID is 4171
2020-01-13T23:57:56.117Z,1578959876.117 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:56.121Z,1578959876.121 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.134Z,1578959876.134 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-13T23:57:56.134Z,1578959876.134 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.134Z,1578959876.134 [logger](INFO): Join timeout helper Thread ID is 4172
2020-01-13T23:57:56.149Z,1578959876.149 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:56.149Z,1578959876.149 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.154Z,1578959876.154 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-13T23:57:56.154Z,1578959876.154 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.154Z,1578959876.154 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-13T23:57:56.154Z,1578959876.154 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.154Z,1578959876.154 [controlThread](INFO): Join timeout helper Thread ID is 4173
2020-01-13T23:57:56.397Z,1578959876.397 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-13T23:57:56.397Z,1578959876.397 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-13T23:57:56.398Z,1578959876.398 [AHRS_M2](INFO): Powering down
2020-01-13T23:57:56.469Z,1578959876.469 [DDM](INFO): Powering down
2020-01-13T23:57:56.561Z,1578959876.561 [DUSBL_Hydroid](INFO): Powering down
2020-01-13T23:57:56.653Z,1578959876.653 [Micromodem](INFO): Powering down
2020-01-13T23:57:56.749Z,1578959876.749 [NAL9602](INFO): Powering down
2020-01-13T23:57:56.821Z,1578959876.821 [RDI_Pathfinder](INFO): Powering down
2020-01-13T23:57:56.823Z,1578959876.823 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-13T23:57:56.824Z,1578959876.824 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-13T23:57:56.825Z,1578959876.825 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-13T23:57:56.825Z,1578959876.825 [MissionManager](INFO): Uninitializing Mission Default
2020-01-13T23:57:56.825Z,1578959876.825 [Default] Stopped
2020-01-13T23:57:56.825Z,1578959876.825 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-13T23:57:56.825Z,1578959876.825 [Default:B.GoToSurface] Stopped
2020-01-13T23:57:56.826Z,1578959876.826 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-13T23:57:56.826Z,1578959876.826 [Default:CheckIn] Stopped
2020-01-13T23:57:56.826Z,1578959876.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-13T23:57:56.826Z,1578959876.826 [Default:CheckIn:Read_Iridium] Stopped
2020-01-13T23:57:56.828Z,1578959876.828 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-13T23:57:56.828Z,1578959876.828 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-13T23:57:56.828Z,1578959876.828 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-13T23:57:56.829Z,1578959876.829 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-13T23:57:56.829Z,1578959876.829 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-13T23:57:56.829Z,1578959876.829 [BuoyancyServo](INFO): Powering down
2020-01-13T23:57:56.841Z,1578959876.841 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-13T23:57:56.841Z,1578959876.841 [ElevatorServo](INFO): Powering down
2020-01-13T23:57:56.842Z,1578959876.842 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-13T23:57:56.842Z,1578959876.842 [MassServo](INFO): Powering down
2020-01-13T23:57:56.843Z,1578959876.843 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-13T23:57:56.843Z,1578959876.843 [RudderServo](INFO): Powering down
2020-01-13T23:57:56.843Z,1578959876.843 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-13T23:57:56.844Z,1578959876.844 [ThrusterServo](INFO): Powering down
2020-01-13T23:57:56.844Z,1578959876.844 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-13T23:57:56.845Z,1578959876.845 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-13T23:57:56.845Z,1578959876.845 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-13T23:57:56.845Z,1578959876.845 [CBIT](DEBUG): Powering off loads.
2020-01-13T23:57:56.857Z,1578959876.857 [CBIT](DEBUG): Disabling WDT.
2020-01-13T23:57:56.869Z,1578959876.869 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-13T23:57:56.870Z,1578959876.870 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.914Z,1578959876.914 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.924Z,1578959876.924 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.965Z,1578959876.965 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:56.968Z,1578959876.968 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:57.025Z,1578959877.025 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-13T23:57:57.090Z,1578959877.090 [logger ThreadHandler](INFO): Thread cancelled.