2020-01-10T01:34:25.711Z,1578620065.711 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T01:34:25.714Z,1578620065.714 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T01:34:25.715Z,1578620065.715 [SyncHandler](INFO): Protected caller Thread ID is 6325
2020-01-10T01:34:25.716Z,1578620065.716 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T01:34:25.717Z,1578620065.717 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T01:34:25.717Z,1578620065.717 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6326
2020-01-10T01:34:25.720Z,1578620065.720 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T01:34:25.732Z,1578620065.732 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T01:34:25.733Z,1578620065.733 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T01:34:25.733Z,1578620065.733 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6327
2020-01-10T01:34:25.734Z,1578620065.734 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T01:34:25.735Z,1578620065.735 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T01:34:25.735Z,1578620065.735 [logger ThreadHandler](INFO): Protected caller Thread ID is 6328
2020-01-10T01:34:25.737Z,1578620065.737 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T01:34:25.738Z,1578620065.738 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T01:34:25.739Z,1578620065.739 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T01:34:26.155Z,1578620066.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T01:34:26.155Z,1578620066.155 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T01:34:26.250Z,1578620066.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T01:34:26.250Z,1578620066.250 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T01:34:26.560Z,1578620066.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T01:34:26.560Z,1578620066.560 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T01:34:26.694Z,1578620066.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T01:34:26.695Z,1578620066.695 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T01:34:26.876Z,1578620066.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T01:34:26.877Z,1578620066.877 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T01:34:27.310Z,1578620067.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T01:34:27.311Z,1578620067.311 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T01:34:27.511Z,1578620067.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T01:34:27.511Z,1578620067.511 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T01:34:27.651Z,1578620067.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T01:34:27.651Z,1578620067.651 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T01:34:27.832Z,1578620067.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T01:34:27.832Z,1578620067.832 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T01:34:27.926Z,1578620067.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T01:34:27.926Z,1578620067.926 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T01:34:28.215Z,1578620068.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T01:34:28.216Z,1578620068.216 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T01:34:28.294Z,1578620068.294 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T01:34:28.393Z,1578620068.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T01:34:28.394Z,1578620068.394 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T01:34:29.355Z,1578620069.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T01:34:29.355Z,1578620069.355 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T01:34:29.764Z,1578620069.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T01:34:29.766Z,1578620069.766 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T01:34:29.767Z,1578620069.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T01:34:29.964Z,1578620069.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T01:34:30.059Z,1578620070.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T01:34:30.154Z,1578620070.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T01:34:30.377Z,1578620070.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T01:34:30.378Z,1578620070.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T01:34:30.460Z,1578620070.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T01:34:30.551Z,1578620070.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T01:34:30.644Z,1578620070.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T01:34:30.724Z,1578620070.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T01:34:30.827Z,1578620070.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T01:34:31.001Z,1578620071.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T01:34:31.126Z,1578620071.126 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T01:34:31.127Z,1578620071.127 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T01:34:31.150Z,1578620071.150 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T01:34:31.468Z,1578620071.468 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T01:34:31.473Z,1578620071.473 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T01:34:31.475Z,1578620071.475 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T01:34:31.480Z,1578620071.480 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T01:34:31.481Z,1578620071.481 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T01:34:31.486Z,1578620071.486 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T01:34:31.486Z,1578620071.486 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T01:34:31.492Z,1578620071.492 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T01:34:31.562Z,1578620071.562 [AHRS_M2] Loaded
2020-01-10T01:34:31.562Z,1578620071.562 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T01:34:31.639Z,1578620071.639 [DataOverHttps] Loaded
2020-01-10T01:34:31.639Z,1578620071.639 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T01:34:31.640Z,1578620071.640 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-10T01:34:31.641Z,1578620071.641 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6407
2020-01-10T01:34:31.664Z,1578620071.664 [DDM] Loaded
2020-01-10T01:34:31.665Z,1578620071.665 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T01:34:31.678Z,1578620071.678 [Depth_Keller] Loaded
2020-01-10T01:34:31.678Z,1578620071.678 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T01:34:31.683Z,1578620071.683 [DropWeight] Loaded
2020-01-10T01:34:31.683Z,1578620071.683 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T01:34:31.744Z,1578620071.744 [DUSBL_Hydroid] Loaded
2020-01-10T01:34:31.744Z,1578620071.744 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T01:34:31.790Z,1578620071.790 [Micromodem] Loaded
2020-01-10T01:34:31.791Z,1578620071.791 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T01:34:31.887Z,1578620071.887 [NAL9602] Loaded
2020-01-10T01:34:31.888Z,1578620071.888 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T01:34:31.925Z,1578620071.925 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T01:34:31.926Z,1578620071.926 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T01:34:31.926Z,1578620071.926 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T01:34:31.927Z,1578620071.927 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T01:34:31.928Z,1578620071.928 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T01:34:31.928Z,1578620071.928 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T01:34:31.929Z,1578620071.929 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T01:34:31.929Z,1578620071.929 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T01:34:31.929Z,1578620071.929 [Onboard] Loaded
2020-01-10T01:34:31.930Z,1578620071.930 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T01:34:31.935Z,1578620071.935 [PowerOnly] Loaded
2020-01-10T01:34:31.936Z,1578620071.936 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T01:34:31.942Z,1578620071.942 [Radio_Surface] Loaded
2020-01-10T01:34:31.943Z,1578620071.943 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T01:34:31.943Z,1578620071.943 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-10T01:34:31.944Z,1578620071.944 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6408
2020-01-10T01:34:31.987Z,1578620071.987 [RDI_Pathfinder] Loaded
2020-01-10T01:34:31.987Z,1578620071.987 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T01:34:33.492Z,1578620073.492 [BPC1] Loaded
2020-01-10T01:34:33.492Z,1578620073.492 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T01:34:33.493Z,1578620073.493 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T01:34:33.493Z,1578620073.493 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T01:34:33.506Z,1578620073.506 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T01:34:33.507Z,1578620073.507 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T01:34:33.610Z,1578620073.610 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T01:34:33.610Z,1578620073.610 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T01:34:33.630Z,1578620073.630 [NavChart] Loaded
2020-01-10T01:34:33.631Z,1578620073.631 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T01:34:33.635Z,1578620073.635 [UniversalFixResidualReporter] Loaded
2020-01-10T01:34:33.635Z,1578620073.635 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T01:34:33.635Z,1578620073.635 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T01:34:33.636Z,1578620073.636 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T01:34:33.742Z,1578620073.742 [BuoyancyServo] Loaded
2020-01-10T01:34:33.742Z,1578620073.742 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T01:34:33.757Z,1578620073.757 [ElevatorServo] Loaded
2020-01-10T01:34:33.757Z,1578620073.757 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T01:34:33.772Z,1578620073.772 [MassServo] Loaded
2020-01-10T01:34:33.772Z,1578620073.772 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T01:34:33.787Z,1578620073.787 [RudderServo] Loaded
2020-01-10T01:34:33.787Z,1578620073.787 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T01:34:33.801Z,1578620073.801 [ThrusterServo] Loaded
2020-01-10T01:34:33.802Z,1578620073.802 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T01:34:33.802Z,1578620073.802 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T01:34:33.803Z,1578620073.803 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T01:34:34.057Z,1578620074.057 [CTD_NeilBrown] Loaded
2020-01-10T01:34:34.057Z,1578620074.057 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T01:34:34.058Z,1578620074.058 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-10T01:34:34.059Z,1578620074.059 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6409
2020-01-10T01:34:34.073Z,1578620074.073 [PAR_Licor] Loaded
2020-01-10T01:34:34.073Z,1578620074.073 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T01:34:34.116Z,1578620074.116 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T01:34:34.117Z,1578620074.117 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T01:34:34.118Z,1578620074.118 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-10T01:34:34.118Z,1578620074.118 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6410
2020-01-10T01:34:34.119Z,1578620074.119 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T01:34:34.119Z,1578620074.119 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T01:34:34.425Z,1578620074.425 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T01:34:34.426Z,1578620074.426 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T01:34:34.519Z,1578620074.519 [DepthRateCalculator] Loaded
2020-01-10T01:34:34.519Z,1578620074.519 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T01:34:34.536Z,1578620074.536 [PitchRateCalculator] Loaded
2020-01-10T01:34:34.536Z,1578620074.536 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T01:34:34.548Z,1578620074.548 [SpeedCalculator] Loaded
2020-01-10T01:34:34.548Z,1578620074.548 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T01:34:34.600Z,1578620074.600 [TempGradientCalculator] Loaded
2020-01-10T01:34:34.600Z,1578620074.600 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T01:34:34.618Z,1578620074.618 [YawRateCalculator] Loaded
2020-01-10T01:34:34.618Z,1578620074.618 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T01:34:34.685Z,1578620074.685 [ElevatorOffsetCalculator] Loaded
2020-01-10T01:34:34.686Z,1578620074.686 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T01:34:34.686Z,1578620074.686 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T01:34:34.686Z,1578620074.686 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T01:34:34.891Z,1578620074.891 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T01:34:34.913Z,1578620074.913 [SBIT] Loaded
2020-01-10T01:34:34.913Z,1578620074.913 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T01:34:34.914Z,1578620074.914 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T01:34:34.926Z,1578620074.926 [IBIT] Loaded
2020-01-10T01:34:34.927Z,1578620074.927 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T01:34:34.930Z,1578620074.930 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T01:34:35.205Z,1578620075.205 [CBIT] Loaded
2020-01-10T01:34:35.206Z,1578620075.206 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T01:34:35.206Z,1578620075.206 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T01:34:35.207Z,1578620075.207 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T01:34:35.288Z,1578620075.288 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T01:34:35.288Z,1578620075.288 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T01:34:35.383Z,1578620075.383 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T01:34:35.384Z,1578620075.384 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T01:34:35.431Z,1578620075.431 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T01:34:35.513Z,1578620075.513 [VerticalControl] Loaded
2020-01-10T01:34:35.513Z,1578620075.513 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T01:34:35.514Z,1578620075.514 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T01:34:35.583Z,1578620075.583 [HorizontalControl] Loaded
2020-01-10T01:34:35.584Z,1578620075.584 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T01:34:35.584Z,1578620075.584 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T01:34:35.586Z,1578620075.586 [SpeedControl] Loaded
2020-01-10T01:34:35.586Z,1578620075.586 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T01:34:35.587Z,1578620075.587 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T01:34:35.587Z,1578620075.587 [LoopControl] Loaded
2020-01-10T01:34:35.588Z,1578620075.588 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T01:34:35.588Z,1578620075.588 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T01:34:35.589Z,1578620075.589 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T01:34:35.621Z,1578620075.621 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T01:34:35.624Z,1578620075.624 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T01:34:35.625Z,1578620075.625 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T01:34:35.632Z,1578620075.632 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T01:34:35.633Z,1578620075.633 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-10T01:34:35.633Z,1578620075.633 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6411
2020-01-10T01:34:35.638Z,1578620075.638 [Supervisor](INFO): Main Thread ID is 6063
2020-01-10T01:34:35.638Z,1578620075.638 [Supervisor](DEBUG): Running supervisor.
2020-01-10T01:34:35.638Z,1578620075.638 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6412
2020-01-10T01:34:35.641Z,1578620075.641 [controlThread ThreadHandler](INFO): Handler Thread ID is 6413
2020-01-10T01:34:35.641Z,1578620075.641 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T01:34:35.648Z,1578620075.648 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T01:34:35.648Z,1578620075.648 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T01:34:35.653Z,1578620075.653 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T01:34:35.653Z,1578620075.653 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T01:34:35.654Z,1578620075.654 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T01:34:35.654Z,1578620075.654 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T01:34:35.654Z,1578620075.654 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T01:34:35.655Z,1578620075.655 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T01:34:35.655Z,1578620075.655 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T01:34:35.656Z,1578620075.656 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T01:34:35.656Z,1578620075.656 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T01:34:35.656Z,1578620075.656 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T01:34:35.658Z,1578620075.658 [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-10T01:34:35.659Z,1578620075.659 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T01:34:35.659Z,1578620075.659 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T01:34:35.660Z,1578620075.660 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T01:34:35.661Z,1578620075.661 [logger ThreadHandler](INFO): Handler Thread ID is 6414
2020-01-10T01:34:35.673Z,1578620075.673 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T01:34:35.673Z,1578620075.673 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T01:34:35.681Z,1578620075.681 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6415
2020-01-10T01:34:35.682Z,1578620075.682 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T01:34:35.693Z,1578620075.693 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6416
2020-01-10T01:34:35.697Z,1578620075.697 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T01:34:35.697Z,1578620075.697 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T01:34:35.705Z,1578620075.705 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6417
2020-01-10T01:34:35.706Z,1578620075.706 [CTD_NeilBrown](INFO): Powering down
2020-01-10T01:34:35.725Z,1578620075.725 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6418
2020-01-10T01:34:35.726Z,1578620075.726 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T01:34:35.758Z,1578620075.758 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6419
2020-01-10T01:34:35.761Z,1578620075.761 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T01:34:35.762Z,1578620075.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T01:34:35.762Z,1578620075.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T01:34:35.762Z,1578620075.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T01:34:35.762Z,1578620075.762 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T01:34:35.762Z,1578620075.762 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T01:34:35.763Z,1578620075.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T01:34:35.763Z,1578620075.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T01:34:35.763Z,1578620075.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T01:34:35.763Z,1578620075.763 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T01:34:35.763Z,1578620075.763 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T01:34:35.764Z,1578620075.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T01:34:35.764Z,1578620075.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T01:34:35.764Z,1578620075.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T01:34:35.764Z,1578620075.764 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T01:34:35.764Z,1578620075.764 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T01:34:35.769Z,1578620075.769 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T01:34:35.769Z,1578620075.769 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T01:34:35.805Z,1578620075.805 [CBIT](DEBUG): Backplane powered.
2020-01-10T01:34:35.805Z,1578620075.805 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T01:34:35.807Z,1578620075.807 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T01:34:35.807Z,1578620075.807 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T01:34:35.808Z,1578620075.808 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T01:34:35.809Z,1578620075.809 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T01:34:35.819Z,1578620075.819 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T01:34:35.837Z,1578620075.837 [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-10T01:34:35.838Z,1578620075.838 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T01:34:35.927Z,1578620075.927 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T01:34:35.928Z,1578620075.928 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T01:34:35.930Z,1578620075.930 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T01:34:35.966Z,1578620075.966 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T01:34:35.968Z,1578620075.968 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T01:34:35.992Z,1578620075.992 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T01:34:36.003Z,1578620076.003 [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-10T01:34:36.015Z,1578620076.015 [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-10T01:34:36.037Z,1578620076.037 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T01:34:36.072Z,1578620076.072 [DDM](INFO): Powering up
2020-01-10T01:34:36.072Z,1578620076.072 [DDM](DEBUG): Initializing DDM.
2020-01-10T01:34:36.097Z,1578620076.097 [Radio_Surface](INFO): Powering up
2020-01-10T01:34:36.100Z,1578620076.100 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T01:34:36.100Z,1578620076.100 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T01:34:36.330Z,1578620076.330 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T01:34:36.337Z,1578620076.337 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T01:34:36.338Z,1578620076.338 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T01:34:36.345Z,1578620076.345 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T01:34:36.346Z,1578620076.346 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T01:34:36.353Z,1578620076.353 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T01:34:36.354Z,1578620076.354 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T01:34:36.361Z,1578620076.361 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T01:34:36.362Z,1578620076.362 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T01:34:36.369Z,1578620076.369 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T01:34:36.442Z,1578620076.442 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-10T01:34:36.442Z,1578620076.442 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T01:34:36.442Z,1578620076.442 [DropWeight](ERROR): Hardware Fault
2020-01-10T01:34:36.444Z,1578620076.444 [Micromodem](INFO): Powering up
2020-01-10T01:34:36.444Z,1578620076.444 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T01:34:36.538Z,1578620076.538 [CommandLine](FAULT): Scheduling is paused
2020-01-10T01:34:36.538Z,1578620076.538 [CBIT](INFO): Critical error at 20200110T013436
2020-01-10T01:34:36.539Z,1578620076.539 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T01:34:36.541Z,1578620076.541 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T01:34:36.542Z,1578620076.542 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T01:34:37.177Z,1578620077.177 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T01:34:37.177Z,1578620077.177 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T01:34:37.177Z,1578620077.177 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T01:34:37.177Z,1578620077.177 [RudderServo](ERROR): Communications Fault
2020-01-10T01:34:37.310Z,1578620077.310 [CBIT](INFO): Critical error at 20200110T013436
2020-01-10T01:34:37.313Z,1578620077.313 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T01:34:37.538Z,1578620077.538 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T01:34:37.538Z,1578620077.538 [RudderServo](INFO): Powering down
2020-01-10T01:34:38.177Z,1578620078.177 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T01:34:38.299Z,1578620078.299 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T01:34:38.303Z,1578620078.303 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T01:34:38.303Z,1578620078.303 [RudderServo] No Fault, FailCount= 1
2020-01-10T01:34:41.405Z,1578620081.405 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-10T01:34:41.804Z,1578620081.804 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T01:34:41.297631Z,RTC,RTC*5B

2020-01-10T01:34:41.804Z,1578620081.804 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T01:34:41.297631Z,RTC,RTC*5B

2020-01-10T01:34:42.211Z,1578620082.211 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-10T01:34:42.211Z,1578620082.211 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-10T01:34:42.623Z,1578620082.623 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-10T01:34:42.623Z,1578620082.623 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-10T01:34:43.019Z,1578620083.019 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-10T01:34:43.019Z,1578620083.019 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-10T01:34:43.423Z,1578620083.423 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-10T01:34:43.423Z,1578620083.423 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-10T01:34:43.835Z,1578620083.835 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-10T01:34:43.835Z,1578620083.835 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-10T01:34:44.227Z,1578620084.227 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-10T01:34:44.227Z,1578620084.227 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-10T01:34:44.646Z,1578620084.646 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-10T01:34:44.647Z,1578620084.647 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-10T01:34:45.016Z,1578620085.016 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-10T01:34:45.017Z,1578620085.017 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,01,34,46*40

2020-01-10T01:34:45.423Z,1578620085.423 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,1,34,46*42

2020-01-10T01:34:45.425Z,1578620085.425 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T01:34:47Z*73

2020-01-10T01:34:45.426Z,1578620085.426 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T01:34:47.029824Z,USER_CMD,RTC*11

2020-01-10T01:34:46.631Z,1578620086.631 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-10T01:34:49.480Z,1578620089.480 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T01:34:54.294Z,1578620094.294 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T01:35:01.967Z,1578620101.967 [NAL9602](INFO): Powering up NAL9602
2020-01-10T01:35:06.851Z,1578620106.851 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-10T01:35:06.852Z,1578620106.852 [Micromodem](INFO): Have incoming data
2020-01-10T01:35:06.857Z,1578620106.857 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-10T01:35:06.858Z,1578620106.858 [Micromodem](INFO): FrameSize_:20
2020-01-10T01:35:06.858Z,1578620106.858 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-10T01:35:06.859Z,1578620106.859 [Micromodem](INFO): Outer loop. Size:20
2020-01-10T01:35:06.859Z,1578620106.859 [Micromodem](INFO): Inner loop. C:61
2020-01-10T01:35:06.859Z,1578620106.859 [Micromodem](INFO): Inner loop. C:144
2020-01-10T01:35:06.859Z,1578620106.859 [Micromodem](INFO): Inner loop. C:184
2020-01-10T01:35:06.860Z,1578620106.860 [Micromodem](INFO): Inner loop. C:209
2020-01-10T01:35:06.860Z,1578620106.860 [Micromodem](INFO): Inner loop. C:114
2020-01-10T01:35:06.860Z,1578620106.860 [Micromodem](INFO): Inner loop. C:208
2020-01-10T01:35:06.866Z,1578620106.866 [Micromodem](INFO): Inner loop. C:225
2020-01-10T01:35:06.866Z,1578620106.866 [Micromodem](INFO): Inner loop. C:26
2020-01-10T01:35:06.866Z,1578620106.866 [Micromodem](INFO): Inner loop. C:148
2020-01-10T01:35:06.867Z,1578620106.867 [Micromodem](INFO): Inner loop. C:187
2020-01-10T01:35:06.867Z,1578620106.867 [Micromodem](INFO): Inner loop. C:154
2020-01-10T01:35:06.867Z,1578620106.867 [Micromodem](INFO): Inner loop. C:13
2020-01-10T01:35:06.867Z,1578620106.867 [Micromodem](INFO): Inner loop. C:110
2020-01-10T01:35:06.868Z,1578620106.868 [Micromodem](INFO): Inner loop. C:192
2020-01-10T01:35:06.868Z,1578620106.868 [Micromodem](INFO): Inner loop. C:67
2020-01-10T01:35:06.868Z,1578620106.868 [Micromodem](INFO): Inner loop. C:74
2020-01-10T01:35:06.869Z,1578620106.869 [Micromodem](INFO): Inner loop. C:42
2020-01-10T01:35:06.869Z,1578620106.869 [Micromodem](INFO): Inner loop. C:180
2020-01-10T01:35:06.869Z,1578620106.869 [Micromodem](INFO): Inner loop. C:136
2020-01-10T01:35:06.870Z,1578620106.870 [Micromodem](INFO): Inner loop. C:246
2020-01-10T01:35:06.871Z,1578620106.871 [Micromodem](INFO): Received command:stop
2020-01-10T01:35:06.886Z,1578620106.886 [CommandLine](IMPORTANT): got command stop
2020-01-10T01:35:06.886Z,1578620106.886 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-10T01:35:07.226Z,1578620107.226 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013506.862900,06,1579,31,0119,0150,59,02,02,00,04,1,012,001,0,3,1,0,150,27.0,8.00,00,-5.00,-01,-0.02,25,10000,4000*70

2020-01-10T01:35:12.894Z,1578620112.894 [NAL9602](INFO): NAL9602 initialized
2020-01-10T01:35:15.418Z,1578620115.418 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T01:35:15.430Z,1578620115.430 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T01:35:26.453Z,1578620126.453 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.018835
CHAN A1 (24V): -0.028794
CHAN A2 (12V): -0.007165
CHAN A3 (5V): -0.001974
CHAN B0 (3.3V): 0.000007
CHAN B1 (3.15aV): 0.000207
CHAN B2 (3.15bV): 0.000254
CHAN B3 (GND): 0.002000
OPEN: 0.005008
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T01:36:09.111Z,1578620169.111 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:0.015396
2020-01-10T01:36:09.111Z,1578620169.111 [SBIT](FAULT): Control surface position failure.
2020-01-10T01:36:09.530Z,1578620169.530 [SBIT](CRITICAL): SBIT FAILED
2020-01-10T01:36:09.550Z,1578620169.550 [CommandLine](FAULT): Scheduling is paused
2020-01-10T01:36:09.550Z,1578620169.550 [CBIT](INFO): Critical error at 20200110T013609
2020-01-10T01:36:09.621Z,1578620169.621 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T01:36:09.621Z,1578620169.621 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T01:36:09.622Z,1578620169.622 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-10T01:36:09.622Z,1578620169.622 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-10T01:36:09.622Z,1578620169.622 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T01:36:09.623Z,1578620169.623 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T01:36:09.624Z,1578620169.624 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T01:36:09.625Z,1578620169.625 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T01:36:09.625Z,1578620169.625 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T01:36:09.625Z,1578620169.625 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T01:36:09.625Z,1578620169.625 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T01:36:09.625Z,1578620169.625 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T01:36:09.864Z,1578620169.864 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T01:36:09.881Z,1578620169.881 [Startup] Running Loop=1
2020-01-10T01:36:09.881Z,1578620169.881 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T01:36:09.881Z,1578620169.881 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T01:36:09.881Z,1578620169.881 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T01:36:09.882Z,1578620169.882 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T01:36:09.882Z,1578620169.882 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T01:36:09.883Z,1578620169.883 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T01:36:09.883Z,1578620169.883 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T01:36:09.883Z,1578620169.883 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T01:36:09.890Z,1578620169.890 [Startup:StartupSatComms] Running Loop=1
2020-01-10T01:36:09.890Z,1578620169.890 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T01:36:09.890Z,1578620169.890 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T01:36:10.321Z,1578620170.321 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T01:37:04.886Z,1578620224.886 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004590
2020-01-10T01:37:10.145Z,1578620230.145 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T01:36:09.9Z
2020-01-10T01:37:10.145Z,1578620230.145 [Startup:StartupSatComms:A] Stopped
2020-01-10T01:37:10.146Z,1578620230.146 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T01:37:10.428Z,1578620230.428 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T01:37:10.428Z,1578620230.428 [Micromodem](INFO): Have incoming data
2020-01-10T01:37:10.435Z,1578620230.435 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T01:37:10.435Z,1578620230.435 [Micromodem](INFO): FrameSize_:-127
2020-01-10T01:37:10.436Z,1578620230.436 [Micromodem](INFO): FrameSize_:-127
2020-01-10T01:37:10.557Z,1578620230.557 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T01:37:10.854Z,1578620230.854 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T01:37:10.854Z,1578620230.854 [Micromodem](INFO): FrameSize_:-39
2020-01-10T01:37:10.854Z,1578620230.854 [Micromodem](INFO): FrameSize_:-39
2020-01-10T01:37:10.854Z,1578620230.854 [Micromodem](INFO): Deserialize. FrameCount:2
2020-01-10T01:37:10.855Z,1578620230.855 [Micromodem](INFO): Outer loop. Size:-39
2020-01-10T01:37:10.855Z,1578620230.855 [Micromodem](INFO): Outer loop. Size:-39
2020-01-10T01:37:10.855Z,1578620230.855 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0
2020-01-10T01:37:10.855Z,1578620230.855 [Micromodem](CRITICAL): Failed to parse uplink message:
2020-01-10T01:37:11.860Z,1578620231.860 [Micromodem](INFO): Outgoing frame #1, 47 bytes: BAD1175E040000005D00000400140000000000000000151D80030C5669C08C34EE56CD3AF807A7F32F3A169F640000
2020-01-10T01:37:11.861Z,1578620231.861 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-10T01:37:11.974Z,1578620231.974 [CBIT](INFO): Critical error at 20200110T013710
2020-01-10T01:37:12.051Z,1578620232.051 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013709.131209,06,1366,31,0106,0150,59,02,02,00,04,1,012,001,0,3,2,0,150,26.9,8.00,00,-5.00,-01,-0.03,24,10000,4000*7D

2020-01-10T01:37:12.051Z,1578620232.051 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,0,20200110013709.131209,06,1366,31,0106,0150,59,02,02,00,04,1,012,001,0,3,2,0,150,26.9,8.00,00,-5.00,-01,-0.03,24,10000,4000*7D

2020-01-10T01:37:13.253Z,1578620233.253 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-10T01:37:13.639Z,1578620233.639 [Micromodem](INFO): Nmea in: $CADRQ,013713,1,12,0,64,1*73

2020-01-10T01:37:13.639Z,1578620233.639 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,BAD1175E040000005D00000400140000000000000000151D80030C5669C08C34EE56CD3AF807A7F32F3A169F640000*45

2020-01-10T01:37:14.043Z,1578620234.043 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-10T01:37:14.451Z,1578620234.451 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-10T01:37:15.659Z,1578620235.659 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-10T01:37:16.072Z,1578620236.072 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,013715.705657,3,0,200,4000,10000,1,1,12,1,1,1,3,47*44

2020-01-10T01:37:16.871Z,1578620236.871 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T01:37:16.872Z,1578620236.872 [Micromodem](INFO): Sent 39 bytes from file Logs/20200110T012202/Courier0004.lzma.parts
2020-01-10T01:37:16.872Z,1578620236.872 [Micromodem](INFO): Packets left to send: 0
2020-01-10T01:37:17.282Z,1578620237.282 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013717.705155,06,611,27,0091,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,23.0,6.00,14,-3.00,-01,-0.02,25,10000,4000*47

2020-01-10T01:37:17.915Z,1578620237.915 [Micromodem](INFO): Outgoing frame #1, 64 bytes: F4D2175E000001005D00000400EE0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T01:37:17.915Z,1578620237.915 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE366E42
2020-01-10T01:37:17.916Z,1578620237.916 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 91DA9B0E3742AB9ECC3DDC36828421866B4B4DB8CC17ECAF1F5365A3B2E3B23948424427B84A3805CDFEB198F4B98AC9D525910E6CC0BCB612BE3604BCC9D192
2020-01-10T01:37:17.916Z,1578620237.916 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T01:37:17.968Z,1578620237.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=10151, MT Status=0, MTMSN=0
2020-01-10T01:37:17.968Z,1578620237.968 [NAL9602](INFO): No messages in MT queue
2020-01-10T01:37:18.503Z,1578620238.503 [DataOverHttps](INFO): Sending 362 bytes from file Logs/20200110T012716/Courier0000.lzma
2020-01-10T01:37:18.932Z,1578620238.932 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T01:37:19.327Z,1578620239.327 [Micromodem](INFO): Nmea in: $CADRQ,013719,1,12,0,64,1*79

2020-01-10T01:37:19.327Z,1578620239.327 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F4D2175E000001005D00000400EE0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*3E

2020-01-10T01:37:19.735Z,1578620239.735 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:37:19.754Z,1578620239.754 [DataOverHttps](INFO): Moved sent file to Logs/20200110T012716/Courier0000.lzma.bak
2020-01-10T01:37:19.754Z,1578620239.754 [DataOverHttps](INFO): SBD MOMSN=12188676
2020-01-10T01:37:20.140Z,1578620240.140 [Micromodem](INFO): Nmea in: $CADRQ,013721,1,12,0,64,2*71

2020-01-10T01:37:20.141Z,1578620240.141 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE366E42*48

2020-01-10T01:37:20.539Z,1578620240.539 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:37:20.943Z,1578620240.943 [Micromodem](INFO): Nmea in: $CADRQ,013722,1,12,0,64,3*73

2020-01-10T01:37:20.943Z,1578620240.943 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,91DA9B0E3742AB9ECC3DDC36828421866B4B4DB8CC17ECAF1F5365A3B2E3B23948424427B84A3805CDFEB198F4B98AC9D525910E6CC0BCB612BE3604BCC9D192*32

2020-01-10T01:37:21.347Z,1578620241.347 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:37:21.750Z,1578620241.750 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T01:37:25.386Z,1578620245.386 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T01:37:25.800Z,1578620245.800 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,013723.049702,3,0,200,4000,10000,1,1,12,1,3,3,3,192*76

2020-01-10T01:37:26.605Z,1578620246.605 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T01:37:27.003Z,1578620247.003 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T01:37:27.411Z,1578620247.411 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T01:37:27.412Z,1578620247.412 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T012716/Courier0000.lzma.parts
2020-01-10T01:37:27.412Z,1578620247.412 [Micromodem](INFO): Packets left to send: 1
2020-01-10T01:37:28.294Z,1578620248.294 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013727.606366,06,691,22,0112,0150,89,02,02,01,03,1,012,001,0,4,1,0,150,22.2,5.00,10,-2.00,-01,-0.05,29,10000,4000*41

2020-01-10T01:37:28.890Z,1578620248.890 [Micromodem](INFO): Outgoing frame #1, 47 bytes: F4D2175E040000005D00000400140000000000000000151D80030C5669C08C34EE702A73FD487557B21E9BB85BA000
2020-01-10T01:37:28.891Z,1578620248.891 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-10T01:37:29.815Z,1578620249.815 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-10T01:37:30.227Z,1578620250.227 [Micromodem](INFO): Nmea in: $CADRQ,013730,1,12,0,64,1*72

2020-01-10T01:37:30.228Z,1578620250.228 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F4D2175E040000005D00000400140000000000000000151D80030C5669C08C34EE702A73FD487557B21E9BB85BA000*3A

2020-01-10T01:37:30.619Z,1578620250.619 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-10T01:37:31.026Z,1578620251.026 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-10T01:37:32.256Z,1578620252.256 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-10T01:37:32.640Z,1578620252.640 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,013732.295511,3,0,200,4000,10000,1,1,12,1,1,1,3,47*4C

2020-01-10T01:37:33.447Z,1578620253.447 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T01:37:33.448Z,1578620253.448 [Micromodem](INFO): Sent 39 bytes from file Logs/20200110T012716/Courier0004.lzma.parts
2020-01-10T01:37:33.448Z,1578620253.448 [Micromodem](INFO): Packets left to send: 0
2020-01-10T01:37:33.868Z,1578620253.868 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013734.297308,06,851,23,0135,0150,89,02,02,01,03,1,012,001,0,4,1,0,150,12.5,7.00,19,-4.00,-01,-0.02,39,10000,4000*4E

2020-01-10T01:37:35.538Z,1578620255.538 [Micromodem](INFO): Outgoing frame #1, 64 bytes: A1D4175E000001005D00000400670100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T01:37:35.539Z,1578620255.539 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE36701D
2020-01-10T01:37:35.539Z,1578620255.539 [Micromodem](INFO): Outgoing frame #3, 64 bytes: E384461482A4606EFD3C14E8C4F3BFF7172AF1223B8E9A4F345B925A842E58901544EDCCE2EA9BB973CAFB29CFD498D2AC4134F8D64617AD9FB69E529765241B
2020-01-10T01:37:35.540Z,1578620255.540 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T01:37:35.667Z,1578620255.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T01:37:35.667Z,1578620255.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T01:37:35.679Z,1578620255.679 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T01:37:35.878Z,1578620255.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T01:37:35.878Z,1578620255.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T01:37:36.523Z,1578620256.523 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T01:37:36.927Z,1578620256.927 [Micromodem](INFO): Nmea in: $CADRQ,013737,1,12,0,64,1*75

2020-01-10T01:37:36.927Z,1578620256.927 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,A1D4175E000001005D00000400670100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*3B

2020-01-10T01:37:37.331Z,1578620257.331 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:37:37.735Z,1578620257.735 [Micromodem](INFO): Nmea in: $CADRQ,013738,1,12,0,64,2*79

2020-01-10T01:37:37.735Z,1578620257.735 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE36701D*4F

2020-01-10T01:37:38.239Z,1578620258.239 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:37:38.582Z,1578620258.582 [Micromodem](INFO): Nmea in: $CADRQ,013739,1,12,0,64,3*79

2020-01-10T01:37:38.583Z,1578620258.583 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E384461482A4606EFD3C14E8C4F3BFF7172AF1223B8E9A4F345B925A842E58901544EDCCE2EA9BB973CAFB29CFD498D2AC4134F8D64617AD9FB69E529765241B*4D

2020-01-10T01:37:38.979Z,1578620258.979 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:37:39.382Z,1578620259.382 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T01:37:43.023Z,1578620263.023 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T01:37:43.153Z,1578620263.153 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20200110T013425/Courier0000.lzma
2020-01-10T01:37:43.424Z,1578620263.424 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,013740.689718,3,0,200,4000,10000,1,1,12,1,3,3,3,192*72

2020-01-10T01:37:44.639Z,1578620264.639 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T01:37:45.043Z,1578620265.043 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T01:37:45.158Z,1578620265.158 [DataOverHttps](INFO): Moved sent file to Logs/20200110T013425/Courier0000.lzma.bak
2020-01-10T01:37:45.159Z,1578620265.159 [DataOverHttps](INFO): SBD MOMSN=12188684
2020-01-10T01:37:45.443Z,1578620265.443 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T01:37:45.444Z,1578620265.444 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T013425/Courier0000.lzma.parts
2020-01-10T01:37:45.444Z,1578620265.444 [Micromodem](INFO): Packets left to send: 1
2020-01-10T01:37:45.850Z,1578620265.850 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013745.241978,06,1072,30,0154,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,24.9,8.00,19,-5.00,-01,-0.03,23,10000,4000*72

2020-01-10T01:37:46.777Z,1578620266.777 [Startup:StartupSatComms:B] Stopped
2020-01-10T01:37:46.777Z,1578620266.777 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-10T01:37:46.777Z,1578620266.777 [Startup:StartupSatComms] Stopped
2020-01-10T01:37:46.777Z,1578620266.777 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T01:37:46.778Z,1578620266.778 [Startup](INFO): Completed Startup
2020-01-10T01:37:46.778Z,1578620266.778 [MissionManager](INFO): Startup is completed.
2020-01-10T01:37:46.778Z,1578620266.778 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T01:37:46.778Z,1578620266.778 [Startup] Stopped
2020-01-10T01:37:46.778Z,1578620266.778 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T01:37:46.778Z,1578620266.778 [Startup:A.GoToSurface] Stopped
2020-01-10T01:37:46.779Z,1578620266.779 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T01:37:46.942Z,1578620266.942 [MissionManager](IMPORTANT): Started mission Default
2020-01-10T01:37:46.942Z,1578620266.942 [Default] Running Loop=1
2020-01-10T01:37:46.942Z,1578620266.942 [Default](DEBUG): Aggregate::initialize Default
2020-01-10T01:37:46.942Z,1578620266.942 [Default:B.GoToSurface] Running Loop=1
2020-01-10T01:37:46.942Z,1578620266.942 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T01:37:46.943Z,1578620266.943 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T01:37:46.943Z,1578620266.943 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T01:37:46.943Z,1578620266.943 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T01:37:46.944Z,1578620266.944 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T01:37:46.944Z,1578620266.944 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T01:37:46.944Z,1578620266.944 [Default:A.Wait] Running Loop=1
2020-01-10T01:37:46.944Z,1578620266.944 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-10T01:38:00.327Z,1578620280.327 [Default:A.Wait](INFO): Done Waiting.
2020-01-10T01:38:00.327Z,1578620280.327 [Default:A.Wait] Stopped
2020-01-10T01:38:00.327Z,1578620280.327 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T01:38:00.704Z,1578620280.704 [Default:CheckIn] Running Loop=1
2020-01-10T01:38:00.704Z,1578620280.704 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-10T01:38:00.704Z,1578620280.704 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-10T01:38:01.115Z,1578620281.115 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-10T01:38:29.493Z,1578620309.493 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20200109T191734/Express0008.lzma
2020-01-10T01:38:31.508Z,1578620311.508 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Express0008.lzma.bak
2020-01-10T01:38:31.508Z,1578620311.508 [DataOverHttps](INFO): SBD MOMSN=12188696
2020-01-10T01:39:11.795Z,1578620351.795 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T01:39:11.795Z,1578620351.795 [Micromodem](INFO): Have incoming data
2020-01-10T01:39:12.189Z,1578620352.189 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T01:39:12.190Z,1578620352.190 [Micromodem](INFO): FrameSize_:-127
2020-01-10T01:39:12.190Z,1578620352.190 [Micromodem](INFO): FrameSize_:-127
2020-01-10T01:39:12.194Z,1578620352.194 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T01:39:12.195Z,1578620352.195 [Micromodem](INFO): FrameSize_:-39
2020-01-10T01:39:12.195Z,1578620352.195 [Micromodem](INFO): FrameSize_:-39
2020-01-10T01:39:12.196Z,1578620352.196 [Micromodem](INFO): Deserialize. FrameCount:2
2020-01-10T01:39:12.196Z,1578620352.196 [Micromodem](INFO): Outer loop. Size:-39
2020-01-10T01:39:12.245Z,1578620352.245 [Micromodem](INFO): Outer loop. Size:-39
2020-01-10T01:39:12.246Z,1578620352.246 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0
2020-01-10T01:39:12.247Z,1578620352.247 [Micromodem](CRITICAL): Failed to parse uplink message:
2020-01-10T01:39:12.358Z,1578620352.358 [CBIT](INFO): Critical error at 20200110T013912
2020-01-10T01:39:12.587Z,1578620352.587 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110013910.691466,06,1914,32,0142,0150,29,01,01,00,03,1,012,001,0,3,2,0,150,27.5,8.00,00,-5.00,-01,-0.02,19,10000,4000*77

2020-01-10T01:39:27.957Z,1578620367.957 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T01:39:27.957Z,1578620367.957 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010918521789,35.0, -0.1,   0.0, 0
2020-01-10T01:40:36.262Z,1578620436.262 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-10T01:40:36.262Z,1578620436.262 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T01:40:36.272Z,1578620436.272 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T01:40:36.699Z,1578620436.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T01:40:36.699Z,1578620436.699 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-10T01:41:33.620Z,1578620493.620 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T01:41:33.620Z,1578620493.620 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010918542389,35,   0.0,1448.9,  0
2020-01-10T01:41:44.112Z,1578620504.112 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-10T01:41:44.131Z,1578620504.131 [BPC1](INFO): Received data from all battery sticks.
2020-01-10T01:42:36.201Z,1578620556.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014235.00,A,3648.16066,N,12147.28336,W,0.194,0.00,100120,,,A*70
2020-01-10T01:42:36.204Z,1578620556.204 [NAL9602](INFO): GPS fix at 20200110T014235: (36.802678, -121.788056)
2020-01-10T01:42:36.276Z,1578620556.276 [Default:CheckIn:Read_GPS] Stopped
2020-01-10T01:42:36.293Z,1578620556.293 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-10T01:42:36.716Z,1578620556.716 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T01:42:39.482Z,1578620559.482 [Micromodem](INFO): Outgoing frame #1, 64 bytes: A1D4175E040002005D00000400430500000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60
2020-01-10T01:42:39.483Z,1578620559.483 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 3498F14214C868A5E39E72973665B2E27B6BB4517DCA5863FC5D533B91B867A99157927473A10F3EA469BB69D3D46B50FE5F53EA63E2294B392133FD008E6045
2020-01-10T01:42:39.483Z,1578620559.483 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 429EDFB40A59E6BE4223F8288CADBD2C28583D73035AB2189A6391CD12EBFA4C048E908098837F537797387A401E3BD4B4FE6080B647B23AAEB5B196771B2B78
2020-01-10T01:42:39.484Z,1578620559.484 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T01:42:39.850Z,1578620559.850 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T01:42:40.183Z,1578620560.183 [Micromodem](INFO): Nmea in: $CADRQ,014241,1,12,0,64,1*76

2020-01-10T01:42:40.183Z,1578620560.183 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,A1D4175E040002005D00000400430500000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60*4B

2020-01-10T01:42:40.322Z,1578620560.322 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-10T01:42:40.322Z,1578620560.322 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00 +0.00,   0.00,470.99
2020-01-10T01:42:40.574Z,1578620560.574 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:42:40.993Z,1578620560.993 [Micromodem](INFO): Nmea in: $CADRQ,014242,1,12,0,64,2*76

2020-01-10T01:42:40.994Z,1578620560.994 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3498F14214C868A5E39E72973665B2E27B6BB4517DCA5863FC5D533B91B867A99157927473A10F3EA469BB69D3D46B50FE5F53EA63E2294B392133FD008E6045*43

2020-01-10T01:42:41.382Z,1578620561.382 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:42:41.786Z,1578620561.786 [Micromodem](INFO): Nmea in: $CADRQ,014243,1,12,0,64,3*76

2020-01-10T01:42:41.787Z,1578620561.787 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,429EDFB40A59E6BE4223F8288CADBD2C28583D73035AB2189A6391CD12EBFA4C048E908098837F537797387A401E3BD4B4FE6080B647B23AAEB5B196771B2B78*40

2020-01-10T01:42:42.206Z,1578620562.206 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T01:42:42.600Z,1578620562.600 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T01:42:46.243Z,1578620566.243 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T01:42:46.636Z,1578620566.636 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,014243.891844,3,0,200,4000,10000,1,1,12,1,3,3,3,192*72

2020-01-10T01:42:47.620Z,1578620567.620 [DataOverHttps](INFO): Sending 462 bytes from file Logs/20200110T013425/Courier0004.lzma
2020-01-10T01:42:47.861Z,1578620567.861 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T01:42:48.251Z,1578620568.251 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T01:42:48.656Z,1578620568.656 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T01:42:48.657Z,1578620568.657 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T013425/Courier0004.lzma.parts
2020-01-10T01:42:48.657Z,1578620568.657 [Micromodem](INFO): Packets left to send: 2
2020-01-10T01:42:49.066Z,1578620569.066 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110014248.445729,06,577,27,0091,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.4,7.00,16,-4.00,-01,-0.04,23,10000,4000*40

2020-01-10T01:42:49.658Z,1578620569.658 [DataOverHttps](INFO): Moved sent file to Logs/20200110T013425/Courier0004.lzma.bak
2020-01-10T01:42:49.658Z,1578620569.658 [DataOverHttps](INFO): SBD MOMSN=12188705
2020-01-10T01:42:49.767Z,1578620569.767 [Default:CheckIn:Read_Iridium] Stopped
2020-01-10T01:42:49.767Z,1578620569.767 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-10T01:42:49.767Z,1578620569.767 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-10T01:43:10.027Z,1578620590.027 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-10T01:43:49.642Z,1578620629.642 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T01:43:49.643Z,1578620629.643 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010918563989,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T01:44:29.638Z,1578620669.638 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T01:44:29.639Z,1578620669.639 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010918571989,35,   0.0,1448.9,  0
2020-01-10T01:45:09.635Z,1578620709.635 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T01:45:09.635Z,1578620709.635 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010918575989,35.0, -0.1,448.9,  0
2020-01-10T01:45:15.699Z,1578620715.699 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-10T01:45:15.700Z,1578620715.700 [RDI_Pathfinder](ERROR): Failed to parse:
:RA   0.00,   0.00,   0.00,   0.00
2020-01-10T01:46:26.428Z,1578620786.428 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-10T01:46:26.437Z,1578620786.437 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3768,-32768,V
2020-01-10T01:47:02.487Z,1578620822.487 [CommandLine](IMPORTANT): got command restart application
2020-01-10T01:47:03.493Z,1578620823.493 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:03.493Z,1578620823.493 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:03.593Z,1578620823.593 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-10T01:47:03.593Z,1578620823.593 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:03.594Z,1578620823.594 [CommandLine](INFO): Join timeout helper Thread ID is 6458
2020-01-10T01:47:03.614Z,1578620823.614 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T01:47:03.614Z,1578620823.614 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:03.615Z,1578620823.615 [NavChartDb](INFO): Join timeout helper Thread ID is 6459
2020-01-10T01:47:03.945Z,1578620823.945 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:03.945Z,1578620823.945 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:03.949Z,1578620823.949 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T01:47:03.949Z,1578620823.949 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:03.949Z,1578620823.949 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 6460
2020-01-10T01:47:04.189Z,1578620824.189 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:04.189Z,1578620824.189 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T01:47:04.190Z,1578620824.190 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:04.201Z,1578620824.201 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T01:47:04.201Z,1578620824.201 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:04.201Z,1578620824.201 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6461
2020-01-10T01:47:04.317Z,1578620824.317 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:04.317Z,1578620824.317 [CTD_NeilBrown](INFO): Powering down
2020-01-10T01:47:04.329Z,1578620824.329 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:04.333Z,1578620824.333 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T01:47:04.333Z,1578620824.333 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:04.334Z,1578620824.334 [Radio_Surface](INFO): Join timeout helper Thread ID is 6462
2020-01-10T01:47:04.473Z,1578620824.473 [Radio_Surface](INFO): Powering down
2020-01-10T01:47:04.474Z,1578620824.474 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:04.474Z,1578620824.474 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:04.490Z,1578620824.490 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T01:47:04.490Z,1578620824.490 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:04.490Z,1578620824.490 [DataOverHttps](INFO): Join timeout helper Thread ID is 6463
2020-01-10T01:47:06.637Z,1578620826.637 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:06.640Z,1578620826.640 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:06.642Z,1578620826.642 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T01:47:06.642Z,1578620826.642 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:06.642Z,1578620826.642 [logger](INFO): Join timeout helper Thread ID is 6464
2020-01-10T01:47:06.657Z,1578620826.657 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:06.657Z,1578620826.657 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:06.662Z,1578620826.662 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T01:47:06.662Z,1578620826.662 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:06.662Z,1578620826.662 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T01:47:06.662Z,1578620826.662 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:06.662Z,1578620826.662 [controlThread](INFO): Join timeout helper Thread ID is 6465
2020-01-10T01:47:06.753Z,1578620826.753 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T01:47:06.753Z,1578620826.753 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T01:47:06.754Z,1578620826.754 [AHRS_M2](INFO): Powering down
2020-01-10T01:47:06.825Z,1578620826.825 [DDM](INFO): Powering down
2020-01-10T01:47:06.917Z,1578620826.917 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T01:47:07.009Z,1578620827.009 [Micromodem](INFO): Powering down
2020-01-10T01:47:07.105Z,1578620827.105 [NAL9602](INFO): Powering down
2020-01-10T01:47:07.177Z,1578620827.177 [RDI_Pathfinder](INFO): Powering down
2020-01-10T01:47:07.178Z,1578620827.178 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T01:47:07.179Z,1578620827.179 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T01:47:07.180Z,1578620827.180 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T01:47:07.180Z,1578620827.180 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T01:47:07.180Z,1578620827.180 [Default] Stopped
2020-01-10T01:47:07.180Z,1578620827.180 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-10T01:47:07.180Z,1578620827.180 [Default:B.GoToSurface] Stopped
2020-01-10T01:47:07.181Z,1578620827.181 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T01:47:07.181Z,1578620827.181 [Default:CheckIn] Stopped
2020-01-10T01:47:07.181Z,1578620827.181 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-10T01:47:07.181Z,1578620827.181 [Default:CheckIn:C.Wait] Stopped
2020-01-10T01:47:07.181Z,1578620827.181 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T01:47:07.183Z,1578620827.183 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T01:47:07.184Z,1578620827.184 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T01:47:07.184Z,1578620827.184 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T01:47:07.184Z,1578620827.184 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T01:47:07.184Z,1578620827.184 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T01:47:07.185Z,1578620827.185 [BuoyancyServo](INFO): Powering down
2020-01-10T01:47:07.197Z,1578620827.197 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T01:47:07.197Z,1578620827.197 [ElevatorServo](INFO): Powering down
2020-01-10T01:47:07.198Z,1578620827.198 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T01:47:07.198Z,1578620827.198 [MassServo](INFO): Powering down
2020-01-10T01:47:07.199Z,1578620827.199 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T01:47:07.199Z,1578620827.199 [RudderServo](INFO): Powering down
2020-01-10T01:47:07.199Z,1578620827.199 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T01:47:07.200Z,1578620827.200 [ThrusterServo](INFO): Powering down
2020-01-10T01:47:07.200Z,1578620827.200 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T01:47:07.201Z,1578620827.201 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T01:47:07.201Z,1578620827.201 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T01:47:07.201Z,1578620827.201 [CBIT](DEBUG): Powering off loads.
2020-01-10T01:47:07.213Z,1578620827.213 [CBIT](DEBUG): Disabling WDT.
2020-01-10T01:47:07.225Z,1578620827.225 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T01:47:07.226Z,1578620827.226 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:07.281Z,1578620827.281 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:07.292Z,1578620827.292 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:07.339Z,1578620827.339 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:07.342Z,1578620827.342 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:07.402Z,1578620827.402 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T01:47:07.475Z,1578620827.475 [logger ThreadHandler](INFO): Thread cancelled.