2019-03-21T23:33:03.780Z,1553211183.780 [Supervisor](DEBUG): Initializing supervisor.
2019-03-21T23:33:03.783Z,1553211183.783 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-21T23:33:03.784Z,1553211183.784 [SyncHandler](INFO): Protected caller Thread ID is 1091
2019-03-21T23:33:03.785Z,1553211183.785 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-21T23:33:03.786Z,1553211183.786 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-21T23:33:03.786Z,1553211183.786 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1092
2019-03-21T23:33:03.790Z,1553211183.790 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-21T23:33:03.802Z,1553211183.802 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-21T23:33:03.803Z,1553211183.803 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-21T23:33:03.804Z,1553211183.804 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1093
2019-03-21T23:33:03.805Z,1553211183.805 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-21T23:33:03.806Z,1553211183.806 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-21T23:33:03.806Z,1553211183.806 [logger ThreadHandler](INFO): Protected caller Thread ID is 1094
2019-03-21T23:33:03.808Z,1553211183.808 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-21T23:33:03.808Z,1553211183.808 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-21T23:33:03.810Z,1553211183.810 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-21T23:33:04.299Z,1553211184.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-21T23:33:04.299Z,1553211184.299 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-21T23:33:04.442Z,1553211184.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-21T23:33:04.443Z,1553211184.443 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-21T23:33:04.523Z,1553211184.523 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-21T23:33:04.620Z,1553211184.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-21T23:33:04.621Z,1553211184.621 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-21T23:33:04.715Z,1553211184.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-21T23:33:04.716Z,1553211184.716 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-21T23:33:05.084Z,1553211185.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-21T23:33:05.085Z,1553211185.085 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-21T23:33:05.186Z,1553211185.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-21T23:33:05.187Z,1553211185.187 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-21T23:33:05.647Z,1553211185.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-21T23:33:05.647Z,1553211185.647 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-21T23:33:05.849Z,1553211185.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-21T23:33:05.850Z,1553211185.850 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-21T23:33:06.155Z,1553211186.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-21T23:33:06.155Z,1553211186.155 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-21T23:33:06.367Z,1553211186.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-21T23:33:06.368Z,1553211186.368 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-21T23:33:06.510Z,1553211186.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-21T23:33:06.510Z,1553211186.510 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-21T23:33:07.003Z,1553211187.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-21T23:33:07.004Z,1553211187.004 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-21T23:33:07.338Z,1553211187.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-21T23:33:07.339Z,1553211187.339 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-21T23:33:07.544Z,1553211187.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-21T23:33:07.547Z,1553211187.547 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-03-21T23:33:07.547Z,1553211187.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-03-21T23:33:07.722Z,1553211187.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-03-21T23:33:07.804Z,1553211187.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-03-21T23:33:07.917Z,1553211187.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-03-21T23:33:08.002Z,1553211188.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-03-21T23:33:08.229Z,1553211188.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-21T23:33:08.229Z,1553211188.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-03-21T23:33:08.327Z,1553211188.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-03-21T23:33:08.436Z,1553211188.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-03-21T23:33:08.529Z,1553211188.529 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-03-21T23:33:08.679Z,1553211188.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-03-21T23:33:08.827Z,1553211188.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-03-21T23:33:09.385Z,1553211189.385 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-03-21T23:33:09.385Z,1553211189.385 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-21T23:33:09.389Z,1553211189.389 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-21T23:33:09.528Z,1553211189.528 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-21T23:33:09.528Z,1553211189.528 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-21T23:33:10.048Z,1553211190.048 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-21T23:33:10.161Z,1553211190.161 [VerticalControl] Loaded
2019-03-21T23:33:10.161Z,1553211190.161 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-21T23:33:10.162Z,1553211190.162 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-21T23:33:10.231Z,1553211190.231 [HorizontalControl] Loaded
2019-03-21T23:33:10.231Z,1553211190.231 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-21T23:33:10.232Z,1553211190.232 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-21T23:33:10.237Z,1553211190.237 [SpeedControl] Loaded
2019-03-21T23:33:10.237Z,1553211190.237 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-21T23:33:10.238Z,1553211190.238 [LoopControl](DEBUG): Construct LoopControl.
2019-03-21T23:33:10.239Z,1553211190.239 [LoopControl] Loaded
2019-03-21T23:33:10.239Z,1553211190.239 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-21T23:33:10.239Z,1553211190.239 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-21T23:33:10.240Z,1553211190.240 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-21T23:33:10.418Z,1553211190.418 [ElevatorServo] Loaded
2019-03-21T23:33:10.419Z,1553211190.419 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-21T23:33:10.430Z,1553211190.430 [MassServo] Loaded
2019-03-21T23:33:10.430Z,1553211190.430 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-21T23:33:10.441Z,1553211190.441 [RudderServo] Loaded
2019-03-21T23:33:10.442Z,1553211190.442 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-21T23:33:10.452Z,1553211190.452 [ThrusterServo] Loaded
2019-03-21T23:33:10.453Z,1553211190.453 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-21T23:33:10.453Z,1553211190.453 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-21T23:33:10.454Z,1553211190.454 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-21T23:33:10.508Z,1553211190.508 [DepthRateCalculator] Loaded
2019-03-21T23:33:10.509Z,1553211190.509 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-21T23:33:10.514Z,1553211190.514 [PitchRateCalculator] Loaded
2019-03-21T23:33:10.514Z,1553211190.514 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-21T23:33:10.529Z,1553211190.529 [SpeedCalculator] Loaded
2019-03-21T23:33:10.529Z,1553211190.529 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-21T23:33:10.550Z,1553211190.550 [TempGradientCalculator] Loaded
2019-03-21T23:33:10.551Z,1553211190.551 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-21T23:33:10.555Z,1553211190.555 [YawRateCalculator] Loaded
2019-03-21T23:33:10.556Z,1553211190.556 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-21T23:33:10.585Z,1553211190.585 [ElevatorOffsetCalculator] Loaded
2019-03-21T23:33:10.585Z,1553211190.585 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-21T23:33:10.586Z,1553211190.586 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-21T23:33:10.586Z,1553211190.586 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-21T23:33:10.708Z,1553211190.708 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-21T23:33:10.709Z,1553211190.709 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-21T23:33:10.767Z,1553211190.767 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-21T23:33:10.767Z,1553211190.767 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-21T23:33:10.788Z,1553211190.788 [NavChart] Loaded
2019-03-21T23:33:10.788Z,1553211190.788 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-21T23:33:10.792Z,1553211190.792 [UniversalFixResidualReporter] Loaded
2019-03-21T23:33:10.793Z,1553211190.793 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-21T23:33:10.793Z,1553211190.793 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-21T23:33:10.794Z,1553211190.794 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-21T23:33:11.198Z,1553211191.198 [DataOverHttps] Loaded
2019-03-21T23:33:11.198Z,1553211191.198 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-21T23:33:11.199Z,1553211191.199 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F74E0
2019-03-21T23:33:11.199Z,1553211191.199 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1173
2019-03-21T23:33:11.213Z,1553211191.213 [Depth_Keller] Loaded
2019-03-21T23:33:11.213Z,1553211191.213 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-21T23:33:11.218Z,1553211191.218 [DropWeight] Loaded
2019-03-21T23:33:11.218Z,1553211191.218 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-21T23:33:11.316Z,1553211191.316 [NAL9602] Loaded
2019-03-21T23:33:11.316Z,1553211191.316 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-21T23:33:11.332Z,1553211191.332 [Onboard] Loaded
2019-03-21T23:33:11.332Z,1553211191.332 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-21T23:33:11.335Z,1553211191.335 [Radio_Surface] Loaded
2019-03-21T23:33:11.336Z,1553211191.336 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-21T23:33:11.337Z,1553211191.337 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408274E0
2019-03-21T23:33:11.337Z,1553211191.337 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1174
2019-03-21T23:33:13.141Z,1553211193.141 [BPC1] Loaded
2019-03-21T23:33:13.142Z,1553211193.142 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-21T23:33:13.142Z,1553211193.142 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-21T23:33:13.143Z,1553211193.143 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-21T23:33:13.245Z,1553211193.245 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-21T23:33:13.245Z,1553211193.245 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-21T23:33:13.370Z,1553211193.370 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-21T23:33:13.371Z,1553211193.371 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-21T23:33:13.412Z,1553211193.412 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-21T23:33:13.412Z,1553211193.412 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-21T23:33:13.737Z,1553211193.737 [CTD_NeilBrown] Loaded
2019-03-21T23:33:13.737Z,1553211193.737 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-21T23:33:13.738Z,1553211193.738 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409B34E0
2019-03-21T23:33:13.739Z,1553211193.739 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1175
2019-03-21T23:33:13.748Z,1553211193.748 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-03-21T23:33:13.754Z,1553211193.754 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-03-21T23:33:13.756Z,1553211193.756 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-03-21T23:33:13.761Z,1553211193.761 [CTD_Seabird](INFO): created writer for : depth
2019-03-21T23:33:13.761Z,1553211193.761 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-03-21T23:33:13.766Z,1553211193.766 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-03-21T23:33:13.767Z,1553211193.767 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-03-21T23:33:13.772Z,1553211193.772 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-03-21T23:33:13.772Z,1553211193.772 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-03-21T23:33:13.777Z,1553211193.777 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-03-21T23:33:13.778Z,1553211193.778 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-03-21T23:33:13.783Z,1553211193.783 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-03-21T23:33:13.784Z,1553211193.784 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-03-21T23:33:13.788Z,1553211193.788 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-03-21T23:33:13.815Z,1553211193.815 [CTD_Seabird] Loaded
2019-03-21T23:33:13.816Z,1553211193.816 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-03-21T23:33:13.817Z,1553211193.817 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E34E0
2019-03-21T23:33:13.817Z,1553211193.817 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1176
2019-03-21T23:33:13.866Z,1553211193.866 [WetLabsBB2FL] Loaded
2019-03-21T23:33:13.866Z,1553211193.866 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-03-21T23:33:13.867Z,1553211193.867 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A134E0
2019-03-21T23:33:13.867Z,1553211193.867 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1177
2019-03-21T23:33:13.868Z,1553211193.868 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-21T23:33:13.869Z,1553211193.869 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-21T23:33:14.211Z,1553211194.211 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-21T23:33:14.211Z,1553211194.211 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-21T23:33:14.369Z,1553211194.369 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-21T23:33:14.380Z,1553211194.380 [SBIT] Loaded
2019-03-21T23:33:14.381Z,1553211194.381 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-21T23:33:14.381Z,1553211194.381 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-21T23:33:14.393Z,1553211194.393 [IBIT] Loaded
2019-03-21T23:33:14.393Z,1553211194.393 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-21T23:33:14.396Z,1553211194.396 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-21T23:33:14.534Z,1553211194.534 [CBIT] Loaded
2019-03-21T23:33:14.534Z,1553211194.534 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-21T23:33:14.535Z,1553211194.535 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-21T23:33:14.538Z,1553211194.538 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-21T23:33:14.539Z,1553211194.539 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-21T23:33:14.546Z,1553211194.546 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-21T23:33:14.547Z,1553211194.547 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0
2019-03-21T23:33:14.548Z,1553211194.548 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1178
2019-03-21T23:33:14.553Z,1553211194.553 [Supervisor](INFO): Main Thread ID is 811
2019-03-21T23:33:14.553Z,1553211194.553 [Supervisor](DEBUG): Running supervisor.
2019-03-21T23:33:14.553Z,1553211194.553 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1179
2019-03-21T23:33:14.556Z,1553211194.556 [controlThread ThreadHandler](INFO): Handler Thread ID is 1180
2019-03-21T23:33:14.556Z,1553211194.556 [controlThread](DEBUG): Initializing ControlThread
2019-03-21T23:33:14.558Z,1553211194.558 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-21T23:33:14.559Z,1553211194.559 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-21T23:33:14.560Z,1553211194.560 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-21T23:33:14.560Z,1553211194.560 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-21T23:33:14.562Z,1553211194.562 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-21T23:33:14.562Z,1553211194.562 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-21T23:33:14.562Z,1553211194.562 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-21T23:33:14.563Z,1553211194.563 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-21T23:33:14.563Z,1553211194.563 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-21T23:33:14.563Z,1553211194.563 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-21T23:33:14.565Z,1553211194.565 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-21T23:33:14.565Z,1553211194.565 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-21T23:33:14.566Z,1553211194.566 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-21T23:33:14.571Z,1553211194.571 [SBIT](INFO): Initialize SBIT Component.
2019-03-21T23:33:14.572Z,1553211194.572 [SBIT](IMPORTANT): git: 2019-03-18-10-g14b6beb
2019-03-21T23:33:14.572Z,1553211194.572 [SBIT](INFO): git hash: 14b6beb35e2ed04885d9ca1cfe5e8b3dec77c2c3
2019-03-21T23:33:14.572Z,1553211194.572 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-21T23:33:14.574Z,1553211194.574 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-03-21T23:33:14.575Z,1553211194.575 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-21T23:33:14.575Z,1553211194.575 [IBIT](INFO): Initialize IBIT Component.
2019-03-21T23:33:14.576Z,1553211194.576 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-21T23:33:14.577Z,1553211194.577 [logger ThreadHandler](INFO): Handler Thread ID is 1181
2019-03-21T23:33:14.589Z,1553211194.589 [CBIT](DEBUG): Initialized mux pins.
2019-03-21T23:33:14.589Z,1553211194.589 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-21T23:33:14.597Z,1553211194.597 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1182
2019-03-21T23:33:14.602Z,1553211194.602 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-21T23:33:14.609Z,1553211194.609 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1183
2019-03-21T23:33:14.613Z,1553211194.613 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-21T23:33:14.613Z,1553211194.613 [CBIT](DEBUG): Initializing heartbeat.
2019-03-21T23:33:14.621Z,1553211194.621 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1184
2019-03-21T23:33:14.633Z,1553211194.633 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1185
2019-03-21T23:33:14.634Z,1553211194.634 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-21T23:33:14.637Z,1553211194.637 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-21T23:33:14.639Z,1553211194.639 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1187
2019-03-21T23:33:14.640Z,1553211194.640 [WetLabsBB2FL](INFO): Powering down
2019-03-21T23:33:14.669Z,1553211194.669 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1188
2019-03-21T23:33:14.672Z,1553211194.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-21T23:33:14.673Z,1553211194.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-21T23:33:14.673Z,1553211194.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-21T23:33:14.673Z,1553211194.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-21T23:33:14.673Z,1553211194.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-21T23:33:14.673Z,1553211194.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-21T23:33:14.674Z,1553211194.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-21T23:33:14.674Z,1553211194.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-21T23:33:14.674Z,1553211194.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-21T23:33:14.674Z,1553211194.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-21T23:33:14.674Z,1553211194.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-21T23:33:14.675Z,1553211194.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-21T23:33:14.675Z,1553211194.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-21T23:33:14.675Z,1553211194.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-21T23:33:14.675Z,1553211194.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-21T23:33:14.675Z,1553211194.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-21T23:33:14.685Z,1553211194.685 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-21T23:33:14.685Z,1553211194.685 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-21T23:33:14.722Z,1553211194.722 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-21T23:33:14.735Z,1553211194.735 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-21T23:33:14.757Z,1553211194.757 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-03-21T23:33:14.758Z,1553211194.758 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-21T23:33:14.823Z,1553211194.823 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-21T23:33:14.824Z,1553211194.824 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-21T23:33:14.842Z,1553211194.842 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-21T23:33:14.877Z,1553211194.877 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-21T23:33:14.900Z,1553211194.900 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-21T23:33:14.910Z,1553211194.910 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-21T23:33:14.914Z,1553211194.914 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-03-21T23:33:14.932Z,1553211194.932 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-21T23:33:14.971Z,1553211194.971 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2019-03-21T23:33:15.013Z,1553211195.013 [Radio_Surface](INFO): Powering up
2019-03-21T23:33:15.029Z,1553211195.029 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-21T23:33:15.079Z,1553211195.079 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:15.085Z,1553211195.085 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-21T23:33:15.086Z,1553211195.086 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:15.093Z,1553211195.093 [MassServo](DEBUG): Initializing MassServo.
2019-03-21T23:33:15.094Z,1553211195.094 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:15.101Z,1553211195.101 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-21T23:33:15.102Z,1553211195.102 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:15.109Z,1553211195.109 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-21T23:33:15.355Z,1553211195.355 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-03-21T23:33:15.355Z,1553211195.355 [DropWeight] Hardware Fault, FailCount= 1
2019-03-21T23:33:15.355Z,1553211195.355 [DropWeight](ERROR): Hardware Fault
2019-03-21T23:33:15.400Z,1553211195.400 [CommandLine](FAULT): Scheduling is paused
2019-03-21T23:33:15.400Z,1553211195.400 [CBIT](INFO): Critical error at 20190321T233315
2019-03-21T23:33:15.401Z,1553211195.401 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-21T23:33:15.404Z,1553211195.404 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-03-21T23:33:15.404Z,1553211195.404 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-03-21T23:33:16.070Z,1553211196.070 [CBIT](INFO): Critical error at 20190321T233315
2019-03-21T23:33:19.869Z,1553211199.869 [RudderServo](FAULT): Overload Error
2019-03-21T23:33:19.869Z,1553211199.869 [RudderServo] Hardware Fault, FailCount= 1
2019-03-21T23:33:19.869Z,1553211199.869 [RudderServo](ERROR): Hardware Fault
2019-03-21T23:33:19.909Z,1553211199.909 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-03-21T23:33:20.259Z,1553211200.259 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-21T23:33:20.259Z,1553211200.259 [RudderServo](INFO): Powering down
2019-03-21T23:33:20.669Z,1553211200.669 [Radio_Surface](INFO): Should not be on. Powering down.
2019-03-21T23:33:21.073Z,1553211201.073 [Radio_Surface](INFO): Powering down
2019-03-21T23:33:21.118Z,1553211201.118 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:21.238Z,1553211201.238 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-21T23:33:21.242Z,1553211201.242 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-21T23:33:21.242Z,1553211201.242 [RudderServo] No Fault, FailCount= 1
2019-03-21T23:33:24.689Z,1553211204.689 [CBIT](CRITICAL): Environmental Failure. Press:14.926442 PSI. Humidity:38%. Temp:25 C. ABORTING MISSION
2019-03-21T23:33:25.090Z,1553211205.090 [CBIT](INFO): Critical error at 20190321T233324
2019-03-21T23:33:25.897Z,1553211205.897 [RudderServo](FAULT): Overload Error
2019-03-21T23:33:25.897Z,1553211205.897 [RudderServo] Hardware Fault, FailCount= 2
2019-03-21T23:33:25.897Z,1553211205.897 [RudderServo](ERROR): Hardware Fault
2019-03-21T23:33:25.937Z,1553211205.937 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-03-21T23:33:26.293Z,1553211206.293 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-21T23:33:26.293Z,1553211206.293 [RudderServo](INFO): Powering down
2019-03-21T23:33:27.082Z,1553211207.082 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:27.202Z,1553211207.202 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-21T23:33:27.206Z,1553211207.206 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-21T23:33:27.206Z,1553211207.206 [RudderServo] No Fault, FailCount= 2
2019-03-21T23:33:27.685Z,1553211207.685 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-03-21T23:33:27.685Z,1553211207.685 [CTD_Seabird] Communications Fault, FailCount= 1
2019-03-21T23:33:27.685Z,1553211207.685 [CTD_Seabird](ERROR): Communications Fault
2019-03-21T23:33:27.941Z,1553211207.941 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-03-21T23:33:28.089Z,1553211208.089 [CTD_Seabird](INFO): Powering down
2019-03-21T23:33:29.105Z,1553211209.105 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-03-21T23:33:29.105Z,1553211209.105 [CTD_Seabird] No Fault, FailCount= 1
2019-03-21T23:33:29.453Z,1553211209.453 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-21T23:33:29.455Z,1553211209.455 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-21T23:33:31.957Z,1553211211.957 [RudderServo](FAULT): Overload Error
2019-03-21T23:33:31.957Z,1553211211.957 [RudderServo] Hardware Fault, FailCount= 3
2019-03-21T23:33:31.957Z,1553211211.957 [RudderServo](ERROR): Hardware Fault
2019-03-21T23:33:31.997Z,1553211211.997 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-03-21T23:33:31.998Z,1553211211.998 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2019-03-21T23:33:32.337Z,1553211212.337 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-21T23:33:32.338Z,1553211212.338 [RudderServo](INFO): Powering down
2019-03-21T23:33:32.340Z,1553211212.340 [CBIT](INFO): Critical error at 20190321T233331
2019-03-21T23:33:33.145Z,1553211213.145 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-21T23:33:33.266Z,1553211213.266 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-21T23:33:41.602Z,1553211221.602 [NAL9602](INFO): Powering up NAL9602
2019-03-21T23:33:42.505Z,1553211222.505 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-03-21T23:33:42.505Z,1553211222.505 [CTD_Seabird] Communications Fault, FailCount= 2
2019-03-21T23:33:42.505Z,1553211222.505 [CTD_Seabird](ERROR): Communications Fault
2019-03-21T23:33:42.852Z,1553211222.852 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-03-21T23:33:42.909Z,1553211222.909 [CTD_Seabird](INFO): Powering down
2019-03-21T23:33:43.242Z,1553211223.242 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-21T23:33:43.246Z,1553211223.246 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-21T23:33:44.100Z,1553211224.100 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-03-21T23:33:44.100Z,1553211224.100 [CTD_Seabird] No Fault, FailCount= 2
2019-03-21T23:33:44.253Z,1553211224.253 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-21T23:33:44.261Z,1553211224.261 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-21T23:33:45.333Z,1553211225.333 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2019-03-21T23:33:52.554Z,1553211232.554 [NAL9602](INFO): NAL9602 initialized
2019-03-21T23:33:53.373Z,1553211233.373 [NAL9602](DEBUG): Fix Requested
2019-03-21T23:33:54.265Z,1553211234.265 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.020204
CHAN A1 (24V): 0.000923
CHAN A2 (12V): -0.068292
CHAN A3 (5V): -0.002664
CHAN B0 (3.3V): -0.000928
CHAN B1 (3.15aV): -0.001261
CHAN B2 (3.15bV): -0.001228
CHAN B3 (GND): -0.000207
OPEN: -0.001089
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-21T23:33:56.346Z,1553211236.346 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-21T23:33:57.305Z,1553211237.305 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-03-21T23:33:57.305Z,1553211237.305 [CTD_Seabird] Communications Fault, FailCount= 3
2019-03-21T23:33:57.305Z,1553211237.305 [CTD_Seabird](ERROR): Communications Fault
2019-03-21T23:33:57.446Z,1553211237.446 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-03-21T23:33:57.446Z,1553211237.446 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-03-21T23:33:57.709Z,1553211237.709 [CTD_Seabird](INFO): Powering down
2019-03-21T23:34:22.974Z,1553211262.974 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-21T23:34:22.974Z,1553211262.974 [SBIT](FAULT): Control surface position failure.
2019-03-21T23:34:28.320Z,1553211268.320 [BPC1](ERROR): BPC1B: No match for serial number 0202 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-03-21T23:34:28.666Z,1553211268.666 [BPC1](ERROR): BPC1A: No match for serial number 0295 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-03-21T23:34:28.677Z,1553211268.677 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-03-21T23:34:28.677Z,1553211268.677 [BPC1] Data Fault, FailCount= 1
2019-03-21T23:34:28.677Z,1553211268.677 [BPC1](ERROR): Data Fault
2019-03-21T23:34:28.734Z,1553211268.734 [CBIT](ERROR): Data Fault in component: BPC1
2019-03-21T23:34:36.772Z,1553211276.772 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-21T23:34:36.772Z,1553211276.772 [SBIT](FAULT): Control surface position failure.
2019-03-21T23:34:37.166Z,1553211277.166 [SBIT](CRITICAL): SBIT FAILED
2019-03-21T23:34:37.198Z,1553211277.198 [CBIT](INFO): Critical error at 20190321T233437
2019-03-21T23:34:37.202Z,1553211277.202 [CommandLine](IMPORTANT): got command configSet list
2019-03-21T23:34:37.202Z,1553211277.202 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-21T23:34:37.203Z,1553211277.203 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-21T23:34:37.203Z,1553211277.203 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool;

2019-03-21T23:34:37.569Z,1553211277.569 [MissionManager](IMPORTANT): Started mission Startup
2019-03-21T23:34:37.570Z,1553211277.570 [Startup] Running Loop=1
2019-03-21T23:34:37.570Z,1553211277.570 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-21T23:34:37.570Z,1553211277.570 [Startup:A.GoToSurface] Running Loop=1
2019-03-21T23:34:37.570Z,1553211277.570 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-21T23:34:37.571Z,1553211277.571 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-21T23:34:37.571Z,1553211277.571 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-21T23:34:37.571Z,1553211277.571 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-21T23:34:37.572Z,1553211277.572 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-21T23:34:37.572Z,1553211277.572 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-21T23:34:38.237Z,1553211278.237 [Radio_Surface](INFO): Powering up
2019-03-21T23:34:43.509Z,1553211283.509 [DataOverHttps](INFO): Radio surface powered ON.
2019-03-21T23:35:14.760Z,1553211314.760 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-21T23:35:14.760Z,1553211314.760 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-21T23:35:14.760Z,1553211314.760 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-21T23:35:14.762Z,1553211314.762 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-21T23:35:14.762Z,1553211314.762 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-21T23:35:14.762Z,1553211314.762 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-21T23:35:14.773Z,1553211314.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-21T23:35:14.773Z,1553211314.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-21T23:35:15.178Z,1553211315.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-21T23:35:15.178Z,1553211315.178 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-21T23:35:15.178Z,1553211315.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-21T23:35:15.178Z,1553211315.178 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-21T23:35:15.544Z,1553211315.544 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-21T23:36:27.490Z,1553211387.490 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-03-21T23:36:27.491Z,1553211387.491 [CTD_Seabird] No Fault, FailCount= 3
2019-03-21T23:36:27.725Z,1553211387.725 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-03-21T23:36:27.727Z,1553211387.727 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-03-21T23:36:31.572Z,1553211391.572 [CBIT](CRITICAL): run-time exception #7
2019-03-21T23:36:31.572Z,1553211391.572 [CBIT](CRITICAL): Bad address: 0x4041E008
2019-03-21T23:36:31.572Z,1553211391.572 [CBIT](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1790e8]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xbc) [0x178c84]

2019-03-21T23:36:31.572Z,1553211391.572 [CBIT] Software Fault, FailCount= 1
2019-03-21T23:36:31.572Z,1553211391.572 [CBIT](ERROR): Software Fault
2019-03-21T23:36:31.589Z,1553211391.589 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-21T23:36:31.589Z,1553211391.589 [SyncHandler](INFO): Protected caller Thread ID is 1224
2019-03-21T23:36:31.915Z,1553211391.915 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-21T23:36:31.915Z,1553211391.915 [CBIT](DEBUG): Powering off loads.
2019-03-21T23:36:31.929Z,1553211391.929 [CBIT](DEBUG): Disabling WDT.
2019-03-21T23:36:31.941Z,1553211391.941 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-21T23:36:40.778Z,1553211400.778 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-03-21T23:36:40.778Z,1553211400.778 [CTD_Seabird] Communications Fault, FailCount= 1
2019-03-21T23:36:40.778Z,1553211400.778 [CTD_Seabird](ERROR): Communications Fault
2019-03-21T23:36:41.181Z,1553211401.181 [CTD_Seabird](INFO): Powering down
2019-03-21T23:36:41.717Z,1553211401.717 [CTD_Seabird](FAULT): LCB fault:  Current Limiter Activated.
2019-03-21T23:36:41.717Z,1553211401.717 [CTD_Seabird] Hardware Fault, FailCount= 1
2019-03-21T23:36:41.717Z,1553211401.717 [CTD_Seabird](ERROR): Hardware Fault
2019-03-21T23:36:55.170Z,1553211415.170 [CommandLine](IMPORTANT): got command quit
2019-03-21T23:36:56.177Z,1553211416.177 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:56.177Z,1553211416.177 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.377Z,1553211416.377 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-21T23:36:56.377Z,1553211416.377 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.378Z,1553211416.378 [CommandLine](INFO): Join timeout helper Thread ID is 1379
2019-03-21T23:36:56.378Z,1553211416.378 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-21T23:36:56.378Z,1553211416.378 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.378Z,1553211416.378 [NavChartDb](INFO): Join timeout helper Thread ID is 1380
2019-03-21T23:36:56.421Z,1553211416.421 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:56.421Z,1553211416.421 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.437Z,1553211416.437 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-03-21T23:36:56.437Z,1553211416.437 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.437Z,1553211416.437 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1381
2019-03-21T23:36:56.469Z,1553211416.469 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:56.470Z,1553211416.470 [WetLabsBB2FL](INFO): Powering down
2019-03-21T23:36:56.470Z,1553211416.470 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.477Z,1553211416.477 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-03-21T23:36:56.477Z,1553211416.477 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.477Z,1553211416.477 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1382
2019-03-21T23:36:56.681Z,1553211416.681 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:56.681Z,1553211416.681 [CTD_Seabird](INFO): Powering down
2019-03-21T23:36:56.693Z,1553211416.693 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.701Z,1553211416.701 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-21T23:36:56.701Z,1553211416.701 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.701Z,1553211416.701 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1385
2019-03-21T23:36:56.901Z,1553211416.901 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:56.901Z,1553211416.901 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.906Z,1553211416.906 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-21T23:36:56.906Z,1553211416.906 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:56.906Z,1553211416.906 [Radio_Surface](INFO): Join timeout helper Thread ID is 1386
2019-03-21T23:36:57.233Z,1553211417.233 [Radio_Surface](INFO): Powering down
2019-03-21T23:36:57.234Z,1553211417.234 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:57.235Z,1553211417.235 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.246Z,1553211417.246 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-21T23:36:57.246Z,1553211417.246 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.246Z,1553211417.246 [DataOverHttps](INFO): Join timeout helper Thread ID is 1389
2019-03-21T23:36:57.637Z,1553211417.637 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:57.637Z,1553211417.637 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.658Z,1553211417.658 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-21T23:36:57.658Z,1553211417.658 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.658Z,1553211417.658 [logger](INFO): Join timeout helper Thread ID is 1392
2019-03-21T23:36:57.697Z,1553211417.697 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:57.697Z,1553211417.697 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.702Z,1553211417.702 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-21T23:36:57.702Z,1553211417.702 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.702Z,1553211417.702 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-21T23:36:57.702Z,1553211417.702 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.702Z,1553211417.702 [controlThread](INFO): Join timeout helper Thread ID is 1395
2019-03-21T23:36:57.733Z,1553211417.733 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-21T23:36:57.733Z,1553211417.733 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-21T23:36:57.734Z,1553211417.734 [NAL9602](INFO): Powering down
2019-03-21T23:36:57.736Z,1553211417.736 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-21T23:36:57.736Z,1553211417.736 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-21T23:36:57.737Z,1553211417.737 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-21T23:36:57.738Z,1553211417.738 [MissionManager](INFO): Uninitializing Mission Default
2019-03-21T23:36:57.740Z,1553211417.740 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-21T23:36:57.740Z,1553211417.740 [Startup] Stopped
2019-03-21T23:36:57.740Z,1553211417.740 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-21T23:36:57.740Z,1553211417.740 [Startup:A.GoToSurface] Stopped
2019-03-21T23:36:57.740Z,1553211417.740 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-21T23:36:57.741Z,1553211417.741 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-21T23:36:57.742Z,1553211417.742 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-21T23:36:57.742Z,1553211417.742 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-21T23:36:57.742Z,1553211417.742 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-21T23:36:57.742Z,1553211417.742 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-21T23:36:57.742Z,1553211417.742 [ElevatorServo](INFO): Powering down
2019-03-21T23:36:57.743Z,1553211417.743 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-21T23:36:57.743Z,1553211417.743 [MassServo](INFO): Powering down
2019-03-21T23:36:57.744Z,1553211417.744 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-21T23:36:57.744Z,1553211417.744 [RudderServo](INFO): Powering down
2019-03-21T23:36:57.745Z,1553211417.745 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-21T23:36:57.745Z,1553211417.745 [ThrusterServo](INFO): Powering down
2019-03-21T23:36:57.746Z,1553211417.746 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-21T23:36:57.746Z,1553211417.746 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-21T23:36:57.746Z,1553211417.746 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-21T23:36:57.747Z,1553211417.747 [CBIT](DEBUG): Powering off loads.
2019-03-21T23:36:57.761Z,1553211417.761 [CBIT](DEBUG): Disabling WDT.
2019-03-21T23:36:57.773Z,1553211417.773 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-21T23:36:57.774Z,1553211417.774 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.982Z,1553211417.982 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:57.988Z,1553211417.988 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:58.054Z,1553211418.054 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:58.056Z,1553211418.056 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:58.060Z,1553211418.060 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:58.089Z,1553211418.089 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-21T23:36:58.145Z,1553211418.145 [logger ThreadHandler](INFO): Thread cancelled.