2019-01-08T18:40:59.720Z,1546972859.720 [Supervisor](DEBUG): Initializing supervisor.
2019-01-08T18:40:59.723Z,1546972859.723 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-01-08T18:40:59.724Z,1546972859.724 [SyncHandler](INFO): Protected caller Thread ID is 1628
2019-01-08T18:40:59.724Z,1546972859.724 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-01-08T18:40:59.725Z,1546972859.725 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-01-08T18:40:59.726Z,1546972859.726 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1629
2019-01-08T18:40:59.728Z,1546972859.728 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-01-08T18:40:59.741Z,1546972859.741 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-01-08T18:40:59.742Z,1546972859.742 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-01-08T18:40:59.742Z,1546972859.742 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1630
2019-01-08T18:40:59.743Z,1546972859.743 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-01-08T18:40:59.744Z,1546972859.744 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-01-08T18:40:59.744Z,1546972859.744 [logger ThreadHandler](INFO): Protected caller Thread ID is 1631
2019-01-08T18:40:59.746Z,1546972859.746 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-01-08T18:40:59.747Z,1546972859.747 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-01-08T18:40:59.748Z,1546972859.748 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-01-08T18:41:00.087Z,1546972860.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-01-08T18:41:00.088Z,1546972860.088 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-01-08T18:41:00.562Z,1546972860.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-01-08T18:41:00.562Z,1546972860.562 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-01-08T18:41:00.932Z,1546972860.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-01-08T18:41:00.933Z,1546972860.933 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-01-08T18:41:01.138Z,1546972861.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-01-08T18:41:01.138Z,1546972861.138 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-01-08T18:41:01.615Z,1546972861.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-01-08T18:41:01.616Z,1546972861.616 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-01-08T18:41:02.189Z,1546972862.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-01-08T18:41:02.189Z,1546972862.189 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-01-08T18:41:02.297Z,1546972862.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-01-08T18:41:02.298Z,1546972862.298 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-01-08T18:41:02.513Z,1546972862.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-01-08T18:41:02.514Z,1546972862.514 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-01-08T18:41:02.662Z,1546972862.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-01-08T18:41:02.663Z,1546972862.663 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-01-08T18:41:02.745Z,1546972862.745 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-01-08T18:41:02.973Z,1546972862.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-01-08T18:41:02.974Z,1546972862.974 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-01-08T18:41:03.076Z,1546972863.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-01-08T18:41:03.077Z,1546972863.077 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-01-08T18:41:03.175Z,1546972863.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-01-08T18:41:03.176Z,1546972863.176 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-01-08T18:41:03.331Z,1546972863.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-01-08T18:41:03.331Z,1546972863.331 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-01-08T18:41:03.667Z,1546972863.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-01-08T18:41:03.669Z,1546972863.669 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-01-08T18:41:03.670Z,1546972863.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-01-08T18:41:03.808Z,1546972863.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-01-08T18:41:04.036Z,1546972864.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-01-08T18:41:04.036Z,1546972864.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-01-08T18:41:04.220Z,1546972864.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-01-08T18:41:04.324Z,1546972864.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-01-08T18:41:04.425Z,1546972864.425 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-01-08T18:41:04.632Z,1546972864.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-01-08T18:41:04.718Z,1546972864.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-01-08T18:41:04.819Z,1546972864.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-01-08T18:41:04.915Z,1546972864.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-01-08T18:41:04.999Z,1546972864.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-01-08T18:41:05.113Z,1546972865.113 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-01-08T18:41:05.114Z,1546972865.114 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-01-08T18:41:05.118Z,1546972865.118 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-01-08T18:41:05.245Z,1546972865.245 [SBIT](DEBUG): Construct Startup Built In Test.
2019-01-08T18:41:05.276Z,1546972865.276 [SBIT] Loaded
2019-01-08T18:41:05.276Z,1546972865.276 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-01-08T18:41:05.277Z,1546972865.277 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-01-08T18:41:05.304Z,1546972865.304 [IBIT] Loaded
2019-01-08T18:41:05.305Z,1546972865.305 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-01-08T18:41:05.308Z,1546972865.308 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-01-08T18:41:05.461Z,1546972865.461 [CBIT] Loaded
2019-01-08T18:41:05.461Z,1546972865.461 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-01-08T18:41:05.462Z,1546972865.462 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-01-08T18:41:05.462Z,1546972865.462 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-01-08T18:41:05.576Z,1546972865.576 [BuoyancyServo] Loaded
2019-01-08T18:41:05.576Z,1546972865.576 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-01-08T18:41:05.591Z,1546972865.591 [ElevatorServo] Loaded
2019-01-08T18:41:05.591Z,1546972865.591 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-01-08T18:41:05.605Z,1546972865.605 [MassServo] Loaded
2019-01-08T18:41:05.606Z,1546972865.606 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-01-08T18:41:05.621Z,1546972865.621 [RudderServo] Loaded
2019-01-08T18:41:05.621Z,1546972865.621 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-01-08T18:41:05.636Z,1546972865.636 [ThrusterServo] Loaded
2019-01-08T18:41:05.636Z,1546972865.636 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-01-08T18:41:05.636Z,1546972865.636 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-01-08T18:41:05.637Z,1546972865.637 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-01-08T18:41:05.736Z,1546972865.736 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-01-08T18:41:05.736Z,1546972865.736 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-01-08T18:41:05.790Z,1546972865.790 [DeadReckonUsingSpeedCalculator] Loaded
2019-01-08T18:41:05.790Z,1546972865.790 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-01-08T18:41:05.807Z,1546972865.807 [NavChart] Loaded
2019-01-08T18:41:05.807Z,1546972865.807 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-01-08T18:41:05.811Z,1546972865.811 [UniversalFixResidualReporter] Loaded
2019-01-08T18:41:05.811Z,1546972865.811 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-01-08T18:41:05.812Z,1546972865.812 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-01-08T18:41:05.812Z,1546972865.812 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-01-08T18:41:05.825Z,1546972865.825 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-01-08T18:41:05.826Z,1546972865.826 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-01-08T18:41:06.067Z,1546972866.067 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-01-08T18:41:06.067Z,1546972866.067 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-01-08T18:41:06.365Z,1546972866.365 [AHRS_M2] Loaded
2019-01-08T18:41:06.365Z,1546972866.365 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-01-08T18:41:06.437Z,1546972866.437 [DataOverHttps] Loaded
2019-01-08T18:41:06.438Z,1546972866.438 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-01-08T18:41:06.451Z,1546972866.451 [Depth_Keller] Loaded
2019-01-08T18:41:06.451Z,1546972866.451 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-01-08T18:41:06.456Z,1546972866.456 [DropWeight] Loaded
2019-01-08T18:41:06.456Z,1546972866.456 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-01-08T18:41:06.546Z,1546972866.546 [NAL9602] Loaded
2019-01-08T18:41:06.546Z,1546972866.546 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-01-08T18:41:06.551Z,1546972866.551 [Onboard] Loaded
2019-01-08T18:41:06.551Z,1546972866.551 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-01-08T18:41:06.558Z,1546972866.558 [Radio_Surface] Loaded
2019-01-08T18:41:06.559Z,1546972866.559 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-01-08T18:41:06.560Z,1546972866.560 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0
2019-01-08T18:41:06.560Z,1546972866.560 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1710
2019-01-08T18:41:06.588Z,1546972866.588 [RDI_Pathfinder] Loaded
2019-01-08T18:41:06.589Z,1546972866.589 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-01-08T18:41:06.610Z,1546972866.610 [RDI_PathfinderUp] Loaded
2019-01-08T18:41:06.611Z,1546972866.611 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-01-08T18:41:06.617Z,1546972866.617 [SCPI] Loaded
2019-01-08T18:41:06.617Z,1546972866.617 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2019-01-08T18:41:06.618Z,1546972866.618 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-01-08T18:41:06.619Z,1546972866.619 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-01-08T18:41:06.686Z,1546972866.686 [VerticalControl](DEBUG): Construct VerticalControl.
2019-01-08T18:41:06.775Z,1546972866.775 [VerticalControl] Loaded
2019-01-08T18:41:06.775Z,1546972866.775 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-01-08T18:41:06.776Z,1546972866.776 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-01-08T18:41:06.833Z,1546972866.833 [HorizontalControl] Loaded
2019-01-08T18:41:06.834Z,1546972866.834 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-01-08T18:41:06.834Z,1546972866.834 [SpeedControl](DEBUG): Construct SpeedControl.
2019-01-08T18:41:06.836Z,1546972866.836 [SpeedControl] Loaded
2019-01-08T18:41:06.837Z,1546972866.837 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-01-08T18:41:06.837Z,1546972866.837 [LoopControl](DEBUG): Construct LoopControl.
2019-01-08T18:41:06.838Z,1546972866.838 [LoopControl] Loaded
2019-01-08T18:41:06.838Z,1546972866.838 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-01-08T18:41:06.839Z,1546972866.839 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-01-08T18:41:06.839Z,1546972866.839 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-01-08T18:41:06.938Z,1546972866.938 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-01-08T18:41:06.939Z,1546972866.939 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-01-08T18:41:07.096Z,1546972867.096 [Aanderaa_O2] Loaded
2019-01-08T18:41:07.096Z,1546972867.096 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-01-08T18:41:07.180Z,1546972867.180 [CTD_NeilBrown] Loaded
2019-01-08T18:41:07.180Z,1546972867.180 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-01-08T18:41:07.181Z,1546972867.181 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409374E0
2019-01-08T18:41:07.182Z,1546972867.182 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1711
2019-01-08T18:41:07.226Z,1546972867.226 [WetLabsSeaOWL_UV_A] Loaded
2019-01-08T18:41:07.227Z,1546972867.227 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-01-08T18:41:07.228Z,1546972867.228 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409674E0
2019-01-08T18:41:07.228Z,1546972867.228 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1712
2019-01-08T18:41:07.229Z,1546972867.229 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-01-08T18:41:07.229Z,1546972867.229 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-01-08T18:41:07.345Z,1546972867.345 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-01-08T18:41:07.346Z,1546972867.346 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-01-08T18:41:07.396Z,1546972867.396 [DepthRateCalculator] Loaded
2019-01-08T18:41:07.397Z,1546972867.397 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-01-08T18:41:07.402Z,1546972867.402 [PitchRateCalculator] Loaded
2019-01-08T18:41:07.402Z,1546972867.402 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-01-08T18:41:07.414Z,1546972867.414 [SpeedCalculator] Loaded
2019-01-08T18:41:07.415Z,1546972867.415 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-01-08T18:41:07.436Z,1546972867.436 [TempGradientCalculator] Loaded
2019-01-08T18:41:07.436Z,1546972867.436 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-01-08T18:41:07.441Z,1546972867.441 [YawRateCalculator] Loaded
2019-01-08T18:41:07.442Z,1546972867.442 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-01-08T18:41:07.504Z,1546972867.504 [ElevatorOffsetCalculator] Loaded
2019-01-08T18:41:07.504Z,1546972867.504 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-01-08T18:41:07.504Z,1546972867.504 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-01-08T18:41:07.505Z,1546972867.505 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-01-08T18:41:07.573Z,1546972867.573 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-01-08T18:41:07.577Z,1546972867.577 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-01-08T18:41:07.578Z,1546972867.578 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-01-08T18:41:07.592Z,1546972867.592 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-01-08T18:41:07.607Z,1546972867.607 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2019-01-08T18:41:07.608Z,1546972867.608 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1713
2019-01-08T18:41:07.613Z,1546972867.613 [Supervisor](INFO): Main Thread ID is 996
2019-01-08T18:41:07.613Z,1546972867.613 [Supervisor](DEBUG): Running supervisor.
2019-01-08T18:41:07.614Z,1546972867.614 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1714
2019-01-08T18:41:07.616Z,1546972867.616 [controlThread ThreadHandler](INFO): Handler Thread ID is 1715
2019-01-08T18:41:07.616Z,1546972867.616 [controlThread](DEBUG): Initializing ControlThread
2019-01-08T18:41:07.617Z,1546972867.617 [SBIT](INFO): Initialize SBIT Component.
2019-01-08T18:41:07.618Z,1546972867.618 [SBIT](IMPORTANT): git: 2018-11-05-2-g6a71760
2019-01-08T18:41:07.618Z,1546972867.618 [SBIT](INFO): git hash: 6a71760e9f7f683776be04bba2e56bc8c8a5e6e9
2019-01-08T18:41:07.618Z,1546972867.618 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-01-08T18:41:07.619Z,1546972867.619 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-01-08T18:41:07.620Z,1546972867.620 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-01-08T18:41:07.620Z,1546972867.620 [IBIT](INFO): Initialize IBIT Component.
2019-01-08T18:41:07.621Z,1546972867.621 [CBIT](DEBUG): Initialize CBIT Component.
2019-01-08T18:41:07.621Z,1546972867.621 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-01-08T18:41:07.622Z,1546972867.622 [logger ThreadHandler](INFO): Handler Thread ID is 1716
2019-01-08T18:41:07.653Z,1546972867.653 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1717
2019-01-08T18:41:07.659Z,1546972867.659 [Radio_Surface](INFO): Powering up
2019-01-08T18:41:07.665Z,1546972867.665 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1718
2019-01-08T18:41:07.666Z,1546972867.666 [CTD_NeilBrown](INFO): Powering down
2019-01-08T18:41:07.730Z,1546972867.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-08T18:41:07.730Z,1546972867.730 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:41:07.731Z,1546972867.731 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:41:07.731Z,1546972867.731 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-08T18:41:07.732Z,1546972867.732 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:41:07.732Z,1546972867.732 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:41:07.732Z,1546972867.732 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-01-08T18:41:07.733Z,1546972867.733 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-01-08T18:41:07.749Z,1546972867.749 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1719
2019-01-08T18:41:07.750Z,1546972867.750 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-08T18:41:07.751Z,1546972867.751 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1720
2019-01-08T18:41:07.755Z,1546972867.755 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-01-08T18:41:07.755Z,1546972867.755 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-01-08T18:41:07.755Z,1546972867.755 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-01-08T18:41:07.755Z,1546972867.755 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-01-08T18:41:07.756Z,1546972867.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-01-08T18:41:07.756Z,1546972867.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-01-08T18:41:07.756Z,1546972867.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-01-08T18:41:07.756Z,1546972867.756 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-01-08T18:41:07.757Z,1546972867.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-01-08T18:41:07.757Z,1546972867.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-01-08T18:41:07.757Z,1546972867.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-01-08T18:41:07.757Z,1546972867.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-01-08T18:41:07.758Z,1546972867.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-01-08T18:41:07.758Z,1546972867.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-01-08T18:41:07.758Z,1546972867.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-01-08T18:41:07.758Z,1546972867.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-01-08T18:41:07.876Z,1546972867.876 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-01-08T18:41:07.890Z,1546972867.890 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-01-08T18:41:07.891Z,1546972867.891 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-01-08T18:41:07.892Z,1546972867.892 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-01-08T18:41:07.913Z,1546972867.913 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-08T18:41:07.914Z,1546972867.914 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-01-08T18:41:07.914Z,1546972867.914 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-01-08T18:41:07.915Z,1546972867.915 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-01-08T18:41:07.916Z,1546972867.916 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-01-08T18:41:07.916Z,1546972867.916 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-01-08T18:41:07.919Z,1546972867.919 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-01-08T18:41:07.958Z,1546972867.958 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-08T18:41:08.069Z,1546972868.069 [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>


2019-01-08T18:41:08.070Z,1546972868.070 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-01-08T18:41:08.140Z,1546972868.140 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-01-08T18:41:08.141Z,1546972868.141 [Default:A.Wait](DEBUG): Construct Wait.
2019-01-08T18:41:08.143Z,1546972868.143 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-08T18:41:08.191Z,1546972868.191 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-01-08T18:41:08.193Z,1546972868.193 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-01-08T18:41:08.236Z,1546972868.236 [Default:E.Execute](DEBUG): Construct Execute.
2019-01-08T18:41:08.252Z,1546972868.252 [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>


2019-01-08T18:41:08.258Z,1546972868.258 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-01-08T18:41:08.351Z,1546972868.351 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:41:08.400Z,1546972868.400 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-01-08T18:41:08.428Z,1546972868.428 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-01-08T18:41:08.543Z,1546972868.543 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:41:08.781Z,1546972868.781 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:41:08.789Z,1546972868.789 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-08T18:41:08.803Z,1546972868.803 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:41:08.809Z,1546972868.809 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-08T18:41:08.823Z,1546972868.823 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:41:08.829Z,1546972868.829 [MassServo](DEBUG): Initializing MassServo.
2019-01-08T18:41:08.835Z,1546972868.835 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:41:08.845Z,1546972868.845 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-08T18:41:08.858Z,1546972868.858 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:41:08.866Z,1546972868.866 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-01-08T18:41:09.021Z,1546972869.021 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-01-08T18:41:09.022Z,1546972869.022 [DropWeight] Hardware Fault, FailCount= 1
2019-01-08T18:41:09.022Z,1546972869.022 [DropWeight](ERROR): Hardware Fault
2019-01-08T18:41:09.113Z,1546972869.113 [RDI_PathfinderUp](INFO): Powering down
2019-01-08T18:41:09.150Z,1546972869.150 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:41:09.178Z,1546972869.178 [CommandLine](FAULT): Scheduling is paused
2019-01-08T18:41:09.178Z,1546972869.178 [CBIT](INFO): Critical error at 20190108T184109
2019-01-08T18:41:09.179Z,1546972869.179 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-01-08T18:41:09.181Z,1546972869.181 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-01-08T18:41:09.182Z,1546972869.182 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-01-08T18:41:09.373Z,1546972869.373 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:41:09.721Z,1546972869.721 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-01-08T18:41:09.721Z,1546972869.721 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-01-08T18:41:09.721Z,1546972869.721 [BuoyancyServo] Communications Fault, FailCount= 1
2019-01-08T18:41:09.721Z,1546972869.721 [BuoyancyServo](ERROR): Communications Fault
2019-01-08T18:41:09.927Z,1546972869.927 [CBIT](INFO): Critical error at 20190108T184109
2019-01-08T18:41:09.929Z,1546972869.929 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-01-08T18:41:12.001Z,1546972872.001 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2019-01-08T18:41:12.110Z,1546972872.110 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-01-08T18:41:12.110Z,1546972872.110 [BuoyancyServo] No Fault, FailCount= 1
2019-01-08T18:41:12.399Z,1546972872.399 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:41:12.521Z,1546972872.521 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-08T18:41:13.609Z,1546972873.609 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2019-01-08T18:41:17.730Z,1546972877.730 [CBIT](CRITICAL): Environmental Failure. Press:14.839540 PSI. Humidity:38%. Temp:25 C. ABORTING MISSION
2019-01-08T18:41:17.996Z,1546972877.996 [CBIT](INFO): Critical error at 20190108T184117
2019-01-08T18:41:18.770Z,1546972878.770 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:41:18.770Z,1546972878.770 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-08T18:41:18.770Z,1546972878.770 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:41:18.959Z,1546972878.959 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:41:19.289Z,1546972879.289 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:41:20.004Z,1546972880.004 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-08T18:41:20.004Z,1546972880.004 [Aanderaa_O2] No Fault, FailCount= 1
2019-01-08T18:41:21.949Z,1546972881.949 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-01-08T18:41:21.949Z,1546972881.949 [AHRS_M2] Data Fault, FailCount= 1
2019-01-08T18:41:21.949Z,1546972881.949 [AHRS_M2](ERROR): Data Fault
2019-01-08T18:41:22.032Z,1546972882.032 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-01-08T18:41:22.338Z,1546972882.338 [AHRS_M2](INFO): Powering down
2019-01-08T18:41:25.992Z,1546972885.992 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:41:26.001Z,1546972886.001 [AHRS_M2] No Fault, FailCount= 1
2019-01-08T18:41:26.338Z,1546972886.338 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:41:29.861Z,1546972889.861 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:30.277Z,1546972890.277 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:30.693Z,1546972890.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:30.715Z,1546972890.715 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:41:30.715Z,1546972890.715 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-01-08T18:41:30.715Z,1546972890.715 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:41:30.785Z,1546972890.785 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:41:31.121Z,1546972891.121 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:31.141Z,1546972891.141 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:41:31.141Z,1546972891.141 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-01-08T18:41:31.141Z,1546972891.141 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:41:31.261Z,1546972891.261 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:41:31.293Z,1546972891.293 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:41:31.657Z,1546972891.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:31.757Z,1546972891.757 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:41:32.157Z,1546972892.157 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:32.240Z,1546972892.240 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-01-08T18:41:32.240Z,1546972892.240 [Aanderaa_O2] No Fault, FailCount= 2
2019-01-08T18:41:32.573Z,1546972892.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:32.654Z,1546972892.654 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-01-08T18:41:32.654Z,1546972892.654 [RDI_Pathfinder] No Fault, FailCount= 1
2019-01-08T18:41:33.021Z,1546972893.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:33.465Z,1546972893.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:33.873Z,1546972893.873 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:34.297Z,1546972894.297 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:34.737Z,1546972894.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:35.149Z,1546972895.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:35.577Z,1546972895.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:35.993Z,1546972895.993 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:36.064Z,1546972896.064 [SBIT](IMPORTANT): Beginning Startup BIT
2019-01-08T18:41:36.102Z,1546972896.102 [CBIT](IMPORTANT): Beginning ground fault scan
2019-01-08T18:41:36.441Z,1546972896.441 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:36.441Z,1546972896.441 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:41:36.441Z,1546972896.441 [AHRS_M2] Communications Fault, FailCount= 2
2019-01-08T18:41:36.441Z,1546972896.441 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:41:36.603Z,1546972896.603 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:41:36.620Z,1546972896.620 [AHRS_M2](INFO): Powering down
2019-01-08T18:41:36.691Z,1546972896.691 [NAL9602](INFO): Powering up NAL9602
2019-01-08T18:41:40.486Z,1546972900.486 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:41:40.486Z,1546972900.486 [AHRS_M2] No Fault, FailCount= 2
2019-01-08T18:41:40.729Z,1546972900.729 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:41:42.750Z,1546972902.750 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:41:42.750Z,1546972902.750 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-01-08T18:41:42.750Z,1546972902.750 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:41:42.859Z,1546972902.859 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-01-08T18:41:42.859Z,1546972902.859 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-01-08T18:41:43.265Z,1546972903.265 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:41:44.253Z,1546972904.253 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:44.705Z,1546972904.705 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:45.233Z,1546972905.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:45.697Z,1546972905.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:46.133Z,1546972906.133 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:46.681Z,1546972906.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:47.137Z,1546972907.137 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:47.577Z,1546972907.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:48.217Z,1546972908.217 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:48.231Z,1546972908.231 [NAL9602](INFO): NAL9602 initialized
2019-01-08T18:41:48.319Z,1546972908.319 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.022763
CHAN A1 (24V): -0.025964
CHAN A2 (12V): -0.007283
CHAN A3 (5V): -0.002027
CHAN B0 (3.3V): 0.000261
CHAN B1 (3.15aV): -0.000151
CHAN B2 (3.15bV): -0.000116
CHAN B3 (GND): 0.002213
OPEN: -0.000197
Full Scale Calc: 4.765 mA, -1.589 mA
2019-01-08T18:41:48.665Z,1546972908.665 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:49.093Z,1546972909.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:49.513Z,1546972909.513 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:49.961Z,1546972909.961 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:50.409Z,1546972910.409 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:50.842Z,1546972910.842 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:50.842Z,1546972910.842 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:41:50.842Z,1546972910.842 [AHRS_M2] Communications Fault, FailCount= 3
2019-01-08T18:41:50.842Z,1546972910.842 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:41:50.938Z,1546972910.938 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:41:50.949Z,1546972910.949 [AHRS_M2](INFO): Powering down
2019-01-08T18:41:54.982Z,1546972914.982 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:41:54.982Z,1546972914.982 [AHRS_M2] No Fault, FailCount= 3
2019-01-08T18:41:55.213Z,1546972915.213 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:41:55.633Z,1546972915.633 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:41:55.634Z,1546972915.634 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-01-08T18:41:55.634Z,1546972915.634 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:41:55.750Z,1546972915.750 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:41:56.105Z,1546972916.105 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:41:56.995Z,1546972916.995 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-01-08T18:41:56.995Z,1546972916.995 [RDI_Pathfinder] No Fault, FailCount= 2
2019-01-08T18:41:58.733Z,1546972918.733 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:59.181Z,1546972919.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:41:59.621Z,1546972919.621 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:00.101Z,1546972920.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:00.525Z,1546972920.525 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:00.941Z,1546972920.941 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:01.361Z,1546972921.361 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:01.813Z,1546972921.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:02.249Z,1546972922.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:02.693Z,1546972922.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:03.133Z,1546972923.133 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:03.545Z,1546972923.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:03.973Z,1546972923.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:04.409Z,1546972924.409 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:04.841Z,1546972924.841 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:05.281Z,1546972925.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:05.281Z,1546972925.281 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:42:05.281Z,1546972925.281 [AHRS_M2] Communications Fault, FailCount= 4
2019-01-08T18:42:05.281Z,1546972925.281 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:42:05.375Z,1546972925.375 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:42:05.382Z,1546972925.382 [AHRS_M2](INFO): Powering down
2019-01-08T18:42:09.042Z,1546972929.042 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-01-08T18:42:09.042Z,1546972929.042 [AHRS_M2] No Fault, FailCount= 4
2019-01-08T18:42:09.386Z,1546972929.386 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:42:12.909Z,1546972932.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:13.317Z,1546972933.317 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:13.717Z,1546972933.717 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:14.117Z,1546972934.117 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:14.533Z,1546972934.533 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:14.949Z,1546972934.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:15.350Z,1546972935.350 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:15.725Z,1546972935.725 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:16.137Z,1546972936.137 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:16.545Z,1546972936.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:16.950Z,1546972936.950 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:17.369Z,1546972937.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:17.777Z,1546972937.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:18.341Z,1546972938.341 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:19.181Z,1546972939.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:19.657Z,1546972939.657 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:42:19.657Z,1546972939.657 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:42:19.657Z,1546972939.657 [AHRS_M2] Communications Fault, FailCount= 5
2019-01-08T18:42:19.657Z,1546972939.657 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:42:19.707Z,1546972939.707 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:42:19.707Z,1546972939.707 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-01-08T18:42:19.707Z,1546972939.707 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:42:19.842Z,1546972939.842 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-01-08T18:42:19.842Z,1546972939.842 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-01-08T18:42:19.843Z,1546972939.843 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:42:19.843Z,1546972939.843 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-01-08T18:42:19.850Z,1546972939.850 [AHRS_M2](INFO): Powering down
2019-01-08T18:42:20.021Z,1546972940.021 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:42:20.140Z,1546972940.140 [CBIT](INFO): Critical error at 20190108T184219
2019-01-08T18:42:29.898Z,1546972949.898 [SBIT](IMPORTANT): SBIT PASSED
2019-01-08T18:42:29.986Z,1546972949.986 [CommandLine](IMPORTANT): got command configSet list
2019-01-08T18:42:29.986Z,1546972949.986 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-08T18:42:29.987Z,1546972949.987 [CommandLine](IMPORTANT): 

2019-01-08T18:42:29.987Z,1546972949.987 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2019-01-08T18:42:29.987Z,1546972949.987 [CommandLine](IMPORTANT): CBIT.gf24Offset=170 microampere;

2019-01-08T18:42:30.291Z,1546972950.291 [MissionManager](IMPORTANT): Started mission Startup
2019-01-08T18:42:30.291Z,1546972950.291 [Startup] Running Loop=1
2019-01-08T18:42:30.291Z,1546972950.291 [Startup](DEBUG): Aggregate::initialize Startup
2019-01-08T18:42:30.292Z,1546972950.292 [Startup:A.GoToSurface] Running Loop=1
2019-01-08T18:42:30.292Z,1546972950.292 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-08T18:42:30.292Z,1546972950.292 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-08T18:42:30.293Z,1546972950.293 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-08T18:42:30.293Z,1546972950.293 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-08T18:42:30.294Z,1546972950.294 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-08T18:42:30.294Z,1546972950.294 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-08T18:42:30.300Z,1546972950.300 [Startup:StartupSatComms] Running Loop=1
2019-01-08T18:42:30.300Z,1546972950.300 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-01-08T18:42:30.300Z,1546972950.300 [Startup:StartupSatComms:A] Running Loop=1
2019-01-08T18:42:30.688Z,1546972950.688 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-01-08T18:43:03.094Z,1546972983.094 [CBIT](FAULT): WATER ALARM STERN.
2019-01-08T18:43:03.095Z,1546972983.095 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2019-01-08T18:43:03.533Z,1546972983.533 [CBIT](FAULT): WATER ALARM STERN.
2019-01-08T18:43:03.534Z,1546972983.534 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2019-01-08T18:43:03.941Z,1546972983.941 [CBIT](FAULT): WATER ALARM STERN.
2019-01-08T18:43:03.942Z,1546972983.942 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2019-01-08T18:43:04.304Z,1546972984.304 [CBIT](FAULT): WATER ALARM STERN.
2019-01-08T18:43:04.329Z,1546972984.329 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2019-01-08T18:43:04.329Z,1546972984.329 [CBIT](IMPORTANT): No ESP to power off.
2019-01-08T18:43:04.712Z,1546972984.712 [CBIT](INFO): Critical error at 20190108T184304
2019-01-08T18:43:07.886Z,1546972987.886 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-01-08T18:43:07.886Z,1546972987.886 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-01-08T18:43:07.886Z,1546972987.886 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-08T18:43:07.888Z,1546972987.888 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-01-08T18:43:07.888Z,1546972987.888 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-01-08T18:43:07.888Z,1546972987.888 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-08T18:43:07.900Z,1546972987.900 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-01-08T18:43:07.901Z,1546972987.901 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-01-08T18:43:08.300Z,1546972988.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-01-08T18:43:08.300Z,1546972988.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-01-08T18:43:08.301Z,1546972988.301 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-01-08T18:43:08.301Z,1546972988.301 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-01-08T18:43:08.704Z,1546972988.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-08T18:43:08.705Z,1546972988.705 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:43:08.705Z,1546972988.705 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:43:08.706Z,1546972988.706 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-08T18:43:08.706Z,1546972988.706 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:43:08.707Z,1546972988.707 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:43:26.167Z,1546973006.167 [CommandLine](IMPORTANT): got command restart application
2019-01-08T18:43:27.175Z,1546973007.175 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:27.176Z,1546973007.176 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.309Z,1546973007.309 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-01-08T18:43:27.309Z,1546973007.309 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.310Z,1546973007.310 [CommandLine](INFO): Join timeout helper Thread ID is 1747
2019-01-08T18:43:27.310Z,1546973007.310 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-01-08T18:43:27.310Z,1546973007.310 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.311Z,1546973007.311 [NavChartDb](INFO): Join timeout helper Thread ID is 1748
2019-01-08T18:43:27.466Z,1546973007.466 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:27.466Z,1546973007.466 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.471Z,1546973007.471 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-01-08T18:43:27.471Z,1546973007.471 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.471Z,1546973007.471 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1749
2019-01-08T18:43:27.709Z,1546973007.709 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:27.709Z,1546973007.709 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-08T18:43:27.710Z,1546973007.710 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.717Z,1546973007.717 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-01-08T18:43:27.717Z,1546973007.717 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.717Z,1546973007.717 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1750
2019-01-08T18:43:27.789Z,1546973007.789 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:27.789Z,1546973007.789 [CTD_NeilBrown](INFO): Powering down
2019-01-08T18:43:27.801Z,1546973007.801 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.817Z,1546973007.817 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-01-08T18:43:27.817Z,1546973007.817 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.817Z,1546973007.817 [Radio_Surface](INFO): Join timeout helper Thread ID is 1751
2019-01-08T18:43:27.913Z,1546973007.913 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:27.913Z,1546973007.913 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.922Z,1546973007.922 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-01-08T18:43:27.922Z,1546973007.922 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.922Z,1546973007.922 [logger](INFO): Join timeout helper Thread ID is 1752
2019-01-08T18:43:27.936Z,1546973007.936 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:27.936Z,1546973007.936 [logger ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.953Z,1546973007.953 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-01-08T18:43:27.954Z,1546973007.954 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.954Z,1546973007.954 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-01-08T18:43:27.954Z,1546973007.954 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:27.954Z,1546973007.954 [controlThread](INFO): Join timeout helper Thread ID is 1753
2019-01-08T18:43:28.249Z,1546973008.249 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-01-08T18:43:28.249Z,1546973008.249 [controlThread](DEBUG): Uninitializing ControlThread
2019-01-08T18:43:28.250Z,1546973008.250 [AHRS_M2](INFO): Powering down
2019-01-08T18:43:28.251Z,1546973008.251 [NAL9602](INFO): Powering down
2019-01-08T18:43:28.252Z,1546973008.252 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:43:28.253Z,1546973008.253 [RDI_PathfinderUp](INFO): Powering down
2019-01-08T18:43:28.254Z,1546973008.254 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:43:28.255Z,1546973008.255 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-08T18:43:28.255Z,1546973008.255 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-01-08T18:43:28.256Z,1546973008.256 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-01-08T18:43:28.256Z,1546973008.256 [MissionManager](INFO): Uninitializing Mission Default
2019-01-08T18:43:28.259Z,1546973008.259 [MissionManager](INFO): Uninitializing Mission Startup
2019-01-08T18:43:28.259Z,1546973008.259 [Startup] Stopped
2019-01-08T18:43:28.259Z,1546973008.259 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-01-08T18:43:28.259Z,1546973008.259 [Startup:A.GoToSurface] Stopped
2019-01-08T18:43:28.259Z,1546973008.259 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-08T18:43:28.259Z,1546973008.259 [Startup:StartupSatComms] Stopped
2019-01-08T18:43:28.259Z,1546973008.259 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-01-08T18:43:28.260Z,1546973008.260 [Startup:StartupSatComms:A] Stopped
2019-01-08T18:43:28.261Z,1546973008.261 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-01-08T18:43:28.262Z,1546973008.262 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-01-08T18:43:28.262Z,1546973008.262 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-01-08T18:43:28.262Z,1546973008.262 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-01-08T18:43:28.262Z,1546973008.262 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-01-08T18:43:28.263Z,1546973008.263 [BuoyancyServo](INFO): Powering down
2019-01-08T18:43:28.277Z,1546973008.277 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-01-08T18:43:28.277Z,1546973008.277 [ElevatorServo](INFO): Powering down
2019-01-08T18:43:28.278Z,1546973008.278 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-01-08T18:43:28.278Z,1546973008.278 [MassServo](INFO): Powering down
2019-01-08T18:43:28.279Z,1546973008.279 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-01-08T18:43:28.279Z,1546973008.279 [RudderServo](INFO): Powering down
2019-01-08T18:43:28.280Z,1546973008.280 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-01-08T18:43:28.280Z,1546973008.280 [ThrusterServo](INFO): Powering down
2019-01-08T18:43:28.281Z,1546973008.281 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-01-08T18:43:28.281Z,1546973008.281 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-01-08T18:43:28.282Z,1546973008.282 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-01-08T18:43:28.282Z,1546973008.282 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:28.343Z,1546973008.343 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:28.384Z,1546973008.384 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:28.387Z,1546973008.387 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:28.409Z,1546973008.409 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-01-08T18:43:28.466Z,1546973008.466 [logger ThreadHandler](INFO): Thread cancelled.