2018-07-12T23:54:13.953Z,1531439653.953 [Supervisor](DEBUG): Initializing supervisor.
2018-07-12T23:54:13.955Z,1531439653.955 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-12T23:54:13.956Z,1531439653.956 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-07-12T23:54:13.956Z,1531439653.956 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-12T23:54:13.957Z,1531439653.957 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-12T23:54:13.958Z,1531439653.958 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-07-12T23:54:13.960Z,1531439653.960 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-12T23:54:13.972Z,1531439653.972 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-12T23:54:13.973Z,1531439653.973 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-12T23:54:13.973Z,1531439653.973 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-07-12T23:54:13.974Z,1531439653.974 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-12T23:54:13.975Z,1531439653.975 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-12T23:54:13.975Z,1531439653.975 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-07-12T23:54:13.977Z,1531439653.977 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-12T23:54:13.978Z,1531439653.978 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-12T23:54:13.982Z,1531439653.982 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-12T23:54:14.178Z,1531439654.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-12T23:54:14.180Z,1531439654.180 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-12T23:54:14.323Z,1531439654.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-12T23:54:14.325Z,1531439654.325 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-12T23:54:14.672Z,1531439654.672 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-12T23:54:14.673Z,1531439654.673 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-12T23:54:14.812Z,1531439654.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-12T23:54:14.814Z,1531439654.814 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-12T23:54:14.895Z,1531439654.895 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-12T23:54:15.099Z,1531439655.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-12T23:54:15.099Z,1531439655.099 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-12T23:54:15.199Z,1531439655.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-12T23:54:15.199Z,1531439655.199 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-12T23:54:15.539Z,1531439655.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-12T23:54:15.540Z,1531439655.540 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-12T23:54:15.979Z,1531439655.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-12T23:54:15.979Z,1531439655.979 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-12T23:54:16.286Z,1531439656.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-12T23:54:16.286Z,1531439656.286 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-12T23:54:16.793Z,1531439656.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-12T23:54:16.794Z,1531439656.794 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-12T23:54:17.004Z,1531439657.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-12T23:54:17.004Z,1531439657.004 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-12T23:54:17.104Z,1531439657.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-12T23:54:17.104Z,1531439657.104 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-12T23:54:17.599Z,1531439657.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-12T23:54:17.599Z,1531439657.599 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-12T23:54:17.716Z,1531439657.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-12T23:54:17.718Z,1531439657.718 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-12T23:54:18.654Z,1531439658.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-12T23:54:18.905Z,1531439658.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-12T23:54:18.907Z,1531439658.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-12T23:54:19.008Z,1531439659.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-12T23:54:19.102Z,1531439659.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-12T23:54:19.205Z,1531439659.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-12T23:54:19.293Z,1531439659.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-12T23:54:19.427Z,1531439659.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-12T23:54:19.595Z,1531439659.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-12T23:54:19.706Z,1531439659.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-12T23:54:19.791Z,1531439659.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-12T23:54:19.875Z,1531439659.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-12T23:54:20.067Z,1531439660.067 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-07-12T23:54:20.073Z,1531439660.073 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-12T23:54:20.235Z,1531439660.235 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-12T23:54:20.265Z,1531439660.265 [SBIT] Loaded
2018-07-12T23:54:20.266Z,1531439660.266 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-12T23:54:20.266Z,1531439660.266 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-12T23:54:20.294Z,1531439660.294 [IBIT] Loaded
2018-07-12T23:54:20.295Z,1531439660.295 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-12T23:54:20.298Z,1531439660.298 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-12T23:54:20.457Z,1531439660.457 [CBIT] Loaded
2018-07-12T23:54:20.457Z,1531439660.457 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-12T23:54:20.458Z,1531439660.458 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-12T23:54:20.458Z,1531439660.458 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-12T23:54:20.549Z,1531439660.549 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-12T23:54:20.651Z,1531439660.651 [VerticalControl] Loaded
2018-07-12T23:54:20.651Z,1531439660.651 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-12T23:54:20.652Z,1531439660.652 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-12T23:54:20.714Z,1531439660.714 [HorizontalControl] Loaded
2018-07-12T23:54:20.714Z,1531439660.714 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-12T23:54:20.715Z,1531439660.715 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-12T23:54:20.720Z,1531439660.720 [SpeedControl] Loaded
2018-07-12T23:54:20.721Z,1531439660.721 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-12T23:54:20.721Z,1531439660.721 [LoopControl](DEBUG): Construct LoopControl.
2018-07-12T23:54:20.722Z,1531439660.722 [LoopControl] Loaded
2018-07-12T23:54:20.722Z,1531439660.722 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-12T23:54:20.723Z,1531439660.723 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-12T23:54:20.723Z,1531439660.723 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-12T23:54:20.780Z,1531439660.780 [DepthRateCalculator] Loaded
2018-07-12T23:54:20.780Z,1531439660.780 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-12T23:54:20.785Z,1531439660.785 [PitchRateCalculator] Loaded
2018-07-12T23:54:20.786Z,1531439660.786 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-12T23:54:20.802Z,1531439660.802 [SpeedCalculator] Loaded
2018-07-12T23:54:20.802Z,1531439660.802 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-12T23:54:20.824Z,1531439660.824 [TempGradientCalculator] Loaded
2018-07-12T23:54:20.824Z,1531439660.824 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-12T23:54:20.840Z,1531439660.840 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-12T23:54:20.840Z,1531439660.840 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-12T23:54:20.846Z,1531439660.846 [YawRateCalculator] Loaded
2018-07-12T23:54:20.846Z,1531439660.846 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-12T23:54:20.876Z,1531439660.876 [ElevatorOffsetCalculator] Loaded
2018-07-12T23:54:20.876Z,1531439660.876 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-12T23:54:20.877Z,1531439660.877 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-12T23:54:20.877Z,1531439660.877 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-12T23:54:21.003Z,1531439661.003 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-12T23:54:21.003Z,1531439661.003 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-12T23:54:21.003Z,1531439661.003 [StratificationFrontDetector] Loaded
2018-07-12T23:54:21.003Z,1531439661.003 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-12T23:54:21.004Z,1531439661.004 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-12T23:54:21.006Z,1531439661.006 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-12T23:54:21.297Z,1531439661.297 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-12T23:54:21.298Z,1531439661.298 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-12T23:54:21.414Z,1531439661.414 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-12T23:54:21.414Z,1531439661.414 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-12T23:54:21.470Z,1531439661.470 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-12T23:54:21.470Z,1531439661.470 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-12T23:54:21.487Z,1531439661.487 [NavChart] Loaded
2018-07-12T23:54:21.487Z,1531439661.487 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-12T23:54:21.491Z,1531439661.491 [UniversalFixResidualReporter] Loaded
2018-07-12T23:54:21.492Z,1531439661.492 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-12T23:54:21.492Z,1531439661.492 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-12T23:54:21.493Z,1531439661.493 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-12T23:54:21.518Z,1531439661.518 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-12T23:54:21.518Z,1531439661.518 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-12T23:54:21.724Z,1531439661.724 [Aanderaa_O2] Loaded
2018-07-12T23:54:21.725Z,1531439661.725 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-12T23:54:21.805Z,1531439661.805 [CTD_NeilBrown] Loaded
2018-07-12T23:54:21.805Z,1531439661.805 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-07-12T23:54:21.806Z,1531439661.806 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D24E0
2018-07-12T23:54:21.807Z,1531439661.807 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 881
2018-07-12T23:54:21.821Z,1531439661.821 [PAR_Licor] Loaded
2018-07-12T23:54:21.822Z,1531439661.822 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-07-12T23:54:21.872Z,1531439661.872 [WetLabsBB2FL] Loaded
2018-07-12T23:54:21.872Z,1531439661.872 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-12T23:54:21.873Z,1531439661.873 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407024E0
2018-07-12T23:54:21.874Z,1531439661.874 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 882
2018-07-12T23:54:21.919Z,1531439661.919 [WetLabsSeaOWL_UV_A] Loaded
2018-07-12T23:54:21.919Z,1531439661.919 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-07-12T23:54:21.920Z,1531439661.920 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407324E0
2018-07-12T23:54:21.921Z,1531439661.921 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 883
2018-07-12T23:54:21.921Z,1531439661.921 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-12T23:54:21.922Z,1531439661.922 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-12T23:54:22.534Z,1531439662.534 [AHRS_M2] Loaded
2018-07-12T23:54:22.535Z,1531439662.535 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-12T23:54:22.673Z,1531439662.673 [DataOverHttps] Loaded
2018-07-12T23:54:22.674Z,1531439662.674 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-12T23:54:22.687Z,1531439662.687 [Depth_Keller] Loaded
2018-07-12T23:54:22.687Z,1531439662.687 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-12T23:54:22.692Z,1531439662.692 [DropWeight] Loaded
2018-07-12T23:54:22.693Z,1531439662.693 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-12T23:54:22.786Z,1531439662.786 [NAL9602] Loaded
2018-07-12T23:54:22.787Z,1531439662.787 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-12T23:54:22.792Z,1531439662.792 [Onboard] Loaded
2018-07-12T23:54:22.792Z,1531439662.792 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-12T23:54:22.799Z,1531439662.799 [Radio_Surface] Loaded
2018-07-12T23:54:22.800Z,1531439662.800 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-12T23:54:22.801Z,1531439662.801 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409884E0
2018-07-12T23:54:22.801Z,1531439662.801 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884
2018-07-12T23:54:22.830Z,1531439662.830 [RDI_Pathfinder] Loaded
2018-07-12T23:54:22.830Z,1531439662.830 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-12T23:54:22.837Z,1531439662.837 [SCPI] Loaded
2018-07-12T23:54:22.837Z,1531439662.837 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-12T23:54:25.041Z,1531439665.041 [BPC1] Loaded
2018-07-12T23:54:25.042Z,1531439665.042 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-12T23:54:25.042Z,1531439665.042 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-12T23:54:25.043Z,1531439665.043 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-12T23:54:25.166Z,1531439665.166 [BuoyancyServo] Loaded
2018-07-12T23:54:25.166Z,1531439665.166 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-12T23:54:25.178Z,1531439665.178 [ElevatorServo] Loaded
2018-07-12T23:54:25.178Z,1531439665.178 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-12T23:54:25.189Z,1531439665.189 [MassServo] Loaded
2018-07-12T23:54:25.190Z,1531439665.190 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-12T23:54:25.201Z,1531439665.201 [RudderServo] Loaded
2018-07-12T23:54:25.201Z,1531439665.201 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-12T23:54:25.213Z,1531439665.213 [ThrusterServo] Loaded
2018-07-12T23:54:25.213Z,1531439665.213 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-12T23:54:25.213Z,1531439665.213 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-12T23:54:25.214Z,1531439665.214 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-12T23:54:25.345Z,1531439665.345 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-12T23:54:25.345Z,1531439665.345 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-12T23:54:25.383Z,1531439665.383 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-12T23:54:25.387Z,1531439665.387 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-12T23:54:25.388Z,1531439665.388 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-12T23:54:25.395Z,1531439665.395 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-12T23:54:25.396Z,1531439665.396 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2018-07-12T23:54:25.396Z,1531439665.396 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2018-07-12T23:54:25.401Z,1531439665.401 [Supervisor](INFO): Main Thread ID is 795
2018-07-12T23:54:25.401Z,1531439665.401 [Supervisor](DEBUG): Running supervisor.
2018-07-12T23:54:25.402Z,1531439665.402 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2018-07-12T23:54:25.404Z,1531439665.404 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2018-07-12T23:54:25.404Z,1531439665.404 [controlThread](DEBUG): Initializing ControlThread
2018-07-12T23:54:25.405Z,1531439665.405 [SBIT](INFO): Initialize SBIT Component.
2018-07-12T23:54:25.406Z,1531439665.406 [SBIT](IMPORTANT): git: 2018-06-03-17-gbdf8474
2018-07-12T23:54:25.406Z,1531439665.406 [SBIT](INFO): git hash: bdf847407d9faffe74fd7e88a9e899f3511e6e11
2018-07-12T23:54:25.406Z,1531439665.406 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-12T23:54:25.408Z,1531439665.408 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-12T23:54:25.409Z,1531439665.409 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-07-12T23:54:25.409Z,1531439665.409 [IBIT](INFO): Initialize IBIT Component.
2018-07-12T23:54:25.410Z,1531439665.410 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-12T23:54:25.410Z,1531439665.410 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-07-12T23:54:25.411Z,1531439665.411 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-07-12T23:54:25.411Z,1531439665.411 [logger ThreadHandler](INFO): Handler Thread ID is 888
2018-07-12T23:54:25.437Z,1531439665.437 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 889
2018-07-12T23:54:25.438Z,1531439665.438 [CTD_NeilBrown](INFO): Powering down
2018-07-12T23:54:25.475Z,1531439665.475 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 890
2018-07-12T23:54:25.493Z,1531439665.493 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 891
2018-07-12T23:54:25.494Z,1531439665.494 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-07-12T23:54:25.517Z,1531439665.517 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-12T23:54:25.519Z,1531439665.519 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-12T23:54:25.520Z,1531439665.520 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-12T23:54:25.520Z,1531439665.520 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-12T23:54:25.520Z,1531439665.520 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-12T23:54:25.521Z,1531439665.521 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-12T23:54:25.521Z,1531439665.521 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-12T23:54:25.522Z,1531439665.522 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-12T23:54:25.522Z,1531439665.522 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-12T23:54:25.523Z,1531439665.523 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-12T23:54:25.523Z,1531439665.523 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-12T23:54:25.524Z,1531439665.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-12T23:54:25.525Z,1531439665.525 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:54:25.525Z,1531439665.525 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:54:25.526Z,1531439665.526 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-12T23:54:25.526Z,1531439665.526 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-12T23:54:25.527Z,1531439665.527 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-12T23:54:25.527Z,1531439665.527 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-12T23:54:25.527Z,1531439665.527 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-12T23:54:25.541Z,1531439665.541 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-07-12T23:54:25.547Z,1531439665.547 [Radio_Surface](INFO): Powering up
2018-07-12T23:54:25.554Z,1531439665.554 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-12T23:54:25.566Z,1531439665.566 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893
2018-07-12T23:54:25.585Z,1531439665.585 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-12T23:54:25.585Z,1531439665.585 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-12T23:54:25.586Z,1531439665.586 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-12T23:54:25.586Z,1531439665.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-12T23:54:25.586Z,1531439665.586 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-12T23:54:25.586Z,1531439665.586 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-12T23:54:25.586Z,1531439665.586 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-12T23:54:25.587Z,1531439665.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-12T23:54:25.587Z,1531439665.587 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-12T23:54:25.587Z,1531439665.587 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-12T23:54:25.587Z,1531439665.587 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-12T23:54:25.588Z,1531439665.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-12T23:54:25.588Z,1531439665.588 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-12T23:54:25.588Z,1531439665.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-12T23:54:25.588Z,1531439665.588 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-12T23:54:25.588Z,1531439665.588 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-12T23:54:25.580Z,1531439665.580 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-12T23:54:25.649Z,1531439665.649 [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>


2018-07-12T23:54:25.650Z,1531439665.650 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-12T23:54:25.736Z,1531439665.736 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-12T23:54:25.738Z,1531439665.738 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-12T23:54:25.739Z,1531439665.739 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-12T23:54:25.794Z,1531439665.794 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-12T23:54:25.797Z,1531439665.797 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-12T23:54:25.819Z,1531439665.819 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-12T23:54:25.822Z,1531439665.822 [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 300</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>


2018-07-12T23:54:25.830Z,1531439665.830 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-12T23:54:25.942Z,1531439665.942 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-12T23:54:26.065Z,1531439666.065 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-12T23:54:26.089Z,1531439666.089 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-07-12T23:54:26.341Z,1531439666.341 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-07-12T23:54:26.453Z,1531439666.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:26.507Z,1531439666.507 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:26.633Z,1531439666.633 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:26.641Z,1531439666.641 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:54:26.660Z,1531439666.660 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:26.661Z,1531439666.661 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-12T23:54:26.667Z,1531439666.667 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:26.673Z,1531439666.673 [MassServo](DEBUG): Initializing MassServo.
2018-07-12T23:54:26.695Z,1531439666.695 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:26.701Z,1531439666.701 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-12T23:54:26.707Z,1531439666.707 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:26.713Z,1531439666.713 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-12T23:54:26.766Z,1531439666.766 [CommandLine](FAULT): Scheduling is paused
2018-07-12T23:54:26.766Z,1531439666.766 [CBIT](INFO): Critical error at 20180712T235425
2018-07-12T23:54:26.766Z,1531439666.766 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-07-12T23:54:26.973Z,1531439666.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:26.974Z,1531439666.974 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:27.225Z,1531439667.225 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	651	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2018-07-12T23:54:27.226Z,1531439667.226 [Aanderaa_O2] Data Fault, FailCount= 1
2018-07-12T23:54:27.226Z,1531439667.226 [Aanderaa_O2](ERROR): Data Fault
2018-07-12T23:54:27.288Z,1531439667.288 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:27.301Z,1531439667.301 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:27.633Z,1531439667.633 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-07-12T23:54:27.633Z,1531439667.633 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-07-12T23:54:27.633Z,1531439667.633 [BuoyancyServo] Communications Fault, FailCount= 1
2018-07-12T23:54:27.633Z,1531439667.633 [BuoyancyServo](ERROR): Communications Fault
2018-07-12T23:54:27.845Z,1531439667.845 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2018-07-12T23:54:27.845Z,1531439667.845 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-12T23:54:27.973Z,1531439667.973 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:54:28.113Z,1531439668.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:28.114Z,1531439668.114 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:28.135Z,1531439668.135 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:54:28.135Z,1531439668.135 [BuoyancyServo](INFO): Powering down
2018-07-12T23:54:28.325Z,1531439668.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:28.326Z,1531439668.326 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:28.701Z,1531439668.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:28.702Z,1531439668.702 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:28.728Z,1531439668.728 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-07-12T23:54:28.729Z,1531439668.729 [Aanderaa_O2] No Fault, FailCount= 1
2018-07-12T23:54:29.101Z,1531439669.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:29.102Z,1531439669.102 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:29.521Z,1531439669.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:29.533Z,1531439669.533 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:29.929Z,1531439669.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:29.931Z,1531439669.931 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:30.321Z,1531439670.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:30.333Z,1531439670.333 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:30.477Z,1531439670.477 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:54:30.477Z,1531439670.477 [BuoyancyServo] No Fault, FailCount= 1
2018-07-12T23:54:30.754Z,1531439670.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:30.755Z,1531439670.755 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:30.778Z,1531439670.778 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:30.898Z,1531439670.898 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:54:31.235Z,1531439671.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:31.236Z,1531439671.236 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:31.615Z,1531439671.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:31.616Z,1531439671.616 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:31.991Z,1531439671.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:31.992Z,1531439671.992 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:32.394Z,1531439672.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:32.395Z,1531439672.395 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:32.799Z,1531439672.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:32.800Z,1531439672.800 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:33.225Z,1531439673.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:33.226Z,1531439673.226 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:33.622Z,1531439673.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:33.623Z,1531439673.623 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:34.033Z,1531439674.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:34.035Z,1531439674.035 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:34.069Z,1531439674.069 [BuoyancyServo](FAULT): Reading outside of valid range:9520.000000

2018-07-12T23:54:34.069Z,1531439674.069 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:54:34.070Z,1531439674.070 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-07-12T23:54:34.070Z,1531439674.070 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:54:34.102Z,1531439674.102 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:54:34.402Z,1531439674.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:34.403Z,1531439674.403 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:34.425Z,1531439674.425 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:54:34.426Z,1531439674.426 [BuoyancyServo](INFO): Powering down
2018-07-12T23:54:34.818Z,1531439674.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:34.820Z,1531439674.820 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:35.223Z,1531439675.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:35.224Z,1531439675.224 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:35.626Z,1531439675.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:35.627Z,1531439675.627 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:35.994Z,1531439675.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:35.995Z,1531439675.995 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:36.393Z,1531439676.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:36.394Z,1531439676.394 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:36.450Z,1531439676.450 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-12T23:54:36.450Z,1531439676.450 [BuoyancyServo] No Fault, FailCount= 2
2018-07-12T23:54:36.801Z,1531439676.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:36.802Z,1531439676.802 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:36.817Z,1531439676.817 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-12T23:54:36.938Z,1531439676.938 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-12T23:54:37.193Z,1531439677.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:37.195Z,1531439677.195 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:37.594Z,1531439677.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:37.595Z,1531439677.595 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:37.978Z,1531439677.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:37.979Z,1531439677.979 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:38.398Z,1531439678.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:38.399Z,1531439678.399 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:38.798Z,1531439678.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:38.799Z,1531439678.799 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:39.142Z,1531439679.142 [Aanderaa_O2](FAULT): Timed out starting
2018-07-12T23:54:39.143Z,1531439679.143 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-07-12T23:54:39.143Z,1531439679.143 [Aanderaa_O2](ERROR): Communications Fault
2018-07-12T23:54:39.202Z,1531439679.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:39.203Z,1531439679.203 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-12T23:54:39.278Z,1531439679.278 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-07-12T23:54:39.661Z,1531439679.661 [Aanderaa_O2](INFO): Powering down
2018-07-12T23:54:40.061Z,1531439680.061 [BuoyancyServo](FAULT): Reading outside of valid range:9521.000000

2018-07-12T23:54:40.061Z,1531439680.061 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2018-07-12T23:54:40.062Z,1531439680.062 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-07-12T23:54:40.062Z,1531439680.062 [BuoyancyServo](ERROR): Hardware Fault
2018-07-12T23:54:40.094Z,1531439680.094 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-12T23:54:40.425Z,1531439680.425 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-12T23:54:40.426Z,153