2019-03-06T23:28:25.635Z,1551914905.635 [Supervisor](DEBUG): Initializing supervisor.
2019-03-06T23:28:25.647Z,1551914905.647 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-06T23:28:25.647Z,1551914905.647 [SyncHandler](INFO): Protected caller Thread ID is 805
2019-03-06T23:28:25.648Z,1551914905.648 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-06T23:28:25.659Z,1551914905.659 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-06T23:28:25.659Z,1551914905.659 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 806
2019-03-06T23:28:25.662Z,1551914905.662 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-06T23:28:25.684Z,1551914905.684 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-06T23:28:25.695Z,1551914905.695 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-06T23:28:25.695Z,1551914905.695 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 807
2019-03-06T23:28:25.696Z,1551914905.696 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-06T23:28:25.707Z,1551914905.707 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-06T23:28:25.707Z,1551914905.707 [logger ThreadHandler](INFO): Protected caller Thread ID is 808
2019-03-06T23:28:25.709Z,1551914905.709 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-06T23:28:25.709Z,1551914905.709 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-06T23:28:25.712Z,1551914905.712 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-06T23:28:26.563Z,1551914906.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-06T23:28:26.565Z,1551914906.565 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-06T23:28:26.817Z,1551914906.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-06T23:28:26.817Z,1551914906.817 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-06T23:28:26.945Z,1551914906.945 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-06T23:28:27.101Z,1551914907.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-06T23:28:27.101Z,1551914907.101 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-06T23:28:27.283Z,1551914907.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-06T23:28:27.283Z,1551914907.283 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-06T23:28:28.009Z,1551914908.009 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-06T23:28:28.010Z,1551914908.010 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-06T23:28:28.373Z,1551914908.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-06T23:28:28.374Z,1551914908.374 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-06T23:28:29.265Z,1551914909.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-06T23:28:29.266Z,1551914909.266 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-06T23:28:29.596Z,1551914909.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-06T23:28:29.597Z,1551914909.597 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-06T23:28:29.900Z,1551914909.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-06T23:28:29.901Z,1551914909.901 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-06T23:28:30.109Z,1551914910.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-06T23:28:30.109Z,1551914910.109 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-06T23:28:30.251Z,1551914910.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-06T23:28:30.252Z,1551914910.252 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-06T23:28:30.736Z,1551914910.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-06T23:28:30.737Z,1551914910.737 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-06T23:28:31.070Z,1551914911.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-06T23:28:31.072Z,1551914911.072 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-06T23:28:31.277Z,1551914911.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-06T23:28:31.279Z,1551914911.279 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-03-06T23:28:31.282Z,1551914911.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-03-06T23:28:31.454Z,1551914911.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-03-06T23:28:31.538Z,1551914911.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-03-06T23:28:31.652Z,1551914911.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-03-06T23:28:31.738Z,1551914911.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-03-06T23:28:31.962Z,1551914911.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-06T23:28:31.963Z,1551914911.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-03-06T23:28:32.085Z,1551914912.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-03-06T23:28:32.253Z,1551914912.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-03-06T23:28:32.349Z,1551914912.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-03-06T23:28:32.498Z,1551914912.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-03-06T23:28:32.599Z,1551914912.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-03-06T23:28:32.696Z,1551914912.696 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-03-06T23:28:32.700Z,1551914912.700 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-06T23:28:32.724Z,1551914912.724 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-06T23:28:32.725Z,1551914912.725 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-06T23:28:32.811Z,1551914912.811 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-06T23:28:32.922Z,1551914912.922 [VerticalControl] Loaded
2019-03-06T23:28:32.922Z,1551914912.922 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-06T23:28:32.923Z,1551914912.923 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-06T23:28:32.990Z,1551914912.990 [HorizontalControl] Loaded
2019-03-06T23:28:32.990Z,1551914912.990 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-06T23:28:32.991Z,1551914912.991 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-06T23:28:32.996Z,1551914912.996 [SpeedControl] Loaded
2019-03-06T23:28:32.996Z,1551914912.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-06T23:28:32.997Z,1551914912.997 [LoopControl](DEBUG): Construct LoopControl.
2019-03-06T23:28:32.998Z,1551914912.998 [LoopControl] Loaded
2019-03-06T23:28:32.998Z,1551914912.998 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-06T23:28:32.998Z,1551914912.998 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-06T23:28:32.999Z,1551914912.999 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-06T23:28:33.136Z,1551914913.136 [BuoyancyServo] Loaded
2019-03-06T23:28:33.137Z,1551914913.137 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-06T23:28:33.148Z,1551914913.148 [ElevatorServo] Loaded
2019-03-06T23:28:33.148Z,1551914913.148 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-06T23:28:33.159Z,1551914913.159 [MassServo] Loaded
2019-03-06T23:28:33.159Z,1551914913.159 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-06T23:28:33.170Z,1551914913.170 [RudderServo] Loaded
2019-03-06T23:28:33.170Z,1551914913.170 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-06T23:28:33.181Z,1551914913.181 [ThrusterServo] Loaded
2019-03-06T23:28:33.181Z,1551914913.181 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-06T23:28:33.181Z,1551914913.181 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-06T23:28:33.182Z,1551914913.182 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-06T23:28:33.236Z,1551914913.236 [DepthRateCalculator] Loaded
2019-03-06T23:28:33.236Z,1551914913.236 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-06T23:28:33.241Z,1551914913.241 [PitchRateCalculator] Loaded
2019-03-06T23:28:33.241Z,1551914913.241 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-06T23:28:33.256Z,1551914913.256 [SpeedCalculator] Loaded
2019-03-06T23:28:33.256Z,1551914913.256 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-06T23:28:33.276Z,1551914913.276 [TempGradientCalculator] Loaded
2019-03-06T23:28:33.277Z,1551914913.277 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-06T23:28:33.282Z,1551914913.282 [YawRateCalculator] Loaded
2019-03-06T23:28:33.282Z,1551914913.282 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-06T23:28:33.311Z,1551914913.311 [ElevatorOffsetCalculator] Loaded
2019-03-06T23:28:33.311Z,1551914913.311 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-06T23:28:33.311Z,1551914913.311 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-06T23:28:33.312Z,1551914913.312 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-06T23:28:33.551Z,1551914913.551 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-06T23:28:33.551Z,1551914913.551 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-06T23:28:33.618Z,1551914913.618 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-06T23:28:33.619Z,1551914913.619 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-06T23:28:33.635Z,1551914913.635 [NavChart] Loaded
2019-03-06T23:28:33.635Z,1551914913.635 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-06T23:28:33.648Z,1551914913.648 [UniversalFixResidualReporter] Loaded
2019-03-06T23:28:33.648Z,1551914913.648 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-06T23:28:33.648Z,1551914913.648 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-06T23:28:33.649Z,1551914913.649 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-06T23:28:34.815Z,1551914914.815 [AHRS_M2] Loaded
2019-03-06T23:28:34.815Z,1551914914.815 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-03-06T23:28:34.958Z,1551914914.958 [DataOverHttps] Loaded
2019-03-06T23:28:34.958Z,1551914914.958 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-06T23:28:34.959Z,1551914914.959 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F54E0
2019-03-06T23:28:34.959Z,1551914914.959 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 888
2019-03-06T23:28:34.972Z,1551914914.972 [Depth_Keller] Loaded
2019-03-06T23:28:34.973Z,1551914914.973 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-06T23:28:34.977Z,1551914914.977 [DropWeight] Loaded
2019-03-06T23:28:34.978Z,1551914914.978 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-06T23:28:35.072Z,1551914915.072 [NAL9602] Loaded
2019-03-06T23:28:35.072Z,1551914915.072 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-06T23:28:35.087Z,1551914915.087 [Onboard] Loaded
2019-03-06T23:28:35.088Z,1551914915.088 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-06T23:28:35.091Z,1551914915.091 [Radio_Surface] Loaded
2019-03-06T23:28:35.091Z,1551914915.091 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-06T23:28:35.092Z,1551914915.092 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408254E0
2019-03-06T23:28:35.093Z,1551914915.093 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 889
2019-03-06T23:28:36.825Z,1551914916.825 [BPC1] Loaded
2019-03-06T23:28:36.825Z,1551914916.825 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-06T23:28:36.826Z,1551914916.826 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-06T23:28:36.827Z,1551914916.827 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-06T23:28:36.927Z,1551914916.927 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-06T23:28:36.928Z,1551914916.928 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-06T23:28:37.050Z,1551914917.050 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-06T23:28:37.051Z,1551914917.051 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-06T23:28:37.092Z,1551914917.092 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-06T23:28:37.092Z,1551914917.092 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-06T23:28:37.404Z,1551914917.404 [CTD_NeilBrown] Loaded
2019-03-06T23:28:37.404Z,1551914917.404 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-06T23:28:37.405Z,1551914917.405 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0
2019-03-06T23:28:37.405Z,1551914917.405 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 890
2019-03-06T23:28:37.415Z,1551914917.415 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-03-06T23:28:37.420Z,1551914917.420 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-03-06T23:28:37.421Z,1551914917.421 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-03-06T23:28:37.426Z,1551914917.426 [CTD_Seabird](INFO): created writer for : depth
2019-03-06T23:28:37.427Z,1551914917.427 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-03-06T23:28:37.432Z,1551914917.432 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-03-06T23:28:37.432Z,1551914917.432 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-03-06T23:28:37.437Z,1551914917.437 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-03-06T23:28:37.438Z,1551914917.438 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-03-06T23:28:37.443Z,1551914917.443 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-03-06T23:28:37.443Z,1551914917.443 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-03-06T23:28:37.448Z,1551914917.448 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-03-06T23:28:37.449Z,1551914917.449 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-03-06T23:28:37.453Z,1551914917.453 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-03-06T23:28:37.480Z,1551914917.480 [CTD_Seabird] Loaded
2019-03-06T23:28:37.480Z,1551914917.480 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-03-06T23:28:37.481Z,1551914917.481 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0
2019-03-06T23:28:37.481Z,1551914917.481 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 891
2019-03-06T23:28:37.529Z,1551914917.529 [WetLabsBB2FL] Loaded
2019-03-06T23:28:37.529Z,1551914917.529 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-06T23:28:37.530Z,1551914917.530 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A104E0
2019-03-06T23:28:37.531Z,1551914917.531 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 892
2019-03-06T23:28:37.531Z,1551914917.531 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-06T23:28:37.532Z,1551914917.532 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-06T23:28:37.801Z,1551914917.801 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-06T23:28:37.803Z,1551914917.803 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-06T23:28:37.956Z,1551914917.956 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-06T23:28:37.967Z,1551914917.967 [SBIT] Loaded
2019-03-06T23:28:37.967Z,1551914917.967 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-06T23:28:37.968Z,1551914917.968 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-06T23:28:37.979Z,1551914917.979 [IBIT] Loaded
2019-03-06T23:28:37.980Z,1551914917.980 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-06T23:28:37.983Z,1551914917.983 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-06T23:28:38.120Z,1551914918.120 [CBIT] Loaded
2019-03-06T23:28:38.120Z,1551914918.120 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-06T23:28:38.120Z,1551914918.120 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-06T23:28:38.124Z,1551914918.124 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-06T23:28:38.125Z,1551914918.125 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-06T23:28:38.131Z,1551914918.131 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-06T23:28:38.132Z,1551914918.132 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2019-03-06T23:28:38.133Z,1551914918.133 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 893
2019-03-06T23:28:38.137Z,1551914918.137 [Supervisor](INFO): Main Thread ID is 801
2019-03-06T23:28:38.137Z,1551914918.137 [Supervisor](DEBUG): Running supervisor.
2019-03-06T23:28:38.138Z,1551914918.138 [CommandLine ThreadHandler](INFO): Handler Thread ID is 894
2019-03-06T23:28:38.140Z,1551914918.140 [controlThread ThreadHandler](INFO): Handler Thread ID is 895
2019-03-06T23:28:38.141Z,1551914918.141 [controlThread](DEBUG): Initializing ControlThread
2019-03-06T23:28:38.141Z,1551914918.141 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-06T23:28:38.143Z,1551914918.143 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-06T23:28:38.144Z,1551914918.144 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-06T23:28:38.144Z,1551914918.144 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-06T23:28:38.146Z,1551914918.146 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-06T23:28:38.146Z,1551914918.146 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-06T23:28:38.146Z,1551914918.146 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-06T23:28:38.147Z,1551914918.147 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-06T23:28:38.148Z,1551914918.148 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-06T23:28:38.148Z,1551914918.148 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-06T23:28:38.149Z,1551914918.149 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T23:28:38.150Z,1551914918.150 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-06T23:28:38.150Z,1551914918.150 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-06T23:28:38.157Z,1551914918.157 [SBIT](INFO): Initialize SBIT Component.
2019-03-06T23:28:38.157Z,1551914918.157 [SBIT](IMPORTANT): git: 2019-02-11-73-gd81f403
2019-03-06T23:28:38.157Z,1551914918.157 [SBIT](INFO): git hash: d81f403ba8dd4609f3c9691256c8521b6bd69743
2019-03-06T23:28:38.158Z,1551914918.158 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-06T23:28:38.159Z,1551914918.159 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-03-06T23:28:38.160Z,1551914918.160 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-06T23:28:38.160Z,1551914918.160 [IBIT](INFO): Initialize IBIT Component.
2019-03-06T23:28:38.161Z,1551914918.161 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-06T23:28:38.162Z,1551914918.162 [logger ThreadHandler](INFO): Handler Thread ID is 896
2019-03-06T23:28:38.175Z,1551914918.175 [CBIT](DEBUG): Initialized mux pins.
2019-03-06T23:28:38.175Z,1551914918.175 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-03-06T23:28:38.175Z,1551914918.175 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-06T23:28:38.187Z,1551914918.187 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 897
2019-03-06T23:28:38.188Z,1551914918.188 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-06T23:28:38.199Z,1551914918.199 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-06T23:28:38.199Z,1551914918.199 [CBIT](DEBUG): Initializing heartbeat.
2019-03-06T23:28:38.199Z,1551914918.199 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898
2019-03-06T23:28:38.211Z,1551914918.211 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 899
2019-03-06T23:28:38.223Z,1551914918.223 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 900
2019-03-06T23:28:38.224Z,1551914918.224 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-06T23:28:38.227Z,1551914918.227 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-06T23:28:38.228Z,1551914918.228 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 902
2019-03-06T23:28:38.229Z,1551914918.229 [WetLabsBB2FL](INFO): Powering down
2019-03-06T23:28:38.271Z,1551914918.271 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-06T23:28:38.271Z,1551914918.271 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-06T23:28:38.279Z,1551914918.279 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 903
2019-03-06T23:28:38.286Z,1551914918.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-06T23:28:38.286Z,1551914918.286 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-06T23:28:38.287Z,1551914918.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-06T23:28:38.287Z,1551914918.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-06T23:28:38.287Z,1551914918.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-06T23:28:38.287Z,1551914918.287 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-06T23:28:38.287Z,1551914918.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-06T23:28:38.288Z,1551914918.288 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-06T23:28:38.288Z,1551914918.288 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-06T23:28:38.288Z,1551914918.288 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-06T23:28:38.288Z,1551914918.288 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-06T23:28:38.288Z,1551914918.288 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-06T23:28:38.289Z,1551914918.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-06T23:28:38.289Z,1551914918.289 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-06T23:28:38.289Z,1551914918.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-06T23:28:38.289Z,1551914918.289 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-06T23:28:38.307Z,1551914918.307 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-06T23:28:38.334Z,1551914918.334 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T23:28:38.345Z,1551914918.345 [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-03-06T23:28:38.346Z,1551914918.346 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-06T23:28:38.466Z,1551914918.466 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-06T23:28:38.468Z,1551914918.467 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-06T23:28:38.469Z,1551914918.469 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T23:28:38.519Z,1551914918.519 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-06T23:28:38.522Z,1551914918.522 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-06T23:28:38.565Z,1551914918.565 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-06T23:28:38.568Z,1551914918.568 [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-03-06T23:28:38.582Z,1551914918.582 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,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-03-06T23:28:38.603Z,1551914918.603 [Radio_Surface](INFO): Powering up
2019-03-06T23:28:38.604Z,1551914918.604 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T23:28:38.903Z,1551914918.903 [Depth_Keller](INFO): A/D timeout, 11 tries over 128 ms

2019-03-06T23:28:38.903Z,1551914918.903 [Depth_Keller] Data Fault, FailCount= 1
2019-03-06T23:28:38.903Z,1551914918.903 [Depth_Keller](ERROR): Data Fault
2019-03-06T23:28:38.951Z,1551914918.951 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T23:28:39.027Z,1551914919.027 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2019-03-06T23:28:39.047Z,1551914919.047 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:28:39.063Z,1551914919.063 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-06T23:28:39.064Z,1551914919.064 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:28:39.075Z,1551914919.075 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-06T23:28:39.076Z,1551914919.076 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:28:39.099Z,1551914919.099 [MassServo](DEBUG): Initializing MassServo.
2019-03-06T23:28:39.100Z,1551914919.100 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:28:39.115Z,1551914919.115 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T23:28:39.116Z,1551914919.116 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:28:39.127Z,1551914919.127 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-06T23:28:39.130Z,1551914919.130 [CBIT](FAULT): WATER ALARM BOW.
2019-03-06T23:28:39.131Z,1551914919.131 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2019-03-06T23:28:39.133Z,1551914919.133 [CBIT](ERROR): Data Fault in component: Depth_Keller
2019-03-06T23:28:39.133Z,1551914919.133 [CBIT](INFO): Clearing failed state for component Depth_Keller
2019-03-06T23:28:39.133Z,1551914919.133 [Depth_Keller] No Fault, FailCount= 1
2019-03-06T23:28:39.187Z,1551914919.187 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-03-06T23:28:39.187Z,1551914919.187 [DropWeight] Hardware Fault, FailCount= 1
2019-03-06T23:28:39.187Z,1551914919.187 [DropWeight](ERROR): Hardware Fault
2019-03-06T23:28:39.223Z,1551914919.223 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T23:28:39.246Z,1551914919.246 [CommandLine](FAULT): Scheduling is paused
2019-03-06T23:28:39.246Z,1551914919.246 [CBIT](INFO): Critical error at 20190306T232839
2019-03-06T23:28:39.247Z,1551914919.247 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-06T23:28:39.248Z,1551914919.248 [CBIT](FAULT): WATER ALARM BOW.
2019-03-06T23:28:39.249Z,1551914919.249 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2019-03-06T23:28:39.250Z,1551914919.250 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-03-06T23:28:39.250Z,1551914919.250 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-03-06T23:28:39.576Z,1551914919.576 [CBIT](INFO): Critical error at 20190306T232839
2019-03-06T23:28:39.577Z,1551914919.577 [CBIT](FAULT): WATER ALARM BOW.
2019-03-06T23:28:39.578Z,1551914919.578 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2019-03-06T23:28:40.463Z,1551914920.463 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-03-06T23:28:40.787Z,1551914920.787 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2019-03-06T23:28:40.787Z,1551914920.787 [ThrusterServo](FAULT): Thruster failed to initialize
2019-03-06T23:28:40.787Z,1551914920.787 [ThrusterServo] Communications Fault, FailCount= 1
2019-03-06T23:28:40.787Z,1551914920.787 [ThrusterServo](ERROR): Communications Fault
2019-03-06T23:28:40.789Z,1551914920.789 [CBIT](FAULT): WATER ALARM BOW.
2019-03-06T23:28:40.803Z,1551914920.803 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2019-03-06T23:28:40.803Z,1551914920.803 [CBIT](IMPORTANT): No ESP to power off.
2019-03-06T23:28:40.804Z,1551914920.804 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-03-06T23:28:40.835Z,1551914920.835 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-06T23:28:40.835Z,1551914920.835 [ThrusterServo](INFO): Powering down
2019-03-06T23:28:40.924Z,1551914920.924 [CBIT](INFO): Critical error at 20190306T232840
2019-03-06T23:28:41.939Z,1551914921.939 [BuoyancyServo](FAULT): Buoyancy initialization uart error:serial timeout
2019-03-06T23:28:41.939Z,1551914921.939 [BuoyancyServo] Communications Fault, FailCount= 1
2019-03-06T23:28:41.939Z,1551914921.939 [BuoyancyServo](ERROR): Communications Fault
2019-03-06T23:28:42.251Z,1551914922.251 [ElevatorServo](FAULT): Elevator initialization uart error:serial timeout
2019-03-06T23:28:42.251Z,1551914922.251 [ElevatorServo] Communications Fault, FailCount= 1
2019-03-06T23:28:42.251Z,1551914922.251 [ElevatorServo](ERROR): Communications Fault
2019-03-06T23:28:42.503Z,1551914922.503 [MassServo](FAULT): Mass initialization uart error:serial timeout
2019-03-06T23:28:42.503Z,1551914922.503 [MassServo] Communications Fault, FailCount= 1
2019-03-06T23:28:42.503Z,1551914922.503 [MassServo](ERROR): Communications Fault
2019-03-06T23:28:42.815Z,1551914922.815 [RudderServo](FAULT): Rudder initialization uart error:serial timeout
2019-03-06T23:28:42.815Z,1551914922.815 [RudderServo] Communications Fault, FailCount= 1
2019-03-06T23:28:42.815Z,1551914922.815 [RudderServo](ERROR): Communications Fault
2019-03-06T23:28:44.663Z,1551914924.663 [Radio_Surface](INFO): Powering down
2019-03-06T23:28:52.279Z,1551914932.279 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-03-06T23:28:52.279Z,1551914932.279 [CTD_Seabird] Communications Fault, FailCount= 1
2019-03-06T23:28:52.279Z,1551914932.279 [CTD_Seabird](ERROR): Communications Fault
2019-03-06T23:28:52.683Z,1551914932.683 [CTD_Seabird](INFO): Powering down
2019-03-06T23:29:12.938Z,1551914952.938 [CBIT](CRITICAL): Environmental Failure. Press:14.848687 PSI. Humidity:38%. Temp:25 C. ABORTING MISSION
2019-03-06T23:29:12.939Z,1551914952.939 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-03-06T23:29:12.939Z,1551914952.939 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2019-03-06T23:29:12.940Z,1551914952.940 [CBIT](ERROR): Communications Fault in component: MassServo
2019-03-06T23:29:12.940Z,1551914952.940 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-06T23:29:12.940Z,1551914952.940 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-03-06T23:29:12.940Z,1551914952.940 [ThrusterServo] No Fault, FailCount= 1
2019-03-06T23:29:12.941Z,1551914952.941 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-03-06T23:29:12.941Z,1551914952.941 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-03-06T23:29:12.941Z,1551914952.941 [CTD_Seabird] No Fault, FailCount= 1
2019-03-06T23:29:13.027Z,1551914953.027 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-06T23:29:13.028Z,1551914953.028 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-06T23:29:13.263Z,1551914953.263 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T23:29:13.263Z,1551914953.263 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T23:29:13.263Z,1551914953.263 [AHRS_M2] Communications Fault, FailCount= 1
2019-03-06T23:29:13.263Z,1551914953.263 [AHRS_M2](ERROR): Communications Fault
2019-03-06T23:29:13.268Z,1551914953.268 [NAL9602](INFO): Powering up NAL9602
2019-03-06T23:29:13.295Z,1551914953.295 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-06T23:29:13.296Z,1551914953.296 [ElevatorServo](INFO): Powering down
2019-03-06T23:29:13.404Z,1551914953.404 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-06T23:29:13.404Z,1551914953.404 [MassServo](INFO): Powering down
2019-03-06T23:29:13.483Z,1551914953.483 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T23:29:13.483Z,1551914953.483 [RudderServo](INFO): Powering down
2019-03-06T23:29:13.565Z,1551914953.565 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:29:13.579Z,1551914953.579 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-06T23:29:13.580Z,1551914953.580 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-06T23:29:13.582Z,1551914953.582 [CBIT](INFO): Critical error at 20190306T232912
2019-03-06T23:29:13.592Z,1551914953.592 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T23:29:13.593Z,1551914953.593 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-06T23:29:13.606Z,1551914953.606 [AHRS_M2](INFO): Powering down
2019-03-06T23:29:14.023Z,1551914954.023 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:29:14.144Z,1551914954.144 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-06T23:29:14.145Z,1551914954.145 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:29:14.264Z,1551914954.264 [MassServo](DEBUG): Initializing MassServo.
2019-03-06T23:29:14.265Z,1551914954.265 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:29:14.384Z,1551914954.384 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T23:29:14.711Z,1551914954.711 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-03-06T23:29:15.035Z,1551914955.035 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2019-03-06T23:29:15.035Z,1551914955.035 [ThrusterServo](FAULT): Thruster failed to initialize
2019-03-06T23:29:15.035Z,1551914955.035 [ThrusterServo] Communications Fault, FailCount= 2
2019-03-06T23:29:15.035Z,1551914955.035 [ThrusterServo](ERROR): Communications Fault
2019-03-06T23:29:15.039Z,1551914955.039 [CBIT](INFO): Clearing failed state for component ElevatorServo
2019-03-06T23:29:15.039Z,1551914955.039 [ElevatorServo] No Fault, FailCount= 1
2019-03-06T23:29:15.040Z,1551914955.040 [CBIT](INFO): Clearing failed state for component MassServo
2019-03-06T23:29:15.040Z,1551914955.040 [MassServo] No Fault, FailCount= 1
2019-03-06T23:29:15.040Z,1551914955.040 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-06T23:29:15.040Z,1551914955.040 [RudderServo] No Fault, FailCount= 1
2019-03-06T23:29:15.040Z,1551914955.040 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-03-06T23:29:15.535Z,1551914955.535 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2019-03-06T23:29:15.535Z,1551914955.535 [ElevatorServo](FAULT): Elevator failed to initialize
2019-03-06T23:29:15.535Z,1551914955.535 [ElevatorServo] Communications Fault, FailCount= 2
2019-03-06T23:29:15.535Z,1551914955.535 [ElevatorServo](ERROR): Communications Fault
2019-03-06T23:29:15.843Z,1551914955.843 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-06T23:29:15.843Z,1551914955.843 [RudderServo](FAULT): Rudder failed to initialize
2019-03-06T23:29:15.843Z,1551914955.843 [RudderServo] Communications Fault, FailCount= 2
2019-03-06T23:29:15.843Z,1551914955.843 [RudderServo](ERROR): Communications Fault
2019-03-06T23:29:15.843Z,1551914955.843 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-06T23:29:15.843Z,1551914955.843 [ThrusterServo](INFO): Powering down
2019-03-06T23:29:15.848Z,1551914955.848 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2019-03-06T23:29:15.848Z,1551914955.848 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-06T23:29:15.848Z,1551914955.848 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T23:29:15.849Z,1551914955.849 [AHRS_M2] No Fault, FailCount= 1
2019-03-06T23:29:15.854Z,1551914955.854 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T23:29:15.988Z,1551914955.988 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-06T23:29:15.989Z,1551914955.989 [ElevatorServo](INFO): Powering down
2019-03-06T23:29:15.990Z,1551914955.990 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T23:29:15.990Z,1551914955.990 [RudderServo](INFO): Powering down
2019-03-06T23:29:16.023Z,1551914956.023 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-03-06T23:29:16.023Z,1551914956.023 [BuoyancyServo] No Fault, FailCount= 1
2019-03-06T23:29:16.282Z,1551914956.282 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-06T23:29:16.400Z,1551914956.400 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-06T23:29:27.071Z,1551914967.071 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-03-06T23:29:27.071Z,1551914967.071 [CTD_Seabird] Communications Fault, FailCount= 2
2019-03-06T23:29:27.071Z,1551914967.071 [CTD_Seabird](ERROR): Communications Fault
2019-03-06T23:29:27.475Z,1551914967.475 [CTD_Seabird](INFO): Powering down
2019-03-06T23:29:28.504Z,1551914968.504 [CommandLine](IMPORTANT): got command restart application
2019-03-06T23:29:29.511Z,1551914969.511 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:29.511Z,1551914969.511 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.523Z,1551914969.523 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-06T23:29:29.523Z,1551914969.523 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.523Z,1551914969.523 [CommandLine](INFO): Join timeout helper Thread ID is 919
2019-03-06T23:29:29.524Z,1551914969.524 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-06T23:29:29.524Z,1551914969.524 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.524Z,1551914969.524 [NavChartDb](INFO): Join timeout helper Thread ID is 920
2019-03-06T23:29:29.611Z,1551914969.611 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:29.611Z,1551914969.611 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.623Z,1551914969.623 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-06T23:29:29.623Z,1551914969.623 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.623Z,1551914969.623 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 921
2019-03-06T23:29:29.679Z,1551914969.679 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:29.679Z,1551914969.679 [WetLabsBB2FL](INFO): Powering down
2019-03-06T23:29:29.680Z,1551914969.680 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.683Z,1551914969.683 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-03-06T23:29:29.683Z,1551914969.683 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:29.683Z,1551914969.683 [CTD_Seabird](INFO): Join timeout helper Thread ID is 922
2019-03-06T23:29:30.423Z,1551914970.423 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:30.423Z,1551914970.423 [CTD_Seabird](INFO): Powering down
2019-03-06T23:29:30.435Z,1551914970.435 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:30.443Z,1551914970.443 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-06T23:29:30.443Z,1551914970.443 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:30.443Z,1551914970.443 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 923
2019-03-06T23:29:30.639Z,1551914970.639 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:30.639Z,1551914970.639 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:30.648Z,1551914970.648 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-06T23:29:30.648Z,1551914970.648 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:30.648Z,1551914970.648 [Radio_Surface](INFO): Join timeout helper Thread ID is 924
2019-03-06T23:29:30.719Z,1551914970.719 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:30.719Z,1551914970.719 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:30.727Z,1551914970.727 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-06T23:29:30.727Z,1551914970.727 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:30.728Z,1551914970.728 [DataOverHttps](INFO): Join timeout helper Thread ID is 925
2019-03-06T23:29:31.111Z,1551914971.111 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:31.111Z,1551914971.111 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:31.127Z,1551914971.127 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-06T23:29:31.128Z,1551914971.128 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:31.128Z,1551914971.128 [logger](INFO): Join timeout helper Thread ID is 926
2019-03-06T23:29:31.207Z,1551914971.207 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:31.207Z,1551914971.207 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:31.227Z,1551914971.227 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-06T23:29:31.227Z,1551914971.227 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:31.228Z,1551914971.228 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-06T23:29:31.228Z,1551914971.228 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:31.228Z,1551914971.228 [controlThread](INFO): Join timeout helper Thread ID is 927
2019-03-06T23:29:46.520Z,1551914986.520 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-03-06T23:29:46.520Z,1551914986.520 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-06T23:29:46.520Z,1551914986.520 [SBIT](ERROR): Could not read massPosReader_.
2019-03-06T23:29:46.523Z,1551914986.523 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-03-06T23:29:46.523Z,1551914986.523 [ThrusterServo] No Fault, FailCount= 2
2019-03-06T23:29:46.524Z,1551914986.524 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-03-06T23:29:46.524Z,1551914986.524 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-03-06T23:29:46.524Z,1551914986.524 [CTD_Seabird] No Fault, FailCount= 2
2019-03-06T23:29:46.608Z,1551914986.608 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T23:29:46.608Z,1551914986.608 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-06T23:29:46.608Z,1551914986.608 [AHRS_M2](INFO): Powering down
2019-03-06T23:29:46.679Z,1551914986.679 [NAL9602](INFO): Powering down
2019-03-06T23:29:46.699Z,1551914986.699 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-06T23:29:46.700Z,1551914986.700 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-06T23:29:46.700Z,1551914986.700 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-06T23:29:46.701Z,1551914986.701 [MissionManager](INFO): Uninitializing Mission Default
2019-03-06T23:29:46.703Z,1551914986.703 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-06T23:29:46.704Z,1551914986.704 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-06T23:29:46.704Z,1551914986.704 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-06T23:29:46.705Z,1551914986.705 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-06T23:29:46.705Z,1551914986.705 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-06T23:29:46.705Z,1551914986.705 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-06T23:29:46.705Z,1551914986.705 [BuoyancyServo](INFO): Powering down
2019-03-06T23:29:46.855Z,1551914986.855 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-06T23:29:46.855Z,1551914986.855 [ElevatorServo](INFO): Powering down
2019-03-06T23:29:46.856Z,1551914986.856 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-06T23:29:46.856Z,1551914986.856 [MassServo](INFO): Powering down
2019-03-06T23:29:46.857Z,1551914986.857 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T23:29:46.857Z,1551914986.857 [RudderServo](INFO): Powering down
2019-03-06T23:29:46.857Z,1551914986.857 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-06T23:29:46.858Z,1551914986.858 [ThrusterServo](INFO): Powering down
2019-03-06T23:29:46.858Z,1551914986.858 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-06T23:29:46.859Z,1551914986.859 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-06T23:29:46.859Z,1551914986.859 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-06T23:29:46.859Z,1551914986.859 [CBIT](DEBUG): Powering off loads.
2019-03-06T23:29:46.871Z,1551914986.871 [CBIT](DEBUG): Disabling WDT.
2019-03-06T23:29:46.882Z,1551914986.882 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-06T23:29:46.883Z,1551914986.883 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.325Z,1551914987.325 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.331Z,1551914987.331 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.362Z,1551914987.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.365Z,1551914987.365 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.368Z,1551914987.368 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.396Z,1551914987.396 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T23:29:47.450Z,1551914987.450 [logger ThreadHandler](INFO): Thread cancelled.