2018-11-12T19:11:09.457Z,1542049869.457 [Supervisor](DEBUG): Initializing supervisor.
2018-11-12T19:11:09.459Z,1542049869.459 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-12T19:11:09.460Z,1542049869.460 [SyncHandler](INFO): Protected caller Thread ID is 801
2018-11-12T19:11:09.460Z,1542049869.460 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-12T19:11:09.461Z,1542049869.461 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-12T19:11:09.462Z,1542049869.462 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 802
2018-11-12T19:11:09.464Z,1542049869.464 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-12T19:11:09.476Z,1542049869.476 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-12T19:11:09.477Z,1542049869.477 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-12T19:11:09.477Z,1542049869.477 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 803
2018-11-12T19:11:09.478Z,1542049869.478 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-12T19:11:09.479Z,1542049869.479 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-12T19:11:09.479Z,1542049869.479 [logger ThreadHandler](INFO): Protected caller Thread ID is 804
2018-11-12T19:11:09.481Z,1542049869.481 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-12T19:11:09.481Z,1542049869.481 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-12T19:11:09.486Z,1542049869.486 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-12T19:11:09.928Z,1542049869.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-12T19:11:09.930Z,1542049869.930 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-12T19:11:10.378Z,1542049870.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-12T19:11:10.379Z,1542049870.379 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-12T19:11:10.573Z,1542049870.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-12T19:11:10.575Z,1542049870.575 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-12T19:11:10.722Z,1542049870.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-12T19:11:10.722Z,1542049870.722 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-12T19:11:11.162Z,1542049871.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-12T19:11:11.163Z,1542049871.163 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-12T19:11:11.260Z,1542049871.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-12T19:11:11.261Z,1542049871.261 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-12T19:11:11.403Z,1542049871.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-12T19:11:11.404Z,1542049871.404 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-12T19:11:11.506Z,1542049871.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-12T19:11:11.506Z,1542049871.506 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-12T19:11:11.611Z,1542049871.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-12T19:11:11.612Z,1542049871.612 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-12T19:11:11.958Z,1542049871.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-12T19:11:11.959Z,1542049871.959 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-12T19:11:12.163Z,1542049872.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-12T19:11:12.163Z,1542049872.163 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-12T19:11:12.377Z,1542049872.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-12T19:11:12.378Z,1542049872.378 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-12T19:11:12.769Z,1542049872.769 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-12T19:11:13.203Z,1542049873.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-12T19:11:13.204Z,1542049873.204 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-12T19:11:13.523Z,1542049873.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-12T19:11:13.525Z,1542049873.525 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-11-12T19:11:13.528Z,1542049873.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-11-12T19:11:13.614Z,1542049873.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-11-12T19:11:13.761Z,1542049873.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-11-12T19:11:13.861Z,1542049873.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-11-12T19:11:14.034Z,1542049874.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-11-12T19:11:14.117Z,1542049874.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-11-12T19:11:14.343Z,1542049874.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-12T19:11:14.344Z,1542049874.344 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-11-12T19:11:14.459Z,1542049874.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-11-12T19:11:14.560Z,1542049874.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-11-12T19:11:14.656Z,1542049874.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-11-12T19:11:14.760Z,1542049874.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-11-12T19:11:14.871Z,1542049874.871 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-11-12T19:11:14.874Z,1542049874.874 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-12T19:11:14.934Z,1542049874.934 [DepthRateCalculator] Loaded
2018-11-12T19:11:14.934Z,1542049874.934 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-12T19:11:14.940Z,1542049874.940 [PitchRateCalculator] Loaded
2018-11-12T19:11:14.940Z,1542049874.940 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-12T19:11:14.956Z,1542049874.956 [SpeedCalculator] Loaded
2018-11-12T19:11:14.956Z,1542049874.956 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-12T19:11:14.978Z,1542049874.978 [TempGradientCalculator] Loaded
2018-11-12T19:11:14.978Z,1542049874.978 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-12T19:11:14.984Z,1542049874.984 [YawRateCalculator] Loaded
2018-11-12T19:11:14.984Z,1542049874.984 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-12T19:11:15.025Z,1542049875.025 [ElevatorOffsetCalculator] Loaded
2018-11-12T19:11:15.025Z,1542049875.025 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-12T19:11:15.025Z,1542049875.025 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-12T19:11:15.026Z,1542049875.026 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-12T19:11:15.324Z,1542049875.324 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-12T19:11:15.324Z,1542049875.324 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-12T19:11:15.971Z,1542049875.971 [AHRS_M2] Loaded
2018-11-12T19:11:15.971Z,1542049875.971 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-11-12T19:11:16.109Z,1542049876.109 [DataOverHttps] Loaded
2018-11-12T19:11:16.109Z,1542049876.109 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-12T19:11:16.123Z,1542049876.123 [Depth_Keller] Loaded
2018-11-12T19:11:16.123Z,1542049876.123 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-12T19:11:16.128Z,1542049876.128 [DropWeight] Loaded
2018-11-12T19:11:16.128Z,1542049876.128 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-12T19:11:16.261Z,1542049876.261 [DVL_micro] Loaded
2018-11-12T19:11:16.261Z,1542049876.261 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2018-11-12T19:11:16.361Z,1542049876.361 [NAL9602] Loaded
2018-11-12T19:11:16.362Z,1542049876.362 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-12T19:11:16.378Z,1542049876.378 [Onboard] Loaded
2018-11-12T19:11:16.378Z,1542049876.378 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-12T19:11:16.389Z,1542049876.389 [Radio_Surface] Loaded
2018-11-12T19:11:16.389Z,1542049876.389 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-12T19:11:16.390Z,1542049876.390 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-11-12T19:11:16.391Z,1542049876.391 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2018-11-12T19:11:16.398Z,1542049876.398 [SCPI] Loaded
2018-11-12T19:11:16.398Z,1542049876.398 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-11-12T19:11:16.444Z,1542049876.444 [PNI_TCM] Loaded
2018-11-12T19:11:16.444Z,1542049876.444 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-12T19:11:18.243Z,1542049878.243 [BPC1] Loaded
2018-11-12T19:11:18.243Z,1542049878.243 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-12T19:11:18.244Z,1542049878.244 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-12T19:11:18.244Z,1542049878.244 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-12T19:11:18.579Z,1542049878.579 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-12T19:11:18.580Z,1542049878.580 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-12T19:11:18.766Z,1542049878.766 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-12T19:11:18.766Z,1542049878.766 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-12T19:11:18.821Z,1542049878.821 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-12T19:11:18.821Z,1542049878.821 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-12T19:11:18.838Z,1542049878.838 [NavChart] Loaded
2018-11-12T19:11:18.838Z,1542049878.838 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-12T19:11:18.842Z,1542049878.842 [UniversalFixResidualReporter] Loaded
2018-11-12T19:11:18.842Z,1542049878.842 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-12T19:11:18.843Z,1542049878.843 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-12T19:11:18.844Z,1542049878.844 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-12T19:11:19.057Z,1542049879.057 [Aanderaa_O2] Loaded
2018-11-12T19:11:19.057Z,1542049879.057 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-12T19:11:19.143Z,1542049879.143 [CTD_NeilBrown] Loaded
2018-11-12T19:11:19.143Z,1542049879.143 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-12T19:11:19.144Z,1542049879.144 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-11-12T19:11:19.145Z,1542049879.145 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 884
2018-11-12T19:11:19.202Z,1542049879.202 [CTD_Seabird] Loaded
2018-11-12T19:11:19.202Z,1542049879.202 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-12T19:11:19.203Z,1542049879.203 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-11-12T19:11:19.203Z,1542049879.203 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 885
2018-11-12T19:11:19.254Z,1542049879.254 [WetLabsBB2FL] Loaded
2018-11-12T19:11:19.254Z,1542049879.254 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-12T19:11:19.255Z,1542049879.255 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2018-11-12T19:11:19.256Z,1542049879.256 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 886
2018-11-12T19:11:19.256Z,1542049879.256 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-12T19:11:19.257Z,1542049879.257 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-12T19:11:19.350Z,1542049879.350 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-12T19:11:19.452Z,1542049879.452 [VerticalControl] Loaded
2018-11-12T19:11:19.452Z,1542049879.452 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-12T19:11:19.453Z,1542049879.453 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-12T19:11:19.523Z,1542049879.523 [HorizontalControl] Loaded
2018-11-12T19:11:19.523Z,1542049879.523 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-12T19:11:19.524Z,1542049879.524 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-12T19:11:19.529Z,1542049879.529 [SpeedControl] Loaded
2018-11-12T19:11:19.530Z,1542049879.530 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-12T19:11:19.530Z,1542049879.530 [LoopControl](DEBUG): Construct LoopControl.
2018-11-12T19:11:19.531Z,1542049879.531 [LoopControl] Loaded
2018-11-12T19:11:19.531Z,1542049879.531 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-12T19:11:19.532Z,1542049879.532 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-12T19:11:19.532Z,1542049879.532 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-12T19:11:19.556Z,1542049879.556 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-12T19:11:19.558Z,1542049879.558 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-12T19:11:19.721Z,1542049879.721 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-12T19:11:19.732Z,1542049879.732 [SBIT] Loaded
2018-11-12T19:11:19.733Z,1542049879.733 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-12T19:11:19.733Z,1542049879.733 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-12T19:11:19.745Z,1542049879.745 [IBIT] Loaded
2018-11-12T19:11:19.745Z,1542049879.745 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-12T19:11:19.748Z,1542049879.748 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-12T19:11:19.901Z,1542049879.901 [CBIT] Loaded
2018-11-12T19:11:19.902Z,1542049879.902 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-12T19:11:19.902Z,1542049879.902 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-12T19:11:19.903Z,1542049879.903 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-12T19:11:20.034Z,1542049880.034 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-12T19:11:20.035Z,1542049880.035 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-12T19:11:20.074Z,1542049880.074 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-12T19:11:20.075Z,1542049880.075 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-12T19:11:20.200Z,1542049880.200 [BuoyancyServo] Loaded
2018-11-12T19:11:20.200Z,1542049880.200 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-12T19:11:20.212Z,1542049880.212 [ElevatorServo] Loaded
2018-11-12T19:11:20.212Z,1542049880.212 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-12T19:11:20.223Z,1542049880.223 [MassServo] Loaded
2018-11-12T19:11:20.223Z,1542049880.223 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-12T19:11:20.235Z,1542049880.235 [RudderServo] Loaded
2018-11-12T19:11:20.235Z,1542049880.235 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-12T19:11:20.246Z,1542049880.246 [ThrusterServo] Loaded
2018-11-12T19:11:20.246Z,1542049880.246 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-12T19:11:20.247Z,1542049880.247 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-12T19:11:20.250Z,1542049880.250 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-12T19:11:20.251Z,1542049880.251 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-12T19:11:20.258Z,1542049880.258 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-12T19:11:20.259Z,1542049880.259 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-11-12T19:11:20.259Z,1542049880.259 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2018-11-12T19:11:20.264Z,1542049880.264 [Supervisor](INFO): Main Thread ID is 797
2018-11-12T19:11:20.264Z,1542049880.264 [Supervisor](DEBUG): Running supervisor.
2018-11-12T19:11:20.265Z,1542049880.265 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2018-11-12T19:11:20.267Z,1542049880.267 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2018-11-12T19:11:20.267Z,1542049880.267 [controlThread](DEBUG): Initializing ControlThread
2018-11-12T19:11:20.268Z,1542049880.268 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-12T19:11:20.268Z,1542049880.268 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-12T19:11:20.269Z,1542049880.269 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-12T19:11:20.269Z,1542049880.269 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-12T19:11:20.270Z,1542049880.270 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-12T19:11:20.270Z,1542049880.270 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-12T19:11:20.277Z,1542049880.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-12T19:11:20.278Z,1542049880.278 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-12T19:11:20.279Z,1542049880.279 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-12T19:11:20.279Z,1542049880.279 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-12T19:11:20.280Z,1542049880.280 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-12T19:11:20.280Z,1542049880.280 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-12T19:11:20.280Z,1542049880.280 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-12T19:11:20.281Z,1542049880.281 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-12T19:11:20.281Z,1542049880.281 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-12T19:11:20.283Z,1542049880.283 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-12T19:11:20.284Z,1542049880.284 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-12T19:11:20.284Z,1542049880.284 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-12T19:11:20.285Z,1542049880.285 [SBIT](INFO): Initialize SBIT Component.
2018-11-12T19:11:20.285Z,1542049880.285 [SBIT](IMPORTANT): git: 2018-10-09-34-gf593050
2018-11-12T19:11:20.285Z,1542049880.285 [SBIT](INFO): git hash: f5930504aac459d36ba66e787e98c9e35813b4f6
2018-11-12T19:11:20.286Z,1542049880.286 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-12T19:11:20.286Z,1542049880.286 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-12T19:11:20.287Z,1542049880.287 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-12T19:11:20.287Z,1542049880.287 [IBIT](INFO): Initialize IBIT Component.
2018-11-12T19:11:20.288Z,1542049880.288 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-12T19:11:20.288Z,1542049880.288 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-11-12T19:11:20.289Z,1542049880.289 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-12T19:11:20.289Z,1542049880.289 [logger ThreadHandler](INFO): Handler Thread ID is 890
2018-11-12T19:11:20.307Z,1542049880.307 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2018-11-12T19:11:20.313Z,1542049880.313 [Radio_Surface](INFO): Powering up
2018-11-12T19:11:20.319Z,1542049880.319 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 892
2018-11-12T19:11:20.331Z,1542049880.331 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 893
2018-11-12T19:11:20.331Z,1542049880.331 [CTD_Seabird](INFO): Initializing
2018-11-12T19:11:20.332Z,1542049880.332 [CTD_Seabird](INFO): Checking LCM
2018-11-12T19:11:20.396Z,1542049880.396 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-12T19:11:20.449Z,1542049880.449 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 894
2018-11-12T19:11:20.451Z,1542049880.451 [WetLabsBB2FL](INFO): Powering down
2018-11-12T19:11:20.453Z,1542049880.453 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895
2018-11-12T19:11:20.579Z,1542049880.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-12T19:11:20.579Z,1542049880.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-12T19:11:20.579Z,1542049880.579 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-12T19:11:20.579Z,1542049880.579 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-12T19:11:20.580Z,1542049880.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-12T19:11:20.580Z,1542049880.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-12T19:11:20.580Z,1542049880.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-12T19:11:20.580Z,1542049880.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-12T19:11:20.580Z,1542049880.580 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-12T19:11:20.580Z,1542049880.580 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-12T19:11:20.581Z,1542049880.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-12T19:11:20.581Z,1542049880.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-12T19:11:20.581Z,1542049880.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-12T19:11:20.581Z,1542049880.581 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-12T19:11:20.581Z,1542049880.581 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-12T19:11:20.582Z,1542049880.582 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-12T19:11:20.584Z,1542049880.584 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-12T19:11:20.682Z,1542049880.682 [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-11-12T19:11:20.686Z,1542049880.686 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-12T19:11:20.766Z,1542049880.766 [CTD_Seabird](INFO): LCM OK
2018-11-12T19:11:20.778Z,1542049880.778 [CTD_Seabird](INFO): Powering up
2018-11-12T19:11:20.812Z,1542049880.812 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-12T19:11:20.814Z,1542049880.814 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-12T19:11:20.816Z,1542049880.816 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-12T19:11:20.860Z,1542049880.860 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-12T19:11:20.863Z,1542049880.863 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-12T19:11:20.885Z,1542049880.885 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-12T19:11:20.888Z,1542049880.888 [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>


2018-11-12T19:11:20.904Z,1542049880.904 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,PNI_TCM,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-12T19:11:20.928Z,1542049880.928 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-11-12T19:11:21.056Z,1542049881.056 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-12T19:11:21.099Z,1542049881.099 [Depth_Keller](ERROR): Pressure reading out of range: 1827.005127 decibar
2018-11-12T19:11:21.125Z,1542049881.125 [DVL_micro](INFO): Initializing
2018-11-12T19:11:21.271Z,1542049881.271 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-12T19:11:21.510Z,1542049881.510 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:21.518Z,1542049881.518 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-12T19:11:21.533Z,1542049881.533 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:21.539Z,1542049881.539 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-12T19:11:21.558Z,1542049881.558 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:21.567Z,1542049881.567 [MassServo](DEBUG): Initializing MassServo.
2018-11-12T19:11:21.577Z,1542049881.577 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:21.582Z,1542049881.582 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-12T19:11:21.588Z,1542049881.588 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:21.594Z,1542049881.594 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-12T19:11:21.755Z,1542049881.755 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-12T19:11:21.755Z,1542049881.755 [DropWeight] Hardware Fault, FailCount= 1
2018-11-12T19:11:21.755Z,1542049881.755 [DropWeight](ERROR): Hardware Fault
2018-11-12T19:11:21.791Z,1542049881.791 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-12T19:11:21.818Z,1542049881.818 [CommandLine](FAULT): Scheduling is paused
2018-11-12T19:11:21.819Z,1542049881.819 [CBIT](INFO): Critical error at 20181112T191121
2018-11-12T19:11:21.819Z,1542049881.819 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-12T19:11:21.821Z,1542049881.821 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-12T19:11:21.838Z,1542049881.838 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-12T19:11:22.458Z,1542049882.458 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-12T19:11:22.458Z,1542049882.458 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-12T19:11:22.458Z,1542049882.458 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-12T19:11:22.459Z,1542049882.459 [BuoyancyServo](ERROR): Communications Fault
2018-11-12T19:11:22.770Z,1542049882.770 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2018-11-12T19:11:22.770Z,1542049882.770 [ElevatorServo](FAULT): Elevator failed to initialize
2018-11-12T19:11:22.770Z,1542049882.770 [ElevatorServo] Communications Fault, FailCount= 1
2018-11-12T19:11:22.771Z,1542049882.770 [ElevatorServo](ERROR): Communications Fault
2018-11-12T19:11:23.074Z,1542049883.074 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2018-11-12T19:11:23.074Z,1542049883.074 [RudderServo](FAULT): Rudder failed to initialize
2018-11-12T19:11:23.074Z,1542049883.074 [RudderServo] Communications Fault, FailCount= 1
2018-11-12T19:11:23.074Z,1542049883.074 [RudderServo](ERROR): Communications Fault
2018-11-12T19:11:23.398Z,1542049883.398 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-12T19:11:23.423Z,1542049883.423 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-12T19:11:23.424Z,1542049883.424 [ThrusterServo] Communications Fault, FailCount= 1
2018-11-12T19:11:23.424Z,1542049883.424 [ThrusterServo](ERROR): Communications Fault
2018-11-12T19:11:23.435Z,1542049883.435 [CBIT](INFO): Critical error at 20181112T191121
2018-11-12T19:11:23.443Z,1542049883.443 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-12T19:11:23.444Z,1542049883.444 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2018-11-12T19:11:23.445Z,1542049883.445 [CBIT](ERROR): Communications Fault in component: RudderServo
2018-11-12T19:11:23.455Z,1542049883.455 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-12T19:11:23.540Z,1542049883.540 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-12T19:11:23.542Z,1542049883.542 [BuoyancyServo](INFO): Powering down
2018-11-12T19:11:23.627Z,1542049883.627 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-12T19:11:23.627Z,1542049883.627 [ElevatorServo](INFO): Powering down
2018-11-12T19:11:23.693Z,1542049883.693 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-12T19:11:23.694Z,1542049883.694 [RudderServo](INFO): Powering down
2018-11-12T19:11:23.753Z,1542049883.753 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-12T19:11:23.753Z,1542049883.753 [ThrusterServo](INFO): Powering down
2018-11-12T19:11:24.358Z,1542049884.358 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-12T19:11:24.416Z,1542049884.416 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:24.535Z,1542049884.535 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-12T19:11:24.790Z,1542049884.790 [MassServo](FAULT): Mass initialization uart error:serial timeout
2018-11-12T19:11:24.790Z,1542049884.790 [MassServo] Communications Fault, FailCount= 1
2018-11-12T19:11:24.790Z,1542049884.790 [MassServo](ERROR): Communications Fault
2018-11-12T19:11:24.791Z,1542049884.791 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:24.911Z,1542049884.911 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-12T19:11:25.034Z,1542049885.034 [CBIT](INFO): Clearing failed state for component ElevatorServo
2018-11-12T19:11:25.034Z,1542049885.034 [ElevatorServo] No Fault, FailCount= 1
2018-11-12T19:11:25.035Z,1542049885.035 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-12T19:11:25.035Z,1542049885.035 [CBIT](INFO): Clearing failed state for component RudderServo
2018-11-12T19:11:25.035Z,1542049885.035 [RudderServo] No Fault, FailCount= 1
2018-11-12T19:11:25.035Z,1542049885.035 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-12T19:11:25.035Z,1542049885.035 [ThrusterServo] No Fault, FailCount= 1
2018-11-12T19:11:25.138Z,1542049885.138 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2018-11-12T19:11:25.506Z,1542049885.506 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2018-11-12T19:11:25.506Z,1542049885.506 [ElevatorServo](FAULT): Elevator failed to initialize
2018-11-12T19:11:25.506Z,1542049885.506 [ElevatorServo] Communications Fault, FailCount= 2
2018-11-12T19:11:25.506Z,1542049885.506 [ElevatorServo](ERROR): Communications Fault
2018-11-12T19:11:25.507Z,1542049885.507 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-12T19:11:25.507Z,1542049885.507 [MassServo](INFO): Powering down
2018-11-12T19:11:25.839Z,1542049885.839 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-12T19:11:25.863Z,1542049885.863 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2018-11-12T19:11:25.863Z,1542049885.863 [RudderServo](FAULT): Rudder failed to initialize
2018-11-12T19:11:25.863Z,1542049885.863 [RudderServo] Communications Fault, FailCount= 2
2018-11-12T19:11:25.863Z,1542049885.863 [RudderServo](ERROR): Communications Fault
2018-11-12T19:11:25.864Z,1542049885.864 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:25.867Z,1542049885.867 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-12T19:11:25.872Z,1542049885.872 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2018-11-12T19:11:25.872Z,1542049885.872 [CBIT](ERROR): Communications Fault in component: RudderServo
2018-11-12T19:11:25.968Z,1542049885.968 [Radio_Surface](INFO): Powering down
2018-11-12T19:11:26.001Z,1542049886.001 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-11-12T19:11:26.045Z,1542049886.045 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-12T19:11:26.046Z,1542049886.046 [ElevatorServo](INFO): Powering down
2018-11-12T19:11:26.060Z,1542049886.060 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-12T19:11:26.060Z,1542049886.060 [RudderServo](INFO): Powering down
2018-11-12T19:11:26.065Z,1542049886.065 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-12T19:11:26.065Z,1542049886.065 [BuoyancyServo] No Fault, FailCount= 1
2018-11-12T19:11:26.141Z,1542049886.141 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-11-12T19:11:26.346Z,1542049886.346 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-11-12T19:11:26.452Z,1542049886.452 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:26.452Z,1542049886.452 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-12T19:11:26.454Z,1542049886.454 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:26.470Z,1542049886.470 [MassServo](DEBUG): Initializing MassServo.
2018-11-12T19:11:26.536Z,1542049886.536 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-12T19:11:26.536Z,1542049886.536 [CTD_Seabird](INFO): Powering down
2018-11-12T19:11:26.794Z,1542049886.794 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-12T19:11:27.118Z,1542049887.118 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2018-11-12T19:11:27.118Z,1542049887.118 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-12T19:11:27.118Z,1542049887.118 [ThrusterServo] Communications Fault, FailCount= 2
2018-11-12T19:11:27.119Z,1542049887.119 [ThrusterServo](ERROR): Communications Fault
2018-11-12T19:11:27.123Z,1542049887.123 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-12T19:11:27.124Z,1542049887.124 [MassServo] No Fault, FailCount= 1
2018-11-12T19:11:27.124Z,1542049887.124 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-12T19:11:27.210Z,1542049887.210 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-11-12T19:11:27.598Z,1542049887.598 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-12T19:11:27.598Z,1542049887.598 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-12T19:11:27.599Z,1542049887.599 [BuoyancyServo] Communications Fault, FailCount= 2
2018-11-12T19:11:27.599Z,1542049887.599 [BuoyancyServo](ERROR): Communications Fault
2018-11-12T19:11:27.599Z,1542049887.599 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:27.719Z,1542049887.719 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-12T19:11:27.723Z,1542049887.723 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:27.843Z,1542049887.843 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-12T19:11:27.844Z,1542049887.844 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-12T19:11:27.844Z,1542049887.844 [ThrusterServo](INFO): Powering down
2018-11-12T19:11:27.849Z,1542049887.849 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-12T19:11:27.849Z,1542049887.849 [CBIT](INFO): Clearing failed state for component ElevatorServo
2018-11-12T19:11:27.849Z,1542049887.849 [ElevatorServo] No Fault, FailCount= 2
2018-11-12T19:11:27.850Z,1542049887.850 [CBIT](INFO): Clearing failed state for component RudderServo
2018-11-12T19:11:27.850Z,1542049887.850 [RudderServo] No Fault, FailCount= 2
2018-11-12T19:11:27.944Z,1542049887.944 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-12T19:11:27.954Z,1542049887.954 [BuoyancyServo](INFO): Powering down
2018-11-12T19:11:28.666Z,1542049888.666 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2018-11-12T19:11:28.666Z,1542049888.666 [ElevatorServo](FAULT): Elevator failed to initialize
2018-11-12T19:11:28.667Z,1542049888.667 [ElevatorServo] Communications Fault, FailCount= 3
2018-11-12T19:11:28.667Z,1542049888.667 [ElevatorServo](ERROR): Communications Fault
2018-11-12T19:11:28.978Z,1542049888.978 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2018-11-12T19:11:28.978Z,1542049888.978 [RudderServo](FAULT): Rudder failed to initialize
2018-11-12T19:11:28.979Z,1542049888.979 [RudderServo] Communications Fault, FailCount= 3
2018-11-12T19:11:28.979Z,1542049888.979 [RudderServo](ERROR): Communications Fault
2018-11-12T19:11:29.102Z,1542049889.102 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2018-11-12T19:11:29.103Z,1542049889.103 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo
2018-11-12T19:11:29.103Z,1542049889.103 [CBIT](ERROR): Communications Fault in component: RudderServo
2018-11-12T19:11:29.103Z,1542049889.103 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2018-11-12T19:11:29.104Z,1542049889.104 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-12T19:11:29.104Z,1542049889.104 [ThrusterServo] No Fault, FailCount= 2
2018-11-12T19:11:29.180Z,1542049889.180 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2018-11-12T19:11:29.181Z,1542049889.181 [PNI_TCM] Data Fault, FailCount= 1
2018-11-12T19:11:29.181Z,1542049889.181 [PNI_TCM](ERROR): Data Fault
2018-11-12T19:11:29.216Z,1542049889.216 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-12T19:11:29.216Z,1542049889.216 [ElevatorServo](INFO): Powering down
2018-11-12T19:11:29.230Z,1542049889.230 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-12T19:11:29.230Z,1542049889.230 [RudderServo](INFO): Powering down
2018-11-12T19:11:29.232Z,1542049889.232 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:29.238Z,1542049889.238 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-12T19:11:29.241Z,1542049889.241 [CBIT](INFO): Critical error at 20181112T191129
2018-11-12T19:11:29.243Z,1542049889.243 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-12T19:11:29.244Z,1542049889.244 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-12T19:11:29.822Z,1542049889.822 [MassServo](FAULT): Mass initialization uart error:serial timeout
2018-11-12T19:11:29.822Z,1542049889.822 [MassServo] Communications Fault, FailCount= 2
2018-11-12T19:11:29.822Z,1542049889.822 [MassServo](ERROR): Communications Fault
2018-11-12T19:11:30.146Z,1542049890.146 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-12T19:11:30.470Z,1542049890.470 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2018-11-12T19:11:30.470Z,1542049890.470 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-12T19:11:30.470Z,1542049890.470 [ThrusterServo] Communications Fault, FailCount= 3
2018-11-12T19:11:30.470Z,1542049890.470 [ThrusterServo](ERROR): Communications Fault
2018-11-12T19:11:30.472Z,1542049890.472 [CBIT](INFO): Critical error at 20181112T191129
2018-11-12T19:11:30.475Z,1542049890.475 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-12T19:11:30.476Z,1542049890.476 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-12T19:11:30.554Z,1542049890.554 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-11-12T19:11:30.602Z,1542049890.602 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:30.719Z,1542049890.719 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-12T19:11:30.720Z,1542049890.720 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-12T19:11:30.720Z,1542049890.720 [MassServo](INFO): Powering down
2018-11-12T19:11:30.721Z,1542049890.721 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:30.839Z,1542049890.839 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-12T19:11:30.840Z,1542049890.840 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-12T19:11:30.840Z,1542049890.840 [ThrusterServo](INFO): Powering down
2018-11-12T19:11:30.845Z,1542049890.845 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-12T19:11:30.845Z,1542049890.845 [BuoyancyServo] No Fault, FailCount= 2
2018-11-12T19:11:30.957Z,1542049890.957 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:31.075Z,1542049891.075 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-12T19:11:31.317Z,1542049891.317 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-12T19:11:31.317Z,1542049891.317 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-12T19:11:31.317Z,1542049891.317 [Aanderaa_O2](ERROR): Communications Fault
2018-11-12T19:11:31.329Z,1542049891.329 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:11:31.447Z,1542049891.447 [MassServo](DEBUG): Initializing MassServo.
2018-11-12T19:11:31.571Z,1542049891.571 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-12T19:11:31.571Z,1542049891.571 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-12T19:11:31.571Z,1542049891.571 [MassServo] No Fault, FailCount= 2
2018-11-12T19:11:31.571Z,1542049891.571 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-12T19:11:31.572Z,1542049891.572 [ThrusterServo] No Fault, FailCount= 3
2018-11-12T19:12:01.834Z,1542049921.834 [Aanderaa_O2](INFO): Powering down
2018-11-12T19:12:02.013Z,1542049922.013 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:02.022Z,1542049922.022 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-12T19:12:02.122Z,1542049922.122 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-11-12T19:12:02.134Z,1542049922.134 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2018-11-12T19:12:02.134Z,1542049922.134 [AHRS_M2] Data Fault, FailCount= 1
2018-11-12T19:12:02.134Z,1542049922.134 [AHRS_M2](ERROR): Data Fault
2018-11-12T19:12:02.140Z,1542049922.140 [NAL9602](INFO): Powering up NAL9602
2018-11-12T19:12:02.201Z,1542049922.201 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-12T19:12:02.209Z,1542049922.209 [CBIT](ERROR): Data Fault in component: AHRS_M2
2018-11-12T19:12:02.219Z,1542049922.219 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-12T19:12:02.462Z,1542049922.462 [AHRS_M2](INFO): Powering down
2018-11-12T19:12:02.902Z,1542049922.902 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-12T19:12:02.966Z,1542049922.966 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-12T19:12:02.966Z,1542049922.966 [ThrusterServo] Communications Fault, FailCount= 4
2018-11-12T19:12:02.966Z,1542049922.966 [ThrusterServo](ERROR): Communications Fault
2018-11-12T19:12:02.971Z,1542049922.971 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-12T19:12:02.971Z,1542049922.971 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-12T19:12:02.972Z,1542049922.972 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-12T19:12:03.085Z,1542049923.085 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-12T19:12:03.085Z,1542049923.085 [ThrusterServo](INFO): Powering down
2018-11-12T19:12:03.738Z,1542049923.738 [BuoyancyServo](FAULT): Buoyancy initialization uart error:serial timeout
2018-11-12T19:12:03.738Z,1542049923.738 [BuoyancyServo] Communications Fault, FailCount= 3
2018-11-12T19:12:03.738Z,1542049923.738 [BuoyancyServo](ERROR): Communications Fault
2018-11-12T19:12:03.994Z,1542049923.994 [MassServo](FAULT): Mass initialization uart error:serial timeout
2018-11-12T19:12:03.994Z,1542049923.994 [MassServo] Communications Fault, FailCount= 3
2018-11-12T19:12:03.994Z,1542049923.994 [MassServo](ERROR): Communications Fault
2018-11-12T19:12:03.999Z,1542049923.999 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-12T19:12:03.000Z,1542049924.000 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-12T19:12:04.176Z,1542049924.176 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-12T19:12:04.177Z,1542049924.177 [BuoyancyServo](INFO): Powering down
2018-11-12T19:12:04.198Z,1542049924.198 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-12T19:12:04.199Z,1542049924.199 [MassServo](INFO): Powering down
2018-11-12T19:12:04.323Z,1542049924.323 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-12T19:12:04.323Z,1542049924.323 [ThrusterServo] No Fault, FailCount= 4
2018-11-12T19:12:04.541Z,1542049924.541 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:04.546Z,1542049924.546 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-12T19:12:04.952Z,1542049924.952 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:05.067Z,1542049925.067 [MassServo](DEBUG): Initializing MassServo.
2018-11-12T19:12:05.386Z,1542049925.386 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-11-12T19:12:05.708Z,1542049925.708 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2018-11-12T19:12:05.708Z,1542049925.708 [ThrusterServo](FAULT): Thruster failed to initialize
2018-11-12T19:12:05.708Z,1542049925.708 [ThrusterServo] Communications Fault, FailCount= 5
2018-11-12T19:12:05.708Z,1542049925.708 [ThrusterServo](ERROR): Communications Fault
2018-11-12T19:12:05.713Z,1542049925.713 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-12T19:12:05.713Z,1542049925.713 [MassServo] No Fault, FailCount= 3
2018-11-12T19:12:05.713Z,1542049925.713 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-11-12T19:12:05.714Z,1542049925.714 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo
2018-11-12T19:12:05.893Z,1542049925.893 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-12T19:12:05.893Z,1542049925.893 [ThrusterServo](INFO): Powering down
2018-11-12T19:12:05.928Z,1542049925.928 [CBIT](INFO): Critical error at 20181112T191205
2018-11-12T19:12:06.291Z,1542049926.291 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-11-12T19:12:06.291Z,1542049926.291 [AHRS_M2] No Fault, FailCount= 1
2018-11-12T19:12:06.292Z,1542049926.292 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-12T19:12:06.292Z,1542049926.292 [BuoyancyServo] No Fault, FailCount= 3
2018-11-12T19:12:06.627Z,1542049926.627 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-11-12T19:12:06.700Z,1542049926.700 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:06.821Z,1542049926.821 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-12T19:12:07.410Z,1542049927.410 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2018-11-12T19:12:07.410Z,1542049927.410 [MassServo] Communications Fault, FailCount= 4
2018-11-12T19:12:07.410Z,1542049927.410 [MassServo](ERROR): Communications Fault
2018-11-12T19:12:07.415Z,1542049927.415 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-12T19:12:07.818Z,1542049927.818 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-12T19:12:07.818Z,1542049927.818 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-12T19:12:07.818Z,1542049927.818 [BuoyancyServo] Communications Fault, FailCount= 4
2018-11-12T19:12:07.818Z,1542049927.818 [BuoyancyServo](ERROR): Communications Fault
2018-11-12T19:12:07.819Z,1542049927.819 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-12T19:12:07.819Z,1542049927.819 [MassServo](INFO): Powering down
2018-11-12T19:12:07.824Z,1542049927.824 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-12T19:12:07.907Z,1542049927.907 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-12T19:12:07.908Z,1542049927.908 [BuoyancyServo](INFO): Powering down
2018-11-12T19:12:08.280Z,1542049928.280 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:08.395Z,1542049928.395 [MassServo](DEBUG): Initializing MassServo.
2018-11-12T19:12:08.400Z,1542049928.400 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-12T19:12:08.400Z,1542049928.400 [MassServo] No Fault, FailCount= 4
2018-11-12T19:12:09.909Z,1542049929.909 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-12T19:12:09.910Z,1542049929.910 [BuoyancyServo] No Fault, FailCount= 4
2018-11-12T19:12:10.314Z,1542049930.314 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-11-12T19:12:10.369Z,1542049930.369 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:10.483Z,1542049930.483 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-12T19:12:10.970Z,1542049930.970 [MassServo](FAULT): Mass initialization uart error:serial timeout
2018-11-12T19:12:10.970Z,1542049930.970 [MassServo] Communications Fault, FailCount= 5
2018-11-12T19:12:10.970Z,1542049930.970 [MassServo](ERROR): Communications Fault
2018-11-12T19:12:10.975Z,1542049930.975 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-12T19:12:10.975Z,1542049930.975 [CBIT](CRITICAL): Communications Fault in component: MassServo
2018-11-12T19:12:11.422Z,1542049931.422 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-12T19:12:11.422Z,1542049931.422 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-12T19:12:11.422Z,1542049931.422 [BuoyancyServo] Communications Fault, FailCount= 5
2018-11-12T19:12:11.422Z,1542049931.422 [BuoyancyServo](ERROR): Communications Fault
2018-11-12T19:12:11.423Z,1542049931.423 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-12T19:12:11.423Z,1542049931.423 [MassServo](INFO): Powering down
2018-11-12T19:12:11.426Z,1542049931.426 [CBIT](INFO): Critical error at 20181112T191210
2018-11-12T19:12:11.429Z,1542049931.429 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-12T19:12:11.565Z,1542049931.565 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-12T19:12:11.567Z,1542049931.567 [BuoyancyServo](INFO): Powering down
2018-11-12T19:12:11.977Z,1542049931.977 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:12.091Z,1542049932.091 [MassServo](DEBUG): Initializing MassServo.
2018-11-12T19:12:13.136Z,1542049933.136 [NAL9602](INFO): NAL9602 initialized
2018-11-12T19:12:13.164Z,1542049933.164 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-12T19:12:13.164Z,1542049933.164 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-12T19:12:13.164Z,1542049933.164 [Aanderaa_O2](ERROR): Communications Fault
2018-11-12T19:12:13.180Z,1542049933.180 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-12T19:12:33.491Z,1542049953.491 [CommandLine](IMPORTANT): got command failComponent
2018-11-12T19:12:33.491Z,1542049953.491 [CommandLine](IMPORTANT): Failed components:
2018-11-12T19:12:33.491Z,1542049953.491 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-12T19:12:33.492Z,1542049953.492 [CommandLine](IMPORTANT): PNI_TCM: Data Fault
2018-11-12T19:12:33.492Z,1542049953.492 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-11-12T19:12:33.492Z,1542049953.492 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2018-11-12T19:12:33.492Z,1542049953.492 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault
2018-11-12T19:12:33.493Z,1542049953.493 [CommandLine](IMPORTANT): MassServo: Communications Fault
2018-11-12T19:12:33.493Z,1542049953.493 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2018-11-12T19:12:33.493Z,1542049953.493 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault
2018-11-12T19:12:39.371Z,1542049959.371 [CommandLine](IMPORTANT): got command restart application
2018-11-12T19:12:40.377Z,1542049960.377 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:40.377Z,1542049960.377 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:40.566Z,1542049960.566 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-12T19:12:40.566Z,1542049960.566 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:40.567Z,1542049960.567 [CommandLine](INFO): Join timeout helper Thread ID is 931
2018-11-12T19:12:40.567Z,1542049960.567 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-12T19:12:40.568Z,1542049960.568 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:40.568Z,1542049960.568 [NavChartDb](INFO): Join timeout helper Thread ID is 932
2018-11-12T19:12:40.954Z,1542049960.954 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:40.955Z,1542049960.955 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:40.966Z,1542049960.966 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-12T19:12:40.966Z,1542049960.966 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:40.967Z,1542049960.967 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 933
2018-11-12T19:12:41.222Z,1542049961.222 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:41.223Z,1542049961.223 [WetLabsBB2FL](INFO): Powering down
2018-11-12T19:12:41.223Z,1542049961.223 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.226Z,1542049961.226 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-11-12T19:12:41.226Z,1542049961.226 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.227Z,1542049961.227 [CTD_Seabird](INFO): Join timeout helper Thread ID is 934
2018-11-12T19:12:41.294Z,1542049961.294 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:41.381Z,1542049961.381 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-12T19:12:41.381Z,1542049961.381 [CTD_Seabird](INFO): Powering down
2018-11-12T19:12:41.383Z,1542049961.383 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.384Z,1542049961.384 [Aanderaa_O2](INFO): Powering down
2018-11-12T19:12:41.386Z,1542049961.386 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-12T19:12:41.386Z,1542049961.386 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.387Z,1542049961.387 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 936
2018-11-12T19:12:41.391Z,1542049961.391 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:41.391Z,1542049961.391 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.415Z,1542049961.415 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-12T19:12:41.415Z,1542049961.415 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.416Z,1542049961.416 [Radio_Surface](INFO): Join timeout helper Thread ID is 937
2018-11-12T19:12:41.426Z,1542049961.426 [SBIT](ERROR): Could not read elevatorAngleReader_.
2018-11-12T19:12:41.426Z,1542049961.426 [SBIT](ERROR): Could not read rudderAngleReader_.
2018-11-12T19:12:41.430Z,1542049961.430 [SBIT](ERROR): Could not read massPosReader_.
2018-11-12T19:12:41.437Z,1542049961.437 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-12T19:12:41.437Z,1542049961.437 [BuoyancyServo] No Fault, FailCount= 5
2018-11-12T19:12:41.438Z,1542049961.438 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-11-12T19:12:41.438Z,1542049961.438 [ThrusterServo] No Fault, FailCount= 5
2018-11-12T19:12:41.534Z,1542049961.534 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2018-11-12T19:12:41.534Z,1542049961.534 [AHRS_M2] Data Fault, FailCount= 2
2018-11-12T19:12:41.534Z,1542049961.534 [AHRS_M2](ERROR): Data Fault
2018-11-12T19:12:41.539Z,1542049961.539 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:41.539Z,1542049961.539 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.547Z,1542049961.547 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-12T19:12:41.547Z,1542049961.547 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.547Z,1542049961.547 [logger](INFO): Join timeout helper Thread ID is 938
2018-11-12T19:12:41.554Z,1542049961.554 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:41.554Z,1542049961.554 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.568Z,1542049961.568 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-12T19:12:41.568Z,1542049961.568 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.568Z,1542049961.568 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-12T19:12:41.569Z,1542049961.569 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.569Z,1542049961.569 [controlThread](INFO): Join timeout helper Thread ID is 939
2018-11-12T19:12:41.588Z,1542049961.588 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:41.707Z,1542049961.707 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-12T19:12:41.708Z,1542049961.708 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-12T19:12:41.709Z,1542049961.709 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-12T19:12:41.715Z,1542049961.715 [CBIT](ERROR): Data Fault in component: AHRS_M2
2018-11-12T19:12:41.720Z,1542049961.720 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.019897
CHAN A1 (24V): 0.001083
CHAN A2 (12V): -0.005328
CHAN A3 (5V): -0.002198
CHAN B0 (3.3V): -0.001082
CHAN B1 (3.15aV): -0.000969
CHAN B2 (3.15bV): -0.001181
CHAN B3 (GND): -0.000701
OPEN: -0.001347
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-12T19:12:41.722Z,1542049961.722 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-12T19:12:41.723Z,1542049961.723 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-12T19:12:41.723Z,1542049961.723 [AHRS_M2](INFO): Powering down
2018-11-12T19:12:41.795Z,1542049961.795 [DVL_micro](INFO): uninitialize:Powering down
2018-11-12T19:12:41.796Z,1542049961.796 [NAL9602](INFO): Powering down
2018-11-12T19:12:41.808Z,1542049961.808 [Aanderaa_O2](INFO): Powering down
2018-11-12T19:12:41.809Z,1542049961.809 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-12T19:12:41.811Z,1542049961.811 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-12T19:12:41.811Z,1542049961.811 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-12T19:12:41.812Z,1542049961.812 [MissionManager](INFO): Uninitializing Mission Default
2018-11-12T19:12:41.815Z,1542049961.815 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-12T19:12:41.816Z,1542049961.816 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-12T19:12:41.816Z,1542049961.816 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-12T19:12:41.817Z,1542049961.817 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-12T19:12:41.817Z,1542049961.817 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-12T19:12:41.817Z,1542049961.817 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-12T19:12:41.818Z,1542049961.818 [BuoyancyServo](INFO): Powering down
2018-11-12T19:12:41.834Z,1542049961.834 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-12T19:12:41.834Z,1542049961.834 [ElevatorServo](INFO): Powering down
2018-11-12T19:12:41.835Z,1542049961.835 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-12T19:12:41.835Z,1542049961.835 [MassServo](INFO): Powering down
2018-11-12T19:12:41.836Z,1542049961.836 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-12T19:12:41.836Z,1542049961.836 [RudderServo](INFO): Powering down
2018-11-12T19:12:41.837Z,1542049961.837 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-12T19:12:41.837Z,1542049961.837 [ThrusterServo](INFO): Powering down
2018-11-12T19:12:41.838Z,1542049961.838 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-12T19:12:41.839Z,1542049961.839 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-12T19:12:41.839Z,1542049961.839 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-12T19:12:41.840Z,1542049961.840 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.930Z,1542049961.930 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.973Z,1542049961.973 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.976Z,1542049961.976 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:41.979Z,1542049961.979 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:42.623Z,1542049962.623 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-12T19:12:42.681Z,1542049962.681 [logger ThreadHandler](INFO): Thread cancelled.