2018-11-16T20:36:16.655Z,1542400576.655 [Supervisor](DEBUG): Initializing supervisor.
2018-11-16T20:36:16.658Z,1542400576.658 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-16T20:36:16.659Z,1542400576.659 [SyncHandler](INFO): Protected caller Thread ID is 919
2018-11-16T20:36:16.660Z,1542400576.660 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-16T20:36:16.661Z,1542400576.661 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-16T20:36:16.661Z,1542400576.661 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 920
2018-11-16T20:36:16.664Z,1542400576.664 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-16T20:36:16.676Z,1542400576.676 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-16T20:36:16.677Z,1542400576.677 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-16T20:36:16.678Z,1542400576.678 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 921
2018-11-16T20:36:16.679Z,1542400576.679 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-16T20:36:16.680Z,1542400576.680 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-16T20:36:16.680Z,1542400576.680 [logger ThreadHandler](INFO): Protected caller Thread ID is 922
2018-11-16T20:36:16.682Z,1542400576.682 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-16T20:36:16.682Z,1542400576.682 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-16T20:36:16.684Z,1542400576.684 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-16T20:36:17.160Z,1542400577.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-16T20:36:17.161Z,1542400577.161 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-16T20:36:17.665Z,1542400577.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-16T20:36:17.666Z,1542400577.666 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-16T20:36:17.940Z,1542400577.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-16T20:36:17.941Z,1542400577.941 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-16T20:36:18.096Z,1542400578.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-16T20:36:18.097Z,1542400578.097 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-16T20:36:18.618Z,1542400578.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-16T20:36:18.619Z,1542400578.619 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-16T20:36:18.718Z,1542400578.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-16T20:36:18.719Z,1542400578.719 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-16T20:36:18.869Z,1542400578.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-16T20:36:18.870Z,1542400578.870 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-16T20:36:18.974Z,1542400578.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-16T20:36:18.974Z,1542400578.974 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-16T20:36:19.082Z,1542400579.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-16T20:36:19.083Z,1542400579.083 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-16T20:36:19.458Z,1542400579.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-16T20:36:19.458Z,1542400579.458 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-16T20:36:19.680Z,1542400579.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-16T20:36:19.681Z,1542400579.681 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-16T20:36:19.911Z,1542400579.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-16T20:36:19.912Z,1542400579.912 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-16T20:36:19.994Z,1542400579.994 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-16T20:36:20.381Z,1542400580.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-16T20:36:20.381Z,1542400580.381 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-16T20:36:20.770Z,1542400580.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-16T20:36:20.772Z,1542400580.772 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-11-16T20:36:20.773Z,1542400580.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-11-16T20:36:20.917Z,1542400580.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-11-16T20:36:21.080Z,1542400581.080 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-11-16T20:36:21.184Z,1542400581.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-11-16T20:36:21.372Z,1542400581.372 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-11-16T20:36:21.457Z,1542400581.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-11-16T20:36:21.692Z,1542400581.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-16T20:36:21.692Z,1542400581.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-11-16T20:36:21.813Z,1542400581.813 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-11-16T20:36:21.919Z,1542400581.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-11-16T20:36:22.018Z,1542400582.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-11-16T20:36:22.126Z,1542400582.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-11-16T20:36:22.244Z,1542400582.244 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2018-11-16T20:36:22.245Z,1542400582.245 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-16T20:36:22.249Z,1542400582.249 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-16T20:36:22.293Z,1542400582.293 [DepthRateCalculator] Loaded
2018-11-16T20:36:22.293Z,1542400582.293 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-16T20:36:22.300Z,1542400582.300 [PitchRateCalculator] Loaded
2018-11-16T20:36:22.300Z,1542400582.300 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-16T20:36:22.316Z,1542400582.316 [SpeedCalculator] Loaded
2018-11-16T20:36:22.316Z,1542400582.316 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-16T20:36:22.338Z,1542400582.338 [TempGradientCalculator] Loaded
2018-11-16T20:36:22.338Z,1542400582.338 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-16T20:36:22.345Z,1542400582.345 [YawRateCalculator] Loaded
2018-11-16T20:36:22.345Z,1542400582.345 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-16T20:36:22.386Z,1542400582.386 [ElevatorOffsetCalculator] Loaded
2018-11-16T20:36:22.386Z,1542400582.386 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-16T20:36:22.387Z,1542400582.387 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-16T20:36:22.387Z,1542400582.387 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-16T20:36:22.627Z,1542400582.627 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-16T20:36:22.628Z,1542400582.628 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-16T20:36:22.923Z,1542400582.923 [DataOverHttps] Loaded
2018-11-16T20:36:22.924Z,1542400582.924 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-16T20:36:22.948Z,1542400582.948 [Depth_Keller] Loaded
2018-11-16T20:36:22.949Z,1542400582.949 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-16T20:36:22.953Z,1542400582.953 [DropWeight] Loaded
2018-11-16T20:36:22.954Z,1542400582.954 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-16T20:36:23.054Z,1542400583.054 [NAL9602] Loaded
2018-11-16T20:36:23.054Z,1542400583.054 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-16T20:36:23.070Z,1542400583.070 [Onboard] Loaded
2018-11-16T20:36:23.071Z,1542400583.071 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-16T20:36:23.081Z,1542400583.081 [Radio_Surface] Loaded
2018-11-16T20:36:23.081Z,1542400583.081 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-16T20:36:23.082Z,1542400583.082 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-11-16T20:36:23.083Z,1542400583.083 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1002
2018-11-16T20:36:25.301Z,1542400585.301 [BPC1] Loaded
2018-11-16T20:36:25.301Z,1542400585.301 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-16T20:36:25.301Z,1542400585.301 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-16T20:36:25.302Z,1542400585.302 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-16T20:36:25.369Z,1542400585.369 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-16T20:36:25.369Z,1542400585.369 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-16T20:36:25.466Z,1542400585.466 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-16T20:36:25.467Z,1542400585.467 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-16T20:36:25.523Z,1542400585.523 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-16T20:36:25.523Z,1542400585.523 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-16T20:36:25.540Z,1542400585.540 [NavChart] Loaded
2018-11-16T20:36:25.540Z,1542400585.540 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-16T20:36:25.544Z,1542400585.544 [UniversalFixResidualReporter] Loaded
2018-11-16T20:36:25.545Z,1542400585.545 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-16T20:36:25.545Z,1542400585.545 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-16T20:36:25.546Z,1542400585.546 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-16T20:36:25.772Z,1542400585.772 [CTD_NeilBrown] Loaded
2018-11-16T20:36:25.772Z,1542400585.772 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-16T20:36:25.773Z,1542400585.773 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CF4E0
2018-11-16T20:36:25.773Z,1542400585.773 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1003
2018-11-16T20:36:25.831Z,1542400585.831 [CTD_Seabird] Loaded
2018-11-16T20:36:25.831Z,1542400585.831 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-16T20:36:25.832Z,1542400585.832 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FF4E0
2018-11-16T20:36:25.833Z,1542400585.833 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1004
2018-11-16T20:36:25.883Z,1542400585.883 [WetLabsBB2FL] Loaded
2018-11-16T20:36:25.884Z,1542400585.884 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-16T20:36:25.885Z,1542400585.885 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092F4E0
2018-11-16T20:36:25.885Z,1542400585.885 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1005
2018-11-16T20:36:25.886Z,1542400585.886 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-16T20:36:25.886Z,1542400585.886 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-16T20:36:25.956Z,1542400585.956 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-16T20:36:26.058Z,1542400586.058 [VerticalControl] Loaded
2018-11-16T20:36:26.059Z,1542400586.059 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-16T20:36:26.059Z,1542400586.059 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-16T20:36:26.130Z,1542400586.130 [HorizontalControl] Loaded
2018-11-16T20:36:26.130Z,1542400586.130 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-16T20:36:26.130Z,1542400586.130 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-16T20:36:26.136Z,1542400586.136 [SpeedControl] Loaded
2018-11-16T20:36:26.136Z,1542400586.136 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-16T20:36:26.137Z,1542400586.137 [LoopControl](DEBUG): Construct LoopControl.
2018-11-16T20:36:26.137Z,1542400586.137 [LoopControl] Loaded
2018-11-16T20:36:26.138Z,1542400586.138 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-16T20:36:26.138Z,1542400586.138 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-16T20:36:26.139Z,1542400586.139 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-16T20:36:26.152Z,1542400586.152 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-16T20:36:26.152Z,1542400586.152 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-16T20:36:26.283Z,1542400586.283 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-16T20:36:26.295Z,1542400586.295 [SBIT] Loaded
2018-11-16T20:36:26.295Z,1542400586.295 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-16T20:36:26.296Z,1542400586.296 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-16T20:36:26.308Z,1542400586.308 [IBIT] Loaded
2018-11-16T20:36:26.308Z,1542400586.308 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-16T20:36:26.311Z,1542400586.311 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-16T20:36:26.465Z,1542400586.465 [CBIT] Loaded
2018-11-16T20:36:26.466Z,1542400586.466 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-16T20:36:26.466Z,1542400586.466 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-16T20:36:26.467Z,1542400586.467 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-16T20:36:26.570Z,1542400586.570 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-16T20:36:26.570Z,1542400586.570 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-16T20:36:26.595Z,1542400586.595 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-16T20:36:26.595Z,1542400586.595 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-16T20:36:26.693Z,1542400586.693 [BuoyancyServo] Loaded
2018-11-16T20:36:26.694Z,1542400586.694 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-16T20:36:26.706Z,1542400586.706 [ElevatorServo] Loaded
2018-11-16T20:36:26.706Z,1542400586.706 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-16T20:36:26.717Z,1542400586.717 [MassServo] Loaded
2018-11-16T20:36:26.718Z,1542400586.718 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-16T20:36:26.729Z,1542400586.729 [RudderServo] Loaded
2018-11-16T20:36:26.730Z,1542400586.730 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-16T20:36:26.741Z,1542400586.741 [ThrusterServo] Loaded
2018-11-16T20:36:26.741Z,1542400586.741 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-16T20:36:26.742Z,1542400586.742 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-16T20:36:26.745Z,1542400586.745 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-16T20:36:26.746Z,1542400586.746 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-16T20:36:26.753Z,1542400586.753 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-16T20:36:26.754Z,1542400586.754 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2018-11-16T20:36:26.754Z,1542400586.754 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1006
2018-11-16T20:36:26.759Z,1542400586.759 [Supervisor](INFO): Main Thread ID is 795
2018-11-16T20:36:26.759Z,1542400586.759 [Supervisor](DEBUG): Running supervisor.
2018-11-16T20:36:26.760Z,1542400586.760 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1007
2018-11-16T20:36:26.762Z,1542400586.762 [controlThread ThreadHandler](INFO): Handler Thread ID is 1008
2018-11-16T20:36:26.763Z,1542400586.763 [controlThread](DEBUG): Initializing ControlThread
2018-11-16T20:36:26.763Z,1542400586.763 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-16T20:36:26.764Z,1542400586.764 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-16T20:36:26.764Z,1542400586.764 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-16T20:36:26.765Z,1542400586.765 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-16T20:36:26.765Z,1542400586.765 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-16T20:36:26.766Z,1542400586.766 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-16T20:36:26.771Z,1542400586.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-16T20:36:26.772Z,1542400586.772 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-16T20:36:26.772Z,1542400586.772 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-16T20:36:26.773Z,1542400586.773 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-16T20:36:26.773Z,1542400586.773 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-16T20:36:26.774Z,1542400586.774 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-16T20:36:26.774Z,1542400586.774 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-16T20:36:26.774Z,1542400586.774 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-16T20:36:26.775Z,1542400586.775 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-16T20:36:26.777Z,1542400586.777 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-16T20:36:26.777Z,1542400586.777 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-16T20:36:26.778Z,1542400586.778 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-16T20:36:26.778Z,1542400586.778 [SBIT](INFO): Initialize SBIT Component.
2018-11-16T20:36:26.779Z,1542400586.779 [SBIT](IMPORTANT): git: 2018-11-05-3-g424fd37
2018-11-16T20:36:26.779Z,1542400586.779 [SBIT](INFO): git hash: 424fd377e53a63ada65ca308b07209cf4fd40d4d
2018-11-16T20:36:26.779Z,1542400586.779 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-16T20:36:26.780Z,1542400586.780 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-16T20:36:26.781Z,1542400586.781 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-16T20:36:26.781Z,1542400586.781 [IBIT](INFO): Initialize IBIT Component.
2018-11-16T20:36:26.782Z,1542400586.782 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-16T20:36:26.782Z,1542400586.782 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-16T20:36:26.783Z,1542400586.783 [logger ThreadHandler](INFO): Handler Thread ID is 1009
2018-11-16T20:36:26.801Z,1542400586.801 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1010
2018-11-16T20:36:26.807Z,1542400586.807 [Radio_Surface](INFO): Powering up
2018-11-16T20:36:26.813Z,1542400586.813 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1011
2018-11-16T20:36:26.825Z,1542400586.825 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1012
2018-11-16T20:36:26.826Z,1542400586.826 [CTD_Seabird](INFO): Initializing
2018-11-16T20:36:26.826Z,1542400586.826 [CTD_Seabird](INFO): Checking LCM
2018-11-16T20:36:26.835Z,1542400586.835 [CTD_Seabird](INFO): LCM OK
2018-11-16T20:36:26.835Z,1542400586.835 [CTD_Seabird](INFO): Powering up
2018-11-16T20:36:26.845Z,1542400586.845 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1013
2018-11-16T20:36:26.846Z,1542400586.846 [WetLabsBB2FL](INFO): Powering down
2018-11-16T20:36:26.874Z,1542400586.874 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1014
2018-11-16T20:36:26.877Z,1542400586.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-16T20:36:26.877Z,1542400586.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-16T20:36:26.877Z,1542400586.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-16T20:36:26.877Z,1542400586.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-16T20:36:26.878Z,1542400586.878 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-16T20:36:26.878Z,1542400586.878 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-16T20:36:26.878Z,1542400586.878 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-16T20:36:26.878Z,1542400586.878 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-16T20:36:26.878Z,1542400586.878 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-16T20:36:26.879Z,1542400586.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-16T20:36:26.879Z,1542400586.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-16T20:36:26.879Z,1542400586.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-16T20:36:26.879Z,1542400586.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-16T20:36:26.879Z,1542400586.879 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-16T20:36:26.879Z,1542400586.879 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-16T20:36:26.880Z,1542400586.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-16T20:36:26.891Z,1542400586.891 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-16T20:36:26.901Z,1542400586.901 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-16T20:36:26.925Z,1542400586.925 [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-16T20:36:26.926Z,1542400586.926 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-16T20:36:27.008Z,1542400587.008 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-16T20:36:27.025Z,1542400587.025 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-16T20:36:27.027Z,1542400587.027 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-16T20:36:27.047Z,1542400587.047 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-16T20:36:27.078Z,1542400587.078 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-16T20:36:27.083Z,1542400587.083 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-16T20:36:27.103Z,1542400587.103 [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-16T20:36:27.108Z,1542400587.108 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,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,
2018-11-16T20:36:27.136Z,1542400587.136 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-16T20:36:27.198Z,1542400587.198 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2018-11-16T20:36:27.315Z,1542400587.315 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-16T20:36:27.532Z,1542400587.532 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:36:27.541Z,1542400587.541 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-16T20:36:27.563Z,1542400587.563 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:36:27.569Z,1542400587.569 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-16T20:36:27.575Z,1542400587.575 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:36:27.581Z,1542400587.581 [MassServo](DEBUG): Initializing MassServo.
2018-11-16T20:36:27.587Z,1542400587.587 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:36:27.593Z,1542400587.593 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-16T20:36:27.615Z,1542400587.615 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:36:27.621Z,1542400587.621 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-16T20:36:27.759Z,1542400587.759 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-16T20:36:27.759Z,1542400587.759 [DropWeight] Hardware Fault, FailCount= 1
2018-11-16T20:36:27.759Z,1542400587.759 [DropWeight](ERROR): Hardware Fault
2018-11-16T20:36:27.805Z,1542400587.805 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-16T20:36:27.834Z,1542400587.834 [CommandLine](FAULT): Scheduling is paused
2018-11-16T20:36:27.834Z,1542400587.834 [CBIT](INFO): Critical error at 20181116T203627
2018-11-16T20:36:27.835Z,1542400587.835 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-16T20:36:27.845Z,1542400587.845 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-16T20:36:27.846Z,1542400587.846 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-16T20:36:28.465Z,1542400588.465 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-16T20:36:28.465Z,1542400588.465 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-16T20:36:28.465Z,1542400588.465 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-16T20:36:28.465Z,1542400588.465 [BuoyancyServo](ERROR): Communications Fault
2018-11-16T20:36:28.675Z,1542400588.675 [CBIT](INFO): Critical error at 20181116T203627
2018-11-16T20:36:28.677Z,1542400588.677 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-16T20:36:30.922Z,1542400590.922 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-16T20:36:30.922Z,1542400590.922 [BuoyancyServo] No Fault, FailCount= 1
2018-11-16T20:36:31.135Z,1542400591.135 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:36:31.254Z,1542400591.254 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-16T20:36:32.356Z,1542400592.356 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-16T20:36:32.492Z,1542400592.492 [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-16T20:36:32.534Z,1542400592.534 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-11-16T20:36:32.891Z,1542400592.891 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-16T20:36:32.891Z,1542400592.891 [CTD_Seabird](INFO): Powering down
2018-11-16T20:36:53.090Z,1542400613.090 [NAL9602](INFO): Powering up NAL9602
2018-11-16T20:36:55.538Z,1542400615.538 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-16T20:36:55.563Z,1542400615.563 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-16T20:37:03.994Z,1542400623.994 [NAL9602](INFO): NAL9602 initialized
2018-11-16T20:37:06.482Z,1542400626.482 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008191
CHAN A1 (24V): 0.001786
CHAN A2 (12V): -0.006162
CHAN A3 (5V): -0.002537
CHAN B0 (3.3V): -0.001045
CHAN B1 (3.15aV): -0.001696
CHAN B2 (3.15bV): -0.001116
CHAN B3 (GND): -0.000366
OPEN: -0.000991
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-16T20:37:34.167Z,1542400654.167 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2018-11-16T20:37:38.473Z,1542400658.473 [MassServo](ERROR): getPosition uart error serial timeout
2018-11-16T20:37:38.473Z,1542400658.473 [MassServo](FAULT): Invalid EZ Servo response:""
2018-11-16T20:37:38.473Z,1542400658.473 [MassServo] Communications Fault, FailCount= 1
2018-11-16T20:37:38.473Z,1542400658.473 [MassServo](ERROR): Communications Fault
2018-11-16T20:37:38.778Z,1542400658.778 [RudderServo](ERROR): getPosition uart error serial timeout
2018-11-16T20:37:38.778Z,1542400658.778 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2018-11-16T20:37:38.778Z,1542400658.778 [RudderServo] Communications Fault, FailCount= 1
2018-11-16T20:37:38.778Z,1542400658.778 [RudderServo](ERROR): Communications Fault
2018-11-16T20:37:38.783Z,1542400658.783 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-16T20:37:38.783Z,1542400658.783 [CBIT](ERROR): Communications Fault in component: RudderServo
2018-11-16T20:37:39.201Z,1542400659.201 [ElevatorServo](ERROR): getPosition uart error serial timeout
2018-11-16T20:37:39.201Z,1542400659.201 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2018-11-16T20:37:39.201Z,1542400659.201 [ElevatorServo] Communications Fault, FailCount= 1
2018-11-16T20:37:39.201Z,1542400659.201 [ElevatorServo](ERROR): Communications Fault
2018-11-16T20:37:39.202Z,1542400659.202 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-16T20:37:39.202Z,1542400659.202 [MassServo](INFO): Powering down
2018-11-16T20:37:39.203Z,1542400659.203 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-16T20:37:39.203Z,1542400659.203 [RudderServo](INFO): Powering down
2018-11-16T20:37:39.209Z,1542400659.209 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2018-11-16T20:37:39.289Z,1542400659.289 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-16T20:37:39.289Z,1542400659.289 [ElevatorServo](INFO): Powering down
2018-11-16T20:37:39.661Z,1542400659.661 [ElevatorServo](FAULT): LCB fault:  Current Limiter Activated.
2018-11-16T20:37:39.661Z,1542400659.661 [ElevatorServo] Hardware Fault, FailCount= 1
2018-11-16T20:37:39.661Z,1542400659.661 [ElevatorServo](ERROR): Hardware Fault
2018-11-16T20:37:39.662Z,1542400659.662 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:37:39.778Z,1542400659.778 [MassServo](DEBUG): Initializing MassServo.
2018-11-16T20:37:39.779Z,1542400659.779 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:37:39.898Z,1542400659.898 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-16T20:37:39.904Z,1542400659.904 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-16T20:37:39.904Z,1542400659.904 [MassServo] No Fault, FailCount= 1
2018-11-16T20:37:39.904Z,1542400659.904 [CBIT](INFO): Clearing failed state for component RudderServo
2018-11-16T20:37:39.904Z,1542400659.904 [RudderServo] No Fault, FailCount= 1
2018-11-16T20:37:40.062Z,1542400660.062 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:37:40.178Z,1542400660.178 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-16T20:37:40.184Z,1542400660.184 [CBIT](INFO): Clearing failed state for component ElevatorServo
2018-11-16T20:37:40.184Z,1542400660.184 [ElevatorServo] No Fault, FailCount= 1
2018-11-16T20:37:40.781Z,1542400660.781 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2018-11-16T20:37:40.781Z,1542400660.781 [RudderServo](FAULT): Rudder failed to initialize
2018-11-16T20:37:40.781Z,1542400660.781 [RudderServo] Communications Fault, FailCount= 2
2018-11-16T20:37:40.781Z,1542400660.781 [RudderServo](ERROR): Communications Fault
2018-11-16T20:37:40.786Z,1542400660.786 [CBIT](ERROR): Communications Fault in component: RudderServo
2018-11-16T20:37:41.181Z,1542400661.181 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2018-11-16T20:37:41.181Z,1542400661.181 [ElevatorServo](FAULT): Elevator failed to initialize
2018-11-16T20:37:41.181Z,1542400661.181 [ElevatorServo] Communications Fault, FailCount= 2
2018-11-16T20:37:41.181Z,1542400661.181 [ElevatorServo](ERROR): Communications Fault
2018-11-16T20:37:41.182Z,1542400661.182 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-16T20:37:41.182Z,1542400661.182 [RudderServo](INFO): Powering down
2018-11-16T20:37:41.188Z,1542400661.188 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2018-11-16T20:37:41.222Z,1542400661.222 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2018-11-16T20:37:41.271Z,1542400661.271 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-16T20:37:41.271Z,1542400661.271 [ElevatorServo](INFO): Powering down
2018-11-16T20:37:41.627Z,1542400661.627 [Radio_Surface](INFO): Powering down
2018-11-16T20:37:41.989Z,1542400661.989 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2018-11-16T20:37:41.989Z,1542400661.989 [MassServo] Communications Fault, FailCount= 2
2018-11-16T20:37:41.989Z,1542400661.989 [MassServo](ERROR): Communications Fault
2018-11-16T20:37:41.990Z,1542400661.990 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:37:42.029Z,1542400662.029 [Radio_Surface](FAULT): LCB fault:  Current Limiter Activated.
2018-11-16T20:37:42.029Z,1542400662.029 [Radio_Surface] Hardware Fault, FailCount= 1
2018-11-16T20:37:42.029Z,1542400662.029 [Radio_Surface](ERROR): Hardware Fault
2018-11-16T20:37:42.107Z,1542400662.107 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-16T20:37:42.112Z,1542400662.112 [CBIT](ERROR): Hardware Fault in component: Radio_Surface
2018-11-16T20:37:42.113Z,1542400662.113 [CBIT](ERROR): Communications Fault in component: MassServo
2018-11-16T20:37:42.113Z,1542400662.113 [CBIT](INFO): Clearing failed state for component RudderServo
2018-11-16T20:37:42.113Z,1542400662.113 [RudderServo] No Fault, FailCount= 2
2018-11-16T20:37:42.149Z,1542400662.149 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:37:42.270Z,1542400662.270 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-16T20:37:42.271Z,1542400662.271 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-16T20:37:42.271Z,1542400662.271 [MassServo](INFO): Powering down
2018-11-16T20:37:42.277Z,1542400662.277 [CBIT](INFO): Clearing failed state for component ElevatorServo
2018-11-16T20:37:42.277Z,1542400662.277 [ElevatorServo] No Fault, FailCount= 2
2018-11-16T20:37:43.025Z,1542400663.025 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-16T20:37:43.146Z,1542400663.146 [MassServo](DEBUG): Initializing MassServo.
2018-11-16T20:37:43.193Z,1542400663.193 [CBIT](INFO): Clearing failed state for component Radio_Surface
2018-11-16T20:37:43.193Z,1542400663.193 [Radio_Surface] No Fault, FailCount= 1
2018-11-16T20:37:43.194Z,1542400663.194 [CBIT](INFO): Clearing failed state for component MassServo
2018-11-16T20:37:43.194Z,1542400663.194 [MassServo] No Fault, FailCount= 2
2018-11-16T20:37:43.241Z,1542400663.241 [Radio_Surface](INFO): Powering up
2018-11-16T20:37:48.898Z,1542400668.898 [Radio_Surface](INFO): Powering down
2018-11-16T20:37:49.128Z,1542400669.128 [SBIT](FAULT): Mass: EXPECTED:0.002000 ACTUAL:-0.002005
2018-11-16T20:37:49.128Z,1542400669.128 [SBIT](FAULT): Control surface position failure.
2018-11-16T20:37:49.454Z,1542400669.454 [SBIT](CRITICAL): SBIT FAILED
2018-11-16T20:37:49.489Z,1542400669.489 [CommandLine](IMPORTANT): got command configSet list
2018-11-16T20:37:49.489Z,1542400669.489 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-16T20:37:49.490Z,1542400669.490 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-11-16T20:37:49.496Z,1542400669.496 [CBIT](INFO): Critical error at 20181116T203749
2018-11-16T20:37:49.751Z,1542400669.751 [MissionManager](IMPORTANT): Started mission Startup
2018-11-16T20:37:49.752Z,1542400669.752 [Startup] Running Loop=1
2018-11-16T20:37:49.752Z,1542400669.752 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-16T20:37:49.752Z,1542400669.752 [Startup:A.GoToSurface] Running Loop=1
2018-11-16T20:37:49.752Z,1542400669.752 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-16T20:37:49.753Z,1542400669.753 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-16T20:37:49.753Z,1542400669.753 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-16T20:37:49.754Z,1542400669.754 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-16T20:37:49.754Z,1542400669.754 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-16T20:37:49.754Z,1542400669.754 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-16T20:37:53.980Z,1542400673.980 [CommandLine](IMPORTANT): got command restart application
2018-11-16T20:37:54.969Z,1542400674.969 [Radio_Surface](INFO): Powering up
2018-11-16T20:37:54.988Z,1542400674.988 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:54.988Z,1542400674.988 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.161Z,1542400675.161 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-16T20:37:55.161Z,1542400675.161 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.162Z,1542400675.161 [CommandLine](INFO): Join timeout helper Thread ID is 1043
2018-11-16T20:37:55.162Z,1542400675.162 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-16T20:37:55.162Z,1542400675.162 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.163Z,1542400675.163 [NavChartDb](INFO): Join timeout helper Thread ID is 1044
2018-11-16T20:37:55.249Z,1542400675.249 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:55.249Z,1542400675.249 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.265Z,1542400675.265 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-16T20:37:55.265Z,1542400675.265 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.265Z,1542400675.265 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1045
2018-11-16T20:37:55.678Z,1542400675.678 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:55.678Z,1542400675.678 [WetLabsBB2FL](INFO): Powering down
2018-11-16T20:37:55.679Z,1542400675.679 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.693Z,1542400675.693 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-11-16T20:37:55.693Z,1542400675.693 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.693Z,1542400675.693 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1046
2018-11-16T20:37:55.837Z,1542400675.837 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:55.924Z,1542400675.924 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-16T20:37:55.925Z,1542400675.925 [CTD_Seabird](INFO): Powering down
2018-11-16T20:37:55.926Z,1542400675.926 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.933Z,1542400675.933 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-16T20:37:55.933Z,1542400675.933 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:55.934Z,1542400675.934 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1048
2018-11-16T20:37:56.089Z,1542400676.089 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:56.089Z,1542400676.089 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.106Z,1542400676.106 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-16T20:37:56.106Z,1542400676.106 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.106Z,1542400676.106 [Radio_Surface](INFO): Join timeout helper Thread ID is 1049
2018-11-16T20:37:56.181Z,1542400676.181 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:56.181Z,1542400676.181 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.202Z,1542400676.202 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-16T20:37:56.202Z,1542400676.202 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.202Z,1542400676.202 [logger](INFO): Join timeout helper Thread ID is 1050
2018-11-16T20:37:56.235Z,1542400676.235 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:56.236Z,1542400676.236 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.245Z,1542400676.245 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-16T20:37:56.246Z,1542400676.246 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.246Z,1542400676.246 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-16T20:37:56.246Z,1542400676.246 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.246Z,1542400676.246 [controlThread](INFO): Join timeout helper Thread ID is 1051
2018-11-16T20:37:56.517Z,1542400676.517 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-16T20:37:56.517Z,1542400676.517 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-16T20:37:56.518Z,1542400676.518 [NAL9602](INFO): Powering down
2018-11-16T20:37:56.521Z,1542400676.521 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-16T20:37:56.522Z,1542400676.522 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-16T20:37:56.523Z,1542400676.523 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-16T20:37:56.523Z,1542400676.523 [MissionManager](INFO): Uninitializing Mission Default
2018-11-16T20:37:56.526Z,1542400676.526 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-16T20:37:56.526Z,1542400676.526 [Startup] Stopped
2018-11-16T20:37:56.527Z,1542400676.527 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-16T20:37:56.527Z,1542400676.527 [Startup:A.GoToSurface] Stopped
2018-11-16T20:37:56.527Z,1542400676.527 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-16T20:37:56.528Z,1542400676.528 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-16T20:37:56.529Z,1542400676.529 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-16T20:37:56.529Z,1542400676.529 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-16T20:37:56.529Z,1542400676.529 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-16T20:37:56.529Z,1542400676.529 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-16T20:37:56.529Z,1542400676.529 [BuoyancyServo](INFO): Powering down
2018-11-16T20:37:56.541Z,1542400676.541 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-16T20:37:56.541Z,1542400676.541 [ElevatorServo](INFO): Powering down
2018-11-16T20:37:56.542Z,1542400676.542 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-16T20:37:56.542Z,1542400676.542 [MassServo](INFO): Powering down
2018-11-16T20:37:56.543Z,1542400676.543 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-16T20:37:56.543Z,1542400676.543 [RudderServo](INFO): Powering down
2018-11-16T20:37:56.544Z,1542400676.544 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-16T20:37:56.544Z,1542400676.544 [ThrusterServo](INFO): Powering down
2018-11-16T20:37:56.545Z,1542400676.545 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-16T20:37:56.545Z,1542400676.545 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-16T20:37:56.546Z,1542400676.546 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-16T20:37:56.547Z,1542400676.547 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.636Z,1542400676.636 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.678Z,1542400676.678 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.681Z,1542400676.681 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.684Z,1542400676.684 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.852Z,1542400676.852 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-16T20:37:56.901Z,1542400676.901 [logger ThreadHandler](INFO): Thread cancelled.