2019-04-22T22:18:12.661Z,1555971492.661 [Supervisor](DEBUG): Initializing supervisor.
2019-04-22T22:18:12.664Z,1555971492.664 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-22T22:18:12.664Z,1555971492.664 [SyncHandler](INFO): Protected caller Thread ID is 808
2019-04-22T22:18:12.665Z,1555971492.665 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-22T22:18:12.666Z,1555971492.666 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-22T22:18:12.666Z,1555971492.666 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 809
2019-04-22T22:18:12.669Z,1555971492.669 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-22T22:18:12.681Z,1555971492.681 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-22T22:18:12.682Z,1555971492.682 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-22T22:18:12.682Z,1555971492.682 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 810
2019-04-22T22:18:12.683Z,1555971492.683 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-22T22:18:12.684Z,1555971492.684 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-22T22:18:12.684Z,1555971492.684 [logger ThreadHandler](INFO): Protected caller Thread ID is 811
2019-04-22T22:18:12.686Z,1555971492.686 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-22T22:18:12.687Z,1555971492.687 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-22T22:18:12.690Z,1555971492.690 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-22T22:18:13.131Z,1555971493.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-22T22:18:13.131Z,1555971493.131 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-22T22:18:13.337Z,1555971493.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-22T22:18:13.339Z,1555971493.339 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-22T22:18:13.480Z,1555971493.480 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-22T22:18:13.481Z,1555971493.481 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-22T22:18:13.790Z,1555971493.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-22T22:18:13.792Z,1555971493.792 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-22T22:18:14.136Z,1555971494.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-22T22:18:14.137Z,1555971494.137 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-22T22:18:14.234Z,1555971494.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-22T22:18:14.235Z,1555971494.235 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-22T22:18:14.397Z,1555971494.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-22T22:18:14.398Z,1555971494.398 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-22T22:18:14.878Z,1555971494.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-22T22:18:14.878Z,1555971494.878 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-22T22:18:15.276Z,1555971495.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-22T22:18:15.277Z,1555971495.277 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-22T22:18:15.359Z,1555971495.359 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-22T22:18:15.567Z,1555971495.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-22T22:18:15.568Z,1555971495.568 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-22T22:18:15.675Z,1555971495.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-22T22:18:15.676Z,1555971495.676 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-22T22:18:16.754Z,1555971496.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-22T22:18:16.755Z,1555971496.755 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-22T22:18:16.859Z,1555971496.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-22T22:18:16.859Z,1555971496.859 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-22T22:18:17.090Z,1555971497.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-22T22:18:17.092Z,1555971497.092 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2019-04-22T22:18:17.095Z,1555971497.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2019-04-22T22:18:17.277Z,1555971497.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2019-04-22T22:18:17.373Z,1555971497.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2019-04-22T22:18:17.484Z,1555971497.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2019-04-22T22:18:17.587Z,1555971497.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2019-04-22T22:18:17.671Z,1555971497.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2019-04-22T22:18:17.758Z,1555971497.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2019-04-22T22:18:17.877Z,1555971497.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2019-04-22T22:18:17.980Z,1555971497.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2019-04-22T22:18:18.135Z,1555971498.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2019-04-22T22:18:18.365Z,1555971498.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-22T22:18:18.366Z,1555971498.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2019-04-22T22:18:18.461Z,1555971498.461 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-22T22:18:18.466Z,1555971498.466 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-22T22:18:18.807Z,1555971498.807 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-22T22:18:18.808Z,1555971498.808 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-22T22:18:18.932Z,1555971498.932 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-22T22:18:18.932Z,1555971498.932 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-22T22:18:18.990Z,1555971498.990 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-22T22:18:18.990Z,1555971498.990 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-22T22:18:19.011Z,1555971499.011 [NavChart] Loaded
2019-04-22T22:18:19.011Z,1555971499.011 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-22T22:18:19.015Z,1555971499.015 [UniversalFixResidualReporter] Loaded
2019-04-22T22:18:19.015Z,1555971499.015 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-22T22:18:19.016Z,1555971499.016 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-22T22:18:19.016Z,1555971499.016 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-22T22:18:19.040Z,1555971499.040 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-22T22:18:19.041Z,1555971499.041 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-22T22:18:19.382Z,1555971499.382 [CTD_NeilBrown] Loaded
2019-04-22T22:18:19.382Z,1555971499.382 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-04-22T22:18:19.384Z,1555971499.384 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406684E0
2019-04-22T22:18:19.384Z,1555971499.384 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 890
2019-04-22T22:18:19.394Z,1555971499.394 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-22T22:18:19.399Z,1555971499.399 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-22T22:18:19.400Z,1555971499.400 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-22T22:18:19.405Z,1555971499.405 [CTD_Seabird](INFO): created writer for : depth
2019-04-22T22:18:19.406Z,1555971499.406 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-22T22:18:19.410Z,1555971499.410 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-22T22:18:19.411Z,1555971499.411 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-22T22:18:19.416Z,1555971499.416 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-22T22:18:19.416Z,1555971499.416 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-22T22:18:19.421Z,1555971499.421 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-22T22:18:19.422Z,1555971499.422 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-22T22:18:19.427Z,1555971499.427 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-22T22:18:19.427Z,1555971499.427 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-22T22:18:19.432Z,1555971499.432 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-22T22:18:19.458Z,1555971499.458 [CTD_Seabird] Loaded
2019-04-22T22:18:19.458Z,1555971499.458 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-22T22:18:19.459Z,1555971499.459 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406984E0
2019-04-22T22:18:19.459Z,1555971499.459 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 891
2019-04-22T22:18:19.508Z,1555971499.508 [WetLabsBB2FL] Loaded
2019-04-22T22:18:19.509Z,1555971499.509 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-22T22:18:19.510Z,1555971499.510 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406C84E0
2019-04-22T22:18:19.510Z,1555971499.510 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 892
2019-04-22T22:18:19.511Z,1555971499.511 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-22T22:18:19.512Z,1555971499.512 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-22T22:18:19.598Z,1555971499.598 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-22T22:18:19.712Z,1555971499.712 [VerticalControl] Loaded
2019-04-22T22:18:19.712Z,1555971499.712 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-22T22:18:19.713Z,1555971499.713 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-22T22:18:19.782Z,1555971499.782 [HorizontalControl] Loaded
2019-04-22T22:18:19.782Z,1555971499.782 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-22T22:18:19.783Z,1555971499.783 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-22T22:18:19.788Z,1555971499.788 [SpeedControl] Loaded
2019-04-22T22:18:19.789Z,1555971499.789 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-22T22:18:19.789Z,1555971499.789 [LoopControl](DEBUG): Construct LoopControl.
2019-04-22T22:18:19.790Z,1555971499.790 [LoopControl] Loaded
2019-04-22T22:18:19.790Z,1555971499.790 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-22T22:18:19.791Z,1555971499.791 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-22T22:18:19.791Z,1555971499.791 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-22T22:18:19.845Z,1555971499.845 [DepthRateCalculator] Loaded
2019-04-22T22:18:19.845Z,1555971499.845 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-22T22:18:19.851Z,1555971499.851 [PitchRateCalculator] Loaded
2019-04-22T22:18:19.851Z,1555971499.851 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-22T22:18:19.864Z,1555971499.864 [SpeedCalculator] Loaded
2019-04-22T22:18:19.864Z,1555971499.864 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-22T22:18:19.885Z,1555971499.885 [TempGradientCalculator] Loaded
2019-04-22T22:18:19.885Z,1555971499.885 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-22T22:18:19.891Z,1555971499.891 [YawRateCalculator] Loaded
2019-04-22T22:18:19.891Z,1555971499.891 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-22T22:18:19.920Z,1555971499.920 [ElevatorOffsetCalculator] Loaded
2019-04-22T22:18:19.920Z,1555971499.920 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-22T22:18:19.921Z,1555971499.921 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-22T22:18:19.922Z,1555971499.922 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-22T22:18:20.032Z,1555971500.032 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-22T22:18:20.032Z,1555971500.032 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-22T22:18:20.160Z,1555971500.160 [BuoyancyServo] Loaded
2019-04-22T22:18:20.160Z,1555971500.160 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-22T22:18:20.172Z,1555971500.172 [ElevatorServo] Loaded
2019-04-22T22:18:20.172Z,1555971500.172 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-22T22:18:20.173Z,1555971500.173 [Module Loader](CRITICAL): Could not find element LineCaptureServo.loadAtStartup
2019-04-22T22:18:20.184Z,1555971500.184 [MassServo] Loaded
2019-04-22T22:18:20.184Z,1555971500.184 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-22T22:18:20.195Z,1555971500.195 [RudderServo] Loaded
2019-04-22T22:18:20.195Z,1555971500.195 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-22T22:18:20.206Z,1555971500.206 [ThrusterServo] Loaded
2019-04-22T22:18:20.207Z,1555971500.207 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-22T22:18:20.207Z,1555971500.207 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-22T22:18:20.208Z,1555971500.208 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-22T22:18:20.334Z,1555971500.334 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-22T22:18:20.335Z,1555971500.335 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-22T22:18:21.013Z,1555971501.013 [AHRS_M2] Loaded
2019-04-22T22:18:21.013Z,1555971501.013 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-04-22T22:18:21.156Z,1555971501.156 [DataOverHttps] Loaded
2019-04-22T22:18:21.156Z,1555971501.156 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-22T22:18:21.157Z,1555971501.157 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0
2019-04-22T22:18:21.158Z,1555971501.158 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 893
2019-04-22T22:18:21.171Z,1555971501.171 [Depth_Keller] Loaded
2019-04-22T22:18:21.171Z,1555971501.171 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-22T22:18:21.176Z,1555971501.176 [DropWeight] Loaded
2019-04-22T22:18:21.177Z,1555971501.177 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-04-22T22:18:21.273Z,1555971501.273 [NAL9602] Loaded
2019-04-22T22:18:21.274Z,1555971501.274 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-22T22:18:21.290Z,1555971501.290 [Onboard] Loaded
2019-04-22T22:18:21.291Z,1555971501.291 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-22T22:18:21.294Z,1555971501.294 [Radio_Surface] Loaded
2019-04-22T22:18:21.294Z,1555971501.294 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-22T22:18:21.295Z,1555971501.295 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0
2019-04-22T22:18:21.296Z,1555971501.296 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 894
2019-04-22T22:18:23.088Z,1555971503.088 [BPC1] Loaded
2019-04-22T22:18:23.088Z,1555971503.088 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-22T22:18:23.088Z,1555971503.088 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-22T22:18:23.089Z,1555971503.089 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-22T22:18:23.246Z,1555971503.246 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-22T22:18:23.257Z,1555971503.257 [SBIT] Loaded
2019-04-22T22:18:23.258Z,1555971503.258 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-22T22:18:23.259Z,1555971503.259 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-22T22:18:23.271Z,1555971503.271 [IBIT] Loaded
2019-04-22T22:18:23.271Z,1555971503.271 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-22T22:18:23.274Z,1555971503.274 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-22T22:18:23.413Z,1555971503.413 [CBIT] Loaded
2019-04-22T22:18:23.413Z,1555971503.413 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-22T22:18:23.414Z,1555971503.414 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-22T22:18:23.415Z,1555971503.415 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-22T22:18:23.455Z,1555971503.455 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-22T22:18:23.459Z,1555971503.459 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-22T22:18:23.460Z,1555971503.460 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-22T22:18:23.467Z,1555971503.467 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-22T22:18:23.468Z,1555971503.468 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2019-04-22T22:18:23.469Z,1555971503.469 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 895
2019-04-22T22:18:23.473Z,1555971503.473 [Supervisor](INFO): Main Thread ID is 804
2019-04-22T22:18:23.473Z,1555971503.473 [Supervisor](DEBUG): Running supervisor.
2019-04-22T22:18:23.474Z,1555971503.474 [CommandLine ThreadHandler](INFO): Handler Thread ID is 896
2019-04-22T22:18:23.476Z,1555971503.476 [controlThread ThreadHandler](INFO): Handler Thread ID is 897
2019-04-22T22:18:23.477Z,1555971503.477 [controlThread](DEBUG): Initializing ControlThread
2019-04-22T22:18:23.478Z,1555971503.478 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:18:23.479Z,1555971503.479 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-22T22:18:23.479Z,1555971503.479 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-22T22:18:23.479Z,1555971503.479 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-22T22:18:23.481Z,1555971503.481 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-22T22:18:23.482Z,1555971503.482 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-22T22:18:23.482Z,1555971503.482 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-22T22:18:23.483Z,1555971503.483 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-22T22:18:23.483Z,1555971503.483 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-22T22:18:23.483Z,1555971503.483 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-22T22:18:23.483Z,1555971503.483 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-22T22:18:23.484Z,1555971503.484 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-22T22:18:23.484Z,1555971503.484 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-22T22:18:23.492Z,1555971503.492 [SBIT](INFO): Initialize SBIT Component.
2019-04-22T22:18:23.493Z,1555971503.493 [SBIT](IMPORTANT): git: 2019-04-10-14-g08d638a
2019-04-22T22:18:23.493Z,1555971503.493 [SBIT](INFO): git hash: 08d638a599400f5b89d801042872eecc619e609b
2019-04-22T22:18:23.493Z,1555971503.493 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-22T22:18:23.495Z,1555971503.495 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-04-22T22:18:23.496Z,1555971503.496 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-04-22T22:18:23.496Z,1555971503.496 [IBIT](INFO): Initialize IBIT Component.
2019-04-22T22:18:23.497Z,1555971503.497 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-22T22:18:23.498Z,1555971503.498 [logger ThreadHandler](INFO): Handler Thread ID is 898
2019-04-22T22:18:23.510Z,1555971503.510 [CBIT](DEBUG): Initialized mux pins.
2019-04-22T22:18:23.510Z,1555971503.510 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-22T22:18:23.518Z,1555971503.518 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 899
2019-04-22T22:18:23.530Z,1555971503.530 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 900
2019-04-22T22:18:23.531Z,1555971503.531 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:18:23.534Z,1555971503.534 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-22T22:18:23.534Z,1555971503.534 [CBIT](DEBUG): Initializing heartbeat.
2019-04-22T22:18:23.535Z,1555971503.535 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:18:23.536Z,1555971503.536 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 902
2019-04-22T22:18:23.537Z,1555971503.537 [WetLabsBB2FL](INFO): Powering down
2019-04-22T22:18:23.565Z,1555971503.565 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 903
2019-04-22T22:18:23.575Z,1555971503.575 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-22T22:18:23.576Z,1555971503.576 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 904
2019-04-22T22:18:23.586Z,1555971503.586 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 905
2019-04-22T22:18:23.595Z,1555971503.595 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-04-22T22:18:23.601Z,1555971503.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-22T22:18:23.602Z,1555971503.602 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-04-22T22:18:23.602Z,1555971503.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-22T22:18:23.604Z,1555971503.604 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-04-22T22:18:23.604Z,1555971503.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-22T22:18:23.606Z,1555971503.606 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-22T22:18:23.606Z,1555971503.606 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-22T22:18:23.606Z,1555971503.606 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-04-22T22:18:23.607Z,1555971503.607 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-22T22:18:23.608Z,1555971503.608 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-04-22T22:18:23.608Z,1555971503.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-22T22:18:23.610Z,1555971503.610 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-04-22T22:18:23.610Z,1555971503.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-22T22:18:23.612Z,1555971503.612 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-04-22T22:18:23.612Z,1555971503.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-22T22:18:23.614Z,1555971503.614 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-04-22T22:18:23.614Z,1555971503.614 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-22T22:18:23.616Z,1555971503.616 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-04-22T22:18:23.642Z,1555971503.642 [CBIT](DEBUG): Backplane powered.
2019-04-22T22:18:23.643Z,1555971503.643 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-22T22:18:23.673Z,1555971503.673 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-22T22:18:23.684Z,1555971503.684 [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-04-22T22:18:23.684Z,1555971503.684 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-22T22:18:23.703Z,1555971503.703 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-22T22:18:23.750Z,1555971503.750 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-22T22:18:23.753Z,1555971503.753 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-22T22:18:23.754Z,1555971503.754 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-22T22:18:23.788Z,1555971503.788 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-22T22:18:23.791Z,1555971503.791 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-22T22:18:23.797Z,1555971503.797 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-22T22:18:23.800Z,1555971503.800 [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-04-22T22:18:23.834Z,1555971503.834 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-22T22:18:23.846Z,1555971503.846 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:18:23.930Z,1555971503.930 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar
2019-04-22T22:18:23.978Z,1555971503.978 [Radio_Surface](INFO): Powering up
2019-04-22T22:18:23.994Z,1555971503.994 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-22T22:18:24.068Z,1555971504.068 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:24.070Z,1555971504.070 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-22T22:18:24.071Z,1555971504.071 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:24.078Z,1555971504.078 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-22T22:18:24.079Z,1555971504.079 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:24.086Z,1555971504.086 [MassServo](DEBUG): Initializing MassServo.
2019-04-22T22:18:24.087Z,1555971504.087 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:24.094Z,1555971504.094 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:18:24.095Z,1555971504.095 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:24.102Z,1555971504.102 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-22T22:18:24.104Z,1555971504.104 [CommandLine](FAULT): Scheduling is paused
2019-04-22T22:18:24.105Z,1555971504.105 [CBIT](INFO): Critical error at 20190422T221820
2019-04-22T22:18:24.105Z,1555971504.105 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-22T22:18:24.260Z,1555971504.260 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-04-22T22:18:24.260Z,1555971504.260 [DropWeight] Hardware Fault, FailCount= 1
2019-04-22T22:18:24.260Z,1555971504.260 [DropWeight](ERROR): Hardware Fault
2019-04-22T22:18:24.287Z,1555971504.287 [CBIT](INFO): Critical error at 20190422T221824
2019-04-22T22:18:24.290Z,1555971504.290 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-04-22T22:18:24.291Z,1555971504.291 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-04-22T22:18:24.994Z,1555971504.994 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-22T22:18:24.994Z,1555971504.994 [RudderServo](FAULT): Rudder failed to initialize
2019-04-22T22:18:24.994Z,1555971504.994 [RudderServo] Communications Fault, FailCount= 1
2019-04-22T22:18:24.994Z,1555971504.994 [RudderServo](ERROR): Communications Fault
2019-04-22T22:18:25.103Z,1555971505.103 [CBIT](INFO): Critical error at 20190422T221824
2019-04-22T22:18:25.106Z,1555971505.106 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-22T22:18:25.255Z,1555971505.255 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:18:25.255Z,1555971505.255 [RudderServo](INFO): Powering down
2019-04-22T22:18:25.946Z,1555971505.946 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:26.067Z,1555971506.067 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:18:26.071Z,1555971506.071 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-22T22:18:26.071Z,1555971506.071 [RudderServo] No Fault, FailCount= 1
2019-04-22T22:18:31.034Z,1555971511.034 [RudderServo](FAULT): Overload Error
2019-04-22T22:18:31.034Z,1555971511.034 [RudderServo] Hardware Fault, FailCount= 2
2019-04-22T22:18:31.035Z,1555971511.035 [RudderServo](ERROR): Hardware Fault
2019-04-22T22:18:31.074Z,1555971511.074 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-04-22T22:18:31.279Z,1555971511.279 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:18:31.279Z,1555971511.279 [RudderServo](INFO): Powering down
2019-04-22T22:18:32.115Z,1555971512.115 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:32.231Z,1555971512.231 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:18:32.235Z,1555971512.235 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-22T22:18:32.235Z,1555971512.235 [RudderServo] No Fault, FailCount= 2
2019-04-22T22:18:36.582Z,1555971516.582 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:18:36.582Z,1555971516.582 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-22T22:18:36.582Z,1555971516.582 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:18:36.926Z,1555971516.926 [RudderServo](FAULT): Overload Error
2019-04-22T22:18:36.926Z,1555971516.926 [RudderServo] Hardware Fault, FailCount= 3
2019-04-22T22:18:36.926Z,1555971516.926 [RudderServo](ERROR): Hardware Fault
2019-04-22T22:18:36.966Z,1555971516.966 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:18:36.966Z,1555971516.966 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-04-22T22:18:36.967Z,1555971516.967 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2019-04-22T22:18:36.986Z,1555971516.986 [CTD_Seabird](INFO): Powering down
2019-04-22T22:18:37.287Z,1555971517.287 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:18:37.287Z,1555971517.287 [RudderServo](INFO): Powering down
2019-04-22T22:18:37.289Z,1555971517.289 [CBIT](INFO): Critical error at 20190422T221836
2019-04-22T22:18:38.087Z,1555971518.087 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:38.211Z,1555971518.211 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:18:38.216Z,1555971518.216 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:18:38.216Z,1555971518.216 [CTD_Seabird] No Fault, FailCount= 1
2019-04-22T22:18:38.358Z,1555971518.358 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:18:38.360Z,1555971518.360 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:18:38.533Z,1555971518.533 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-22T22:18:42.102Z,1555971522.102 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:18:42.102Z,1555971522.102 [AHRS_M2] Data Fault, FailCount= 1
2019-04-22T22:18:42.102Z,1555971522.102 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:18:42.131Z,1555971522.131 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:18:42.494Z,1555971522.494 [AHRS_M2](INFO): Powering down
2019-04-22T22:18:43.334Z,1555971523.334 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:18:43.334Z,1555971523.334 [AHRS_M2] No Fault, FailCount= 1
2019-04-22T22:18:43.706Z,1555971523.706 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:18:51.403Z,1555971531.403 [NAL9602](INFO): Powering up NAL9602
2019-04-22T22:18:51.410Z,1555971531.410 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:18:51.411Z,1555971531.411 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-22T22:18:51.411Z,1555971531.411 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:18:51.432Z,1555971531.432 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:18:51.815Z,1555971531.815 [CTD_Seabird](INFO): Powering down
2019-04-22T22:18:52.231Z,1555971532.231 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-22T22:18:52.236Z,1555971532.236 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-22T22:18:53.076Z,1555971533.076 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:18:53.076Z,1555971533.076 [CTD_Seabird] No Fault, FailCount= 2
2019-04-22T22:18:53.158Z,1555971533.158 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:18:53.160Z,1555971533.160 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:18:53.942Z,1555971533.942 [ElevatorServo](FAULT): Overload Error
2019-04-22T22:18:53.942Z,1555971533.942 [ElevatorServo] Hardware Fault, FailCount= 1
2019-04-22T22:18:53.942Z,1555971533.942 [ElevatorServo](ERROR): Hardware Fault
2019-04-22T22:18:53.987Z,1555971533.987 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2019-04-22T22:18:54.321Z,1555971534.321 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-22T22:18:54.321Z,1555971534.321 [ElevatorServo](INFO): Powering down
2019-04-22T22:18:55.179Z,1555971535.179 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:18:55.303Z,1555971535.303 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-22T22:18:55.347Z,1555971535.347 [CBIT](INFO): Clearing failed state for component ElevatorServo
2019-04-22T22:18:55.347Z,1555971535.347 [ElevatorServo] No Fault, FailCount= 1
2019-04-22T22:18:57.166Z,1555971537.166 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:18:57.166Z,1555971537.166 [AHRS_M2] Data Fault, FailCount= 2
2019-04-22T22:18:57.166Z,1555971537.166 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:18:57.267Z,1555971537.267 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:18:57.558Z,1555971537.558 [AHRS_M2](INFO): Powering down
2019-04-22T22:18:58.467Z,1555971538.467 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:18:58.467Z,1555971538.467 [AHRS_M2] No Fault, FailCount= 2
2019-04-22T22:18:58.771Z,1555971538.771 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:19:00.446Z,1555971540.446 [ElevatorServo](FAULT): Overload Error
2019-04-22T22:19:00.446Z,1555971540.446 [ElevatorServo] Hardware Fault, FailCount= 2
2019-04-22T22:19:00.446Z,1555971540.446 [ElevatorServo](ERROR): Hardware Fault
2019-04-22T22:19:00.531Z,1555971540.531 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2019-04-22T22:19:00.854Z,1555971540.854 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-22T22:19:00.855Z,1555971540.855 [ElevatorServo](INFO): Powering down
2019-04-22T22:19:01.641Z,1555971541.641 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:19:01.759Z,1555971541.759 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-22T22:19:01.807Z,1555971541.807 [CBIT](INFO): Clearing failed state for component ElevatorServo
2019-04-22T22:19:01.807Z,1555971541.807 [ElevatorServo] No Fault, FailCount= 2
2019-04-22T22:19:02.177Z,1555971542.177 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:19:02.318Z,1555971542.318 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:19:02.750Z,1555971542.750 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:19:02.759Z,1555971542.759 [NAL9602](INFO): NAL9602 initialized
2019-04-22T22:19:03.342Z,1555971543.342 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:19:03.456Z,1555971543.456 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007212
CHAN A1 (24V): 0.001068
CHAN A2 (12V): -0.005803
CHAN A3 (5V): -0.003123
CHAN B0 (3.3V): -0.001384
CHAN B1 (3.15aV): -0.001631
CHAN B2 (3.15bV): -0.001296
CHAN B3 (GND): -0.000475
OPEN: 0.004947
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-22T22:19:03.506Z,1555971543.506 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:19:05.236Z,1555971545.236 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-04-22T22:19:06.210Z,1555971546.210 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:19:06.210Z,1555971546.210 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-22T22:19:06.210Z,1555971546.210 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:19:06.426Z,1555971546.426 [ElevatorServo](FAULT): Overload Error
2019-04-22T22:19:06.426Z,1555971546.426 [ElevatorServo] Hardware Fault, FailCount= 3
2019-04-22T22:19:06.426Z,1555971546.426 [ElevatorServo](ERROR): Hardware Fault
2019-04-22T22:19:06.432Z,1555971546.432 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:19:06.432Z,1555971546.432 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-22T22:19:06.432Z,1555971546.432 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2019-04-22T22:19:06.433Z,1555971546.433 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2019-04-22T22:19:06.614Z,1555971546.614 [CTD_Seabird](INFO): Powering down
2019-04-22T22:19:06.745Z,1555971546.745 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-22T22:19:06.745Z,1555971546.745 [ElevatorServo](INFO): Powering down
2019-04-22T22:19:06.749Z,1555971546.749 [CBIT](INFO): Critical error at 20190422T221906
2019-04-22T22:19:07.546Z,1555971547.546 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:19:07.663Z,1555971547.663 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-22T22:19:13.610Z,1555971553.610 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:19:13.610Z,1555971553.610 [AHRS_M2] Data Fault, FailCount= 3
2019-04-22T22:19:13.610Z,1555971553.610 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:19:13.715Z,1555971553.715 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:19:14.003Z,1555971554.003 [AHRS_M2](INFO): Powering down
2019-04-22T22:19:14.899Z,1555971554.899 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:19:14.899Z,1555971554.899 [AHRS_M2] No Fault, FailCount= 3
2019-04-22T22:19:15.214Z,1555971555.214 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:19:28.558Z,1555971568.558 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:19:28.558Z,1555971568.558 [AHRS_M2] Data Fault, FailCount= 4
2019-04-22T22:19:28.558Z,1555971568.558 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:19:28.593Z,1555971568.593 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:19:28.952Z,1555971568.952 [AHRS_M2](INFO): Powering down
2019-04-22T22:19:29.820Z,1555971569.820 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:19:29.820Z,1555971569.820 [AHRS_M2] No Fault, FailCount= 4
2019-04-22T22:19:30.163Z,1555971570.163 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:19:31.848Z,1555971571.848 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-04-22T22:19:31.849Z,1555971571.849 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-04-22T22:19:31.849Z,1555971571.849 [SBIT](FAULT): Control surface position failure.
2019-04-22T22:19:41.676Z,1555971581.676 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:19:43.538Z,1555971583.538 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:19:43.538Z,1555971583.538 [AHRS_M2] Data Fault, FailCount= 5
2019-04-22T22:19:43.538Z,1555971583.538 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:19:43.766Z,1555971583.766 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:19:43.767Z,1555971583.767 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-04-22T22:19:43.931Z,1555971583.931 [AHRS_M2](INFO): Powering down
2019-04-22T22:19:44.154Z,1555971584.154 [CBIT](INFO): Critical error at 20190422T221943
2019-04-22T22:19:45.579Z,1555971585.579 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-04-22T22:19:45.579Z,1555971585.579 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-04-22T22:19:45.580Z,1555971585.580 [SBIT](FAULT): Control surface position failure.
2019-04-22T22:19:45.981Z,1555971585.981 [SBIT](CRITICAL): SBIT FAILED
2019-04-22T22:19:46.032Z,1555971586.032 [CommandLine](IMPORTANT): got command configSet list
2019-04-22T22:19:46.032Z,1555971586.032 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-22T22:19:46.033Z,1555971586.033 [CommandLine](IMPORTANT): No configSet variables persisted
2019-04-22T22:19:46.035Z,1555971586.035 [CBIT](INFO): Critical error at 20190422T221945
2019-04-22T22:19:46.391Z,1555971586.391 [MissionManager](IMPORTANT): Started mission Startup
2019-04-22T22:19:46.391Z,1555971586.391 [Startup] Running Loop=1
2019-04-22T22:19:46.391Z,1555971586.391 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-22T22:19:46.391Z,1555971586.391 [Startup:A.GoToSurface] Running Loop=1
2019-04-22T22:19:46.391Z,1555971586.391 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-22T22:19:46.392Z,1555971586.392 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-22T22:19:46.392Z,1555971586.392 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-22T22:19:46.392Z,1555971586.392 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-22T22:19:46.393Z,1555971586.393 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-22T22:19:46.393Z,1555971586.393 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-22T22:19:46.407Z,1555971586.407 [Startup:StartupSatComms] Running Loop=1
2019-04-22T22:19:46.407Z,1555971586.407 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-22T22:19:46.407Z,1555971586.407 [Startup:StartupSatComms:A] Running Loop=1
2019-04-22T22:19:46.787Z,1555971586.787 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-22T22:19:47.667Z,1555971587.667 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-22T22:19:50.903Z,1555971590.903 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:19:53.488Z,1555971593.488 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-22T22:20:23.549Z,1555971623.549 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:20:23.549Z,1555971623.549 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-22T22:20:23.549Z,1555971623.549 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:20:23.551Z,1555971623.551 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:20:23.551Z,1555971623.551 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-22T22:20:23.551Z,1555971623.551 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:20:23.563Z,1555971623.563 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:20:23.563Z,1555971623.563 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:20:24.159Z,1555971624.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:20:24.159Z,1555971624.159 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-22T22:20:24.159Z,1555971624.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:20:24.159Z,1555971624.159 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-22T22:20:24.375Z,1555971624.375 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:20:29.770Z,1555971629.770 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-22T22:20:29.784Z,1555971629.784 [NavChartDb](INFO): # of records loaded: 19732
2019-04-22T22:20:29.791Z,1555971629.791 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-22T22:20:29.874Z,1555971629.874 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-22T22:20:37.637Z,1555971637.637 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-22T22:20:46.599Z,1555971646.599 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-22T22:19:46.4Z
2019-04-22T22:20:46.599Z,1555971646.599 [Startup:StartupSatComms:A] Stopped
2019-04-22T22:20:46.599Z,1555971646.599 [Startup:StartupSatComms:B] Running Loop=1
2019-04-22T22:20:47.011Z,1555971647.011 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-22T22:20:53.648Z,1555971653.648 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:20:56.917Z,1555971656.917 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006130
2019-04-22T22:21:07.196Z,1555971667.196 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20190422T221635/Courier0000.lzma
2019-04-22T22:21:07.988Z,1555971667.988 [DataOverHttps](INFO): Moved sent file to Logs/20190422T221635/Courier0000.lzma.bak
2019-04-22T22:21:07.988Z,1555971667.988 [DataOverHttps](INFO): SBD MOMSN=10736606
2019-04-22T22:21:15.040Z,1555971675.040 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:21:25.290Z,1555971685.290 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20190422T221812/Courier0000.lzma
2019-04-22T22:21:26.095Z,1555971686.095 [DataOverHttps](INFO): Moved sent file to Logs/20190422T221812/Courier0000.lzma.bak
2019-04-22T22:21:26.096Z,1555971686.096 [DataOverHttps](INFO): SBD MOMSN=10736613
2019-04-22T22:21:30.429Z,1555971690.429 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:21:36.540Z,1555971696.540 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:21:36.540Z,1555971696.540 [CTD_Seabird] No Fault, FailCount= 3
2019-04-22T22:21:36.662Z,1555971696.662 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:21:36.664Z,1555971696.664 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:21:43.656Z,1555971703.656 [DataOverHttps](INFO): Sending 584 bytes from file Logs/20190422T221635/Express0001.lzma
2019-04-22T22:21:44.459Z,1555971704.459 [DataOverHttps](INFO): Moved sent file to Logs/20190422T221635/Express0001.lzma.bak
2019-04-22T22:21:44.460Z,1555971704.460 [DataOverHttps](INFO): SBD MOMSN=10736623
2019-04-22T22:21:46.631Z,1555971706.631 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-22T22:20:46.6Z
2019-04-22T22:21:46.631Z,1555971706.631 [Startup:StartupSatComms:B] Stopped
2019-04-22T22:21:46.631Z,1555971706.631 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-22T22:21:46.631Z,1555971706.631 [Startup:StartupSatComms] Stopped
2019-04-22T22:21:46.631Z,1555971706.631 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-22T22:21:46.632Z,1555971706.632 [Startup](INFO): Completed Startup
2019-04-22T22:21:46.632Z,1555971706.632 [MissionManager](INFO): Startup is completed.
2019-04-22T22:21:46.632Z,1555971706.632 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-22T22:21:46.632Z,1555971706.632 [Startup] Stopped
2019-04-22T22:21:46.632Z,1555971706.632 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-22T22:21:46.633Z,1555971706.633 [Startup:A.GoToSurface] Stopped
2019-04-22T22:21:46.633Z,1555971706.633 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-22T22:21:47.034Z,1555971707.034 [MissionManager](IMPORTANT): Started mission Default
2019-04-22T22:21:47.035Z,1555971707.035 [Default] Running Loop=1
2019-04-22T22:21:47.035Z,1555971707.035 [Default](DEBUG): Aggregate::initialize Default
2019-04-22T22:21:47.035Z,1555971707.035 [Default:B.GoToSurface] Running Loop=1
2019-04-22T22:21:47.035Z,1555971707.035 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-22T22:21:47.035Z,1555971707.035 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-22T22:21:47.035Z,1555971707.035 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-22T22:21:47.036Z,1555971707.036 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-22T22:21:47.036Z,1555971707.036 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-22T22:21:47.036Z,1555971707.036 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-22T22:21:47.037Z,1555971707.037 [Default:A.Wait] Running Loop=1
2019-04-22T22:21:47.037Z,1555971707.037 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-22T22:21:47.065Z,1555971707.065 [NavChartDb](INFO): # of records loaded: 20000
2019-04-22T22:21:49.714Z,1555971709.714 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:21:49.714Z,1555971709.714 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-22T22:21:49.714Z,1555971709.714 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:21:49.899Z,1555971709.899 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:21:50.122Z,1555971710.122 [CTD_Seabird](INFO): Powering down
2019-04-22T22:21:51.091Z,1555971711.091 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:21:51.091Z,1555971711.091 [CTD_Seabird] No Fault, FailCount= 1
2019-04-22T22:21:51.471Z,1555971711.471 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:21:51.473Z,1555971711.473 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:22:00.375Z,1555971720.375 [Default:A.Wait](INFO): Done Waiting.
2019-04-22T22:22:00.375Z,1555971720.375 [Default:A.Wait] Stopped
2019-04-22T22:22:00.375Z,1555971720.375 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-22T22:22:00.795Z,1555971720.795 [Default:CheckIn] Running Loop=1
2019-04-22T22:22:00.795Z,1555971720.795 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-22T22:22:00.795Z,1555971720.795 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-22T22:22:01.175Z,1555971721.175 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-22T22:22:01.536Z,1555971721.536 [DataOverHttps](INFO): Sending 1137 bytes from file Logs/20190422T221812/Express0001.lzma
2019-04-22T22:22:02.339Z,1555971722.339 [DataOverHttps](INFO): Moved sent file to Logs/20190422T221812/Express0001.lzma.bak
2019-04-22T22:22:02.339Z,1555971722.339 [DataOverHttps](INFO): SBD MOMSN=10736643
2019-04-22T22:22:04.530Z,1555971724.530 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:22:04.530Z,1555971724.530 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-22T22:22:04.530Z,1555971724.530 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:22:04.831Z,1555971724.831 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:22:04.934Z,1555971724.934 [CTD_Seabird](INFO): Powering down
2019-04-22T22:22:06.043Z,1555971726.043 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:22:06.043Z,1555971726.043 [CTD_Seabird] No Fault, FailCount= 2
2019-04-22T22:22:06.274Z,1555971726.274 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:22:06.276Z,1555971726.276 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:22:06.873Z,1555971726.873 [NavChartDb](INFO): # of records loaded: 25000
2019-04-22T22:22:14.516Z,1555971734.516 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-22T22:22:19.330Z,1555971739.330 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:22:19.330Z,1555971739.330 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-22T22:22:19.330Z,1555971739.330 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:22:19.386Z,1555971739.386 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:22:19.387Z,1555971739.387 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-22T22:22:19.644Z,1555971739.644 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-22T22:22:19.734Z,1555971739.734 [CTD_Seabird](INFO): Powering down
2019-04-22T22:22:19.899Z,1555971739.899 [NavChartDb](INFO): # of records loaded: 30000
2019-04-22T22:22:24.433Z,1555971744.433 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:22:24.433Z,1555971744.433 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-22T22:22:24.433Z,1555971744.433 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:22:24.446Z,1555971744.446 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:22:24.447Z,1555971744.447 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-22T22:22:24.447Z,1555971744.447 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:22:24.488Z,1555971744.488 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:22:24.488Z,1555971744.488 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:22:24.638Z,1555971744.638 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:22:24.638Z,1555971744.638 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-22T22:22:24.639Z,1555971744.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:22:24.639Z,1555971744.639 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-22T22:22:25.053Z,1555971745.053 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:22:41.696Z,1555971761.696 [NavChartDb](INFO): # of records loaded: 35000
2019-04-22T22:22:55.800Z,1555971775.800 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-22T22:22:55.845Z,1555971775.845 [NavChartDb](INFO): # of records loaded: 37376
2019-04-22T22:22:55.854Z,1555971775.854 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-22T22:22:55.902Z,1555971775.902 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-22T22:23:02.080Z,1555971782.080 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-22T22:23:21.044Z,1555971801.044 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:23:30.173Z,1555971810.173 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:23:39.413Z,1555971819.413 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-22T22:23:41.511Z,1555971821.511 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-22T22:23:44.276Z,1555971824.276 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:23:58.983Z,1555971838.983 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-22T22:23:59.120Z,1555971839.120 [NavChartDb](INFO): # of records loaded: 16998
2019-04-22T22:23:59.124Z,1555971839.124 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-22T22:23:59.125Z,1555971839.125 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-22T22:24:03.922Z,1555971843.922 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-22T22:24:06.439Z,1555971846.439 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-22T22:24:07.258Z,1555971847.258 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:09.682Z,1555971849.682 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:12.510Z,1555971852.510 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:15.742Z,1555971855.742 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:17.914Z,1555971857.914 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:24:18.570Z,1555971858.570 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:21.802Z,1555971861.802 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:24.630Z,1555971864.630 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:25.057Z,1555971865.057 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:24:25.057Z,1555971865.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-22T22:24:25.057Z,1555971865.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:24:25.059Z,1555971865.059 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:24:25.059Z,1555971865.059 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-22T22:24:25.059Z,1555971865.059 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:24:25.097Z,1555971865.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:24:25.097Z,1555971865.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:24:25.482Z,1555971865.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:24:25.482Z,1555971865.482 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-22T22:24:25.482Z,1555971865.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:24:25.482Z,1555971865.482 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-22T22:24:25.857Z,1555971865.857 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:24:27.458Z,1555971867.458 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:27.559Z,1555971867.559 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:24:30.690Z,1555971870.690 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:33.518Z,1555971873.518 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:34.360Z,1555971874.360 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:24:36.750Z,1555971876.750 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:39.586Z,1555971879.586 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:41.325Z,1555971881.325 [NavChartDb](INFO): # of records loaded: 20000
2019-04-22T22:24:42.813Z,1555971882.813 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:43.660Z,1555971883.660 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:24:43.660Z,1555971883.660 [AHRS_M2] No Fault, FailCount= 5
2019-04-22T22:24:44.006Z,1555971884.006 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:24:45.638Z,1555971885.638 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:47.562Z,1555971887.562 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:48.038Z,1555971888.038 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:48.156Z,1555971888.156 [NavChartDb](INFO): # of records loaded: 25000
2019-04-22T22:24:48.458Z,1555971888.458 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:48.878Z,1555971888.878 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:48.894Z,1555971888.894 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:49.298Z,1555971889.298 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:49.382Z,1555971889.382 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:24:49.382Z,1555971889.382 [CTD_Seabird] No Fault, FailCount= 3
2019-04-22T22:24:49.410Z,1555971889.410 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:24:49.412Z,1555971889.412 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:24:49.714Z,1555971889.714 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:50.126Z,1555971890.126 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:50.546Z,1555971890.546 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:50.954Z,1555971890.954 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:51.370Z,1555971891.370 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:51.778Z,1555971891.778 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:51.794Z,1555971891.794 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:52.206Z,1555971892.206 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:52.598Z,1555971892.598 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:52.982Z,1555971892.982 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:53.386Z,1555971893.386 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:53.782Z,1555971893.782 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:54.302Z,1555971894.302 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:54.722Z,1555971894.722 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:54.738Z,1555971894.738 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:55.146Z,1555971895.146 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:55.546Z,1555971895.546 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:55.958Z,1555971895.958 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:56.378Z,1555971896.378 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:56.786Z,1555971896.786 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:57.182Z,1555971897.182 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:24:57.182Z,1555971897.182 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-04-22T22:24:57.182Z,1555971897.182 [AHRS_M2] Communications Fault, FailCount= 1
2019-04-22T22:24:57.182Z,1555971897.182 [AHRS_M2](ERROR): Communications Fault
2019-04-22T22:24:57.223Z,1555971897.223 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-04-22T22:24:57.251Z,1555971897.251 [AHRS_M2](INFO): Powering down
2019-04-22T22:24:57.338Z,1555971897.338 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:24:58.116Z,1555971898.116 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:24:58.116Z,1555971898.116 [AHRS_M2] No Fault, FailCount= 1
2019-04-22T22:24:58.356Z,1555971898.356 [NavChartDb](INFO): # of records loaded: 30000
2019-04-22T22:24:58.463Z,1555971898.463 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:24:59.690Z,1555971899.690 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:02.018Z,1555971902.018 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:02.426Z,1555971902.426 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:02.466Z,1555971902.466 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:25:02.466Z,1555971902.466 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-22T22:25:02.466Z,1555971902.466 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:25:02.822Z,1555971902.822 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:02.838Z,1555971902.838 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:02.870Z,1555971902.870 [CTD_Seabird](INFO): Powering down
2019-04-22T22:25:02.872Z,1555971902.872 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:25:03.246Z,1555971903.246 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:03.650Z,1555971903.650 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:04.038Z,1555971904.038 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:04.095Z,1555971904.095 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:25:04.095Z,1555971904.095 [CTD_Seabird] No Fault, FailCount= 1
2019-04-22T22:25:04.210Z,1555971904.210 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:25:04.212Z,1555971904.212 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:25:04.438Z,1555971904.438 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:04.846Z,1555971904.846 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:05.250Z,1555971905.250 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:05.654Z,1555971905.654 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:05.670Z,1555971905.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:06.066Z,1555971906.066 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:06.466Z,1555971906.466 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:06.746Z,1555971906.746 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:07.102Z,1555971907.102 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:07.537Z,1555971907.537 [NavChartDb](INFO): # of records loaded: 35000
2019-04-22T22:25:07.750Z,1555971907.750 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:07.922Z,1555971907.922 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:08.334Z,1555971908.334 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-04-22T22:25:08.350Z,1555971908.350 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:10.690Z,1555971910.690 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:13.522Z,1555971913.522 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:16.750Z,1555971916.750 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:16.937Z,1555971916.937 [NavChartDb](INFO): # of records loaded: 40000
2019-04-22T22:25:17.278Z,1555971917.278 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:25:17.278Z,1555971917.278 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-22T22:25:17.278Z,1555971917.278 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:25:17.603Z,1555971917.603 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:25:17.682Z,1555971917.682 [CTD_Seabird](INFO): Powering down
2019-04-22T22:25:18.754Z,1555971918.754 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:25:18.754Z,1555971918.754 [AHRS_M2] Data Fault, FailCount= 2
2019-04-22T22:25:18.754Z,1555971918.754 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:25:18.807Z,1555971918.807 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:25:18.807Z,1555971918.807 [CTD_Seabird] No Fault, FailCount= 2
2019-04-22T22:25:18.808Z,1555971918.808 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:25:19.026Z,1555971919.026 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:25:19.035Z,1555971919.035 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:25:19.147Z,1555971919.147 [AHRS_M2](INFO): Powering down
2019-04-22T22:25:19.566Z,1555971919.566 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:20.020Z,1555971920.020 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:25:20.020Z,1555971920.020 [AHRS_M2] No Fault, FailCount= 2
2019-04-22T22:25:20.359Z,1555971920.359 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:25:22.798Z,1555971922.798 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:24.479Z,1555971924.479 [NavChartDb](INFO): # of records loaded: 45000
2019-04-22T22:25:25.638Z,1555971925.638 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:28.466Z,1555971928.466 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:31.702Z,1555971931.702 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:32.082Z,1555971932.082 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:25:32.082Z,1555971932.082 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-22T22:25:32.082Z,1555971932.082 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:25:32.175Z,1555971932.175 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:25:32.176Z,1555971932.176 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-22T22:25:32.487Z,1555971932.487 [CTD_Seabird](INFO): Powering down
2019-04-22T22:25:33.714Z,1555971933.714 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:25:33.714Z,1555971933.714 [AHRS_M2] Data Fault, FailCount= 3
2019-04-22T22:25:33.714Z,1555971933.714 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:25:33.752Z,1555971933.752 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:25:34.103Z,1555971934.103 [AHRS_M2](INFO): Powering down
2019-04-22T22:25:34.522Z,1555971934.522 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:34.776Z,1555971934.776 [NavChartDb](INFO): # of records loaded: 50000
2019-04-22T22:25:34.972Z,1555971934.972 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:25:34.972Z,1555971934.972 [AHRS_M2] No Fault, FailCount= 3
2019-04-22T22:25:35.315Z,1555971935.315 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:25:37.770Z,1555971937.770 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:40.594Z,1555971940.594 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:43.422Z,1555971943.422 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:45.639Z,1555971945.639 [NavChartDb](INFO): # of records loaded: 55000
2019-04-22T22:25:46.654Z,1555971946.654 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:47.280Z,1555971947.280 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-22T22:25:48.658Z,1555971948.658 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:25:48.658Z,1555971948.658 [AHRS_M2] Data Fault, FailCount= 4
2019-04-22T22:25:48.658Z,1555971948.658 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:25:48.736Z,1555971948.736 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:25:49.050Z,1555971949.050 [AHRS_M2](INFO): Powering down
2019-04-22T22:25:49.470Z,1555971949.470 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:49.920Z,1555971949.920 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:25:49.920Z,1555971949.920 [AHRS_M2] No Fault, FailCount= 4
2019-04-22T22:25:50.262Z,1555971950.262 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:25:52.142Z,1555971952.142 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-22T22:25:52.702Z,1555971952.702 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:55.542Z,1555971955.542 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:25:56.467Z,1555971956.467 [NavChartDb](INFO): # of records loaded: 60000
2019-04-22T22:25:58.782Z,1555971958.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:01.602Z,1555971961.602 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:03.606Z,1555971963.606 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:26:03.606Z,1555971963.606 [AHRS_M2] Data Fault, FailCount= 5
2019-04-22T22:26:03.606Z,1555971963.606 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:26:03.644Z,1555971963.644 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:26:03.644Z,1555971963.644 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-04-22T22:26:04.000Z,1555971964.000 [AHRS_M2](INFO): Powering down
2019-04-22T22:26:04.140Z,1555971964.140 [CBIT](INFO): Critical error at 20190422T222603
2019-04-22T22:26:04.434Z,1555971964.434 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:07.650Z,1555971967.650 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:10.478Z,1555971970.478 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:11.554Z,1555971971.554 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-22T22:26:11.665Z,1555971971.665 [NavChartDb](INFO): # of records loaded: 62026
2019-04-22T22:26:11.688Z,1555971971.688 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-22T22:26:11.719Z,1555971971.719 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-22T22:26:13.718Z,1555971973.718 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:16.542Z,1555971976.542 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:19.745Z,1555971979.745 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-22T22:26:19.774Z,1555971979.774 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:22.602Z,1555971982.602 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:25.433Z,1555971985.433 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:25.870Z,1555971985.870 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:26:25.870Z,1555971985.870 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-04-22T22:26:25.870Z,1555971985.870 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:26:25.871Z,1555971985.871 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:26:25.871Z,1555971985.871 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-04-22T22:26:25.871Z,1555971985.871 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:26:25.889Z,1555971985.889 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:26:25.889Z,1555971985.889 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:26:26.284Z,1555971986.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:26:26.284Z,1555971986.284 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-04-22T22:26:26.285Z,1555971986.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:26:26.285Z,1555971986.285 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-04-22T22:26:26.653Z,1555971986.653 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:26:28.670Z,1555971988.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:30.858Z,1555971990.858 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:26:31.490Z,1555971991.490 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:34.722Z,1555971994.722 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:37.554Z,1555971997.554 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:40.782Z,1555972000.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:41.654Z,1555972001.654 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:26:43.610Z,1555972003.610 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:45.237Z,1555972005.237 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-04-22T22:26:45.240Z,1555972005.240 [BPC1](INFO): Received data from all battery sticks.
2019-04-22T22:26:46.446Z,1555972006.446 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:49.670Z,1555972009.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:51.001Z,1555972011.001 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:26:52.502Z,1555972012.502 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:55.730Z,1555972015.730 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:58.558Z,1555972018.558 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:26:59.951Z,1555972019.951 [NavChartDb](INFO): # of records loaded: 20000
2019-04-22T22:27:00.995Z,1555972020.995 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-04-22T22:22:00.8Z
2019-04-22T22:27:00.995Z,1555972020.995 [Default:CheckIn:Read_GPS] Stopped
2019-04-22T22:27:00.995Z,1555972020.995 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-22T22:27:01.419Z,1555972021.419 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-22T22:27:01.810Z,1555972021.810 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:04.638Z,1555972024.638 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:07.466Z,1555972027.466 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:09.650Z,1555972029.650 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20190422T221812/Courier0004.lzma
2019-04-22T22:27:10.456Z,1555972030.456 [DataOverHttps](INFO): Moved sent file to Logs/20190422T221812/Courier0004.lzma.bak
2019-04-22T22:27:10.456Z,1555972030.456 [DataOverHttps](INFO): SBD MOMSN=10736794
2019-04-22T22:27:10.698Z,1555972030.698 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:11.787Z,1555972031.787 [NavChartDb](INFO): # of records loaded: 25000
2019-04-22T22:27:13.526Z,1555972033.526 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:16.758Z,1555972036.758 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:19.586Z,1555972039.586 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:22.818Z,1555972042.818 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:25.646Z,1555972045.646 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:25.740Z,1555972045.740 [NavChartDb](INFO): # of records loaded: 30000
2019-04-22T22:27:28.011Z,1555972048.011 [DataOverHttps](INFO): Sending 481 bytes from file Logs/20190422T221812/Express0005.lzma
2019-04-22T22:27:28.474Z,1555972048.474 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:28.815Z,1555972048.815 [DataOverHttps](INFO): Moved sent file to Logs/20190422T221812/Express0005.lzma.bak
2019-04-22T22:27:28.816Z,1555972048.816 [DataOverHttps](INFO): SBD MOMSN=10736797
2019-04-22T22:27:29.323Z,1555972049.323 [Default:CheckIn:Read_Iridium] Stopped
2019-04-22T22:27:29.323Z,1555972049.323 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-22T22:27:29.323Z,1555972049.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-22T22:27:31.706Z,1555972051.706 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:34.534Z,1555972054.534 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:36.113Z,1555972056.113 [NavChartDb](INFO): # of records loaded: 35000
2019-04-22T22:27:37.778Z,1555972057.778 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:40.602Z,1555972060.602 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:43.430Z,1555972063.430 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:45.450Z,1555972065.450 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:48.686Z,1555972068.686 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:49.801Z,1555972069.801 [NavChartDb](INFO): # of records loaded: 40000
2019-04-22T22:27:51.514Z,1555972071.514 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:54.746Z,1555972074.746 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:27:57.574Z,1555972077.574 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:00.810Z,1555972080.810 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:02.459Z,1555972082.459 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:28:02.459Z,1555972082.459 [CTD_Seabird] No Fault, FailCount= 3
2019-04-22T22:28:02.570Z,1555972082.570 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:28:02.572Z,1555972082.572 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:28:03.641Z,1555972083.641 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:06.466Z,1555972086.466 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:06.795Z,1555972086.795 [NavChartDb](INFO): # of records loaded: 45000
2019-04-22T22:28:09.698Z,1555972089.698 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:11.125Z,1555972091.125 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-22T22:28:12.526Z,1555972092.526 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:12.791Z,1555972092.791 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-22T22:28:15.622Z,1555972095.622 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:28:15.622Z,1555972095.622 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-22T22:28:15.622Z,1555972095.622 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:28:15.758Z,1555972095.758 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:15.796Z,1555972095.796 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:28:16.026Z,1555972096.026 [CTD_Seabird](INFO): Powering down
2019-04-22T22:28:16.992Z,1555972096.992 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:28:16.992Z,1555972096.992 [CTD_Seabird] No Fault, FailCount= 1
2019-04-22T22:28:17.371Z,1555972097.371 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:28:17.372Z,1555972097.372 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:28:18.594Z,1555972098.594 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:21.818Z,1555972101.818 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:23.878Z,1555972103.878 [NavChartDb](INFO): # of records loaded: 50000
2019-04-22T22:28:24.646Z,1555972104.646 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:26.677Z,1555972106.677 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:28:26.677Z,1555972106.677 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-04-22T22:28:26.677Z,1555972106.677 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:28:26.679Z,1555972106.679 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:28:26.679Z,1555972106.679 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-04-22T22:28:26.679Z,1555972106.679 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:28:26.689Z,1555972106.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:28:26.689Z,1555972106.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:28:27.085Z,1555972107.085 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:28:27.086Z,1555972107.086 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-04-22T22:28:27.088Z,1555972107.088 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:28:27.088Z,1555972107.088 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-04-22T22:28:27.474Z,1555972107.474 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:27.497Z,1555972107.497 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:28:30.422Z,1555972110.422 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:28:30.422Z,1555972110.422 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-22T22:28:30.422Z,1555972110.422 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:28:30.706Z,1555972110.706 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:30.735Z,1555972110.735 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:28:30.826Z,1555972110.826 [CTD_Seabird](INFO): Powering down
2019-04-22T22:28:31.934Z,1555972111.934 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:28:31.934Z,1555972111.934 [CTD_Seabird] No Fault, FailCount= 2
2019-04-22T22:28:32.170Z,1555972112.170 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:28:32.178Z,1555972112.178 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:28:33.550Z,1555972113.550 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:36.766Z,1555972116.766 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:39.594Z,1555972119.594 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:42.422Z,1555972122.422 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:43.151Z,1555972123.151 [CommandLine](IMPORTANT): got command show
2019-04-22T22:28:43.151Z,1555972123.151 [CommandLine](FAULT): Incomplete syntax. Try: help show
2019-04-22T22:28:44.512Z,1555972124.512 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-22T22:28:44.688Z,1555972124.688 [NavChartDb](INFO): # of records loaded: 53494
2019-04-22T22:28:44.697Z,1555972124.697 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-22T22:28:44.698Z,1555972124.698 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-22T22:28:45.226Z,1555972125.226 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:28:45.226Z,1555972125.226 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-22T22:28:45.226Z,1555972125.226 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:28:45.303Z,1555972125.303 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:28:45.303Z,1555972125.303 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-22T22:28:45.630Z,1555972125.630 [CTD_Seabird](INFO): Powering down
2019-04-22T22:28:45.670Z,1555972125.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:48.357Z,1555972128.357 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-22T22:28:48.490Z,1555972128.490 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:51.308Z,1555972131.308 [CommandLine](IMPORTANT): got command help show
2019-04-22T22:28:51.730Z,1555972131.730 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:54.550Z,1555972134.550 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:57.782Z,1555972137.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:28:58.881Z,1555972138.881 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:29:00.610Z,1555972140.610 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:29:03.438Z,1555972143.438 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:29:03.831Z,1555972143.831 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-04-22T22:29:03.831Z,1555972143.831 [NAL9602] Data Fault, FailCount= 1
2019-04-22T22:29:03.831Z,1555972143.831 [NAL9602](ERROR): Data Fault
2019-04-22T22:29:03.912Z,1555972143.912 [CBIT](ERROR): Data Fault in component: NAL9602
2019-04-22T22:29:04.235Z,1555972144.235 [NAL9602](INFO): Powering down
2019-04-22T22:29:05.092Z,1555972145.092 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-22T22:29:05.092Z,1555972145.092 [NAL9602] No Fault, FailCount= 1
2019-04-22T22:29:08.605Z,1555972148.605 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:29:15.198Z,1555972155.198 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:29:24.952Z,1555972164.952 [NavChartDb](INFO): # of records loaded: 20000
2019-04-22T22:29:31.487Z,1555972171.487 [NavChartDb](INFO): # of records loaded: 25000
2019-04-22T22:29:34.535Z,1555972174.535 [NAL9602](INFO): Powering up NAL9602
2019-04-22T22:29:38.068Z,1555972178.068 [NavChartDb](INFO): # of records loaded: 30000
2019-04-22T22:29:44.602Z,1555972184.602 [NavChartDb](INFO): # of records loaded: 35000
2019-04-22T22:29:45.440Z,1555972185.440 [NAL9602](INFO): NAL9602 initialized
2019-04-22T22:29:48.686Z,1555972188.686 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:29:51.285Z,1555972191.285 [NavChartDb](INFO): # of records loaded: 40000
2019-04-22T22:29:51.514Z,1555972191.514 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:29:53.534Z,1555972193.534 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:29:55.554Z,1555972195.554 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:29:58.786Z,1555972198.786 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:01.255Z,1555972201.255 [NavChartDb](INFO): # of records loaded: 45000
2019-04-22T22:30:01.614Z,1555972201.614 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:04.846Z,1555972204.846 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:07.674Z,1555972207.674 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:08.343Z,1555972208.343 [NavChartDb](INFO): # of records loaded: 50000
2019-04-22T22:30:10.502Z,1555972210.502 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:12.522Z,1555972212.522 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:14.542Z,1555972214.542 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:15.602Z,1555972215.602 [NavChartDb](INFO): # of records loaded: 55000
2019-04-22T22:30:17.774Z,1555972217.774 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:20.602Z,1555972220.602 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:23.834Z,1555972223.834 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:25.272Z,1555972225.272 [NavChartDb](INFO): # of records loaded: 60000
2019-04-22T22:30:26.662Z,1555972226.662 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:27.885Z,1555972227.885 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:30:27.885Z,1555972227.885 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-04-22T22:30:27.885Z,1555972227.885 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:30:27.887Z,1555972227.887 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:30:27.887Z,1555972227.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-04-22T22:30:27.887Z,1555972227.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:30:27.913Z,1555972227.913 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:30:27.913Z,1555972227.913 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:30:28.314Z,1555972228.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:30:28.314Z,1555972228.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-04-22T22:30:28.314Z,1555972228.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:30:28.314Z,1555972228.314 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-04-22T22:30:28.697Z,1555972228.697 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:30:29.894Z,1555972229.894 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:29.996Z,1555972229.996 [CommandLine](IMPORTANT): got command configSet 
2019-04-22T22:30:29.996Z,1555972229.996 [CommandLine](FAULT): Incomplete syntax. Try: help configSet
2019-04-22T22:30:32.722Z,1555972232.722 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:35.373Z,1555972235.373 [NavChartDb](INFO): # of records loaded: 65000
2019-04-22T22:30:35.554Z,1555972235.554 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:38.782Z,1555972238.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:41.610Z,1555972241.610 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:42.016Z,1555972242.016 [NavChartDb](INFO): # of records loaded: 70000
2019-04-22T22:30:44.842Z,1555972244.842 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:47.670Z,1555972247.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:48.813Z,1555972248.813 [NavChartDb](INFO): # of records loaded: 75000
2019-04-22T22:30:50.906Z,1555972250.906 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:53.734Z,1555972253.734 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:55.537Z,1555972255.537 [NavChartDb](INFO): # of records loaded: 80000
2019-04-22T22:30:56.562Z,1555972256.562 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:30:59.794Z,1555972259.794 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:02.622Z,1555972262.622 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:02.675Z,1555972262.675 [NavChartDb](INFO): # of records loaded: 85000
2019-04-22T22:31:03.864Z,1555972263.864 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:31:03.864Z,1555972263.864 [AHRS_M2] No Fault, FailCount= 5
2019-04-22T22:31:04.223Z,1555972264.223 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:31:05.854Z,1555972265.854 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:08.702Z,1555972268.702 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:11.526Z,1555972271.526 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:12.489Z,1555972272.489 [NavChartDb](INFO): # of records loaded: 90000
2019-04-22T22:31:13.546Z,1555972273.546 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:15.235Z,1555972275.235 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:31:15.235Z,1555972275.235 [CTD_Seabird] No Fault, FailCount= 3
2019-04-22T22:31:15.282Z,1555972275.282 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:31:15.284Z,1555972275.284 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:31:16.794Z,1555972276.794 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:17.586Z,1555972277.586 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:31:17.586Z,1555972277.586 [AHRS_M2] Data Fault, FailCount= 1
2019-04-22T22:31:17.586Z,1555972277.586 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:31:17.631Z,1555972277.631 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:31:17.975Z,1555972277.975 [AHRS_M2](INFO): Powering down
2019-04-22T22:31:18.823Z,1555972278.823 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:31:18.823Z,1555972278.823 [AHRS_M2] No Fault, FailCount= 1
2019-04-22T22:31:19.186Z,1555972279.186 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:31:19.606Z,1555972279.606 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:22.071Z,1555972282.071 [NavChartDb](INFO): # of records loaded: 95000
2019-04-22T22:31:22.866Z,1555972282.866 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:25.682Z,1555972285.682 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:28.334Z,1555972288.334 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:31:28.334Z,1555972288.334 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-22T22:31:28.334Z,1555972288.334 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:31:28.506Z,1555972288.506 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:28.554Z,1555972288.554 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:31:28.738Z,1555972288.738 [CTD_Seabird](INFO): Powering down
2019-04-22T22:31:29.438Z,1555972289.438 [NavChartDb](INFO): # of records loaded: 100000
2019-04-22T22:31:29.747Z,1555972289.747 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:31:29.747Z,1555972289.747 [CTD_Seabird] No Fault, FailCount= 1
2019-04-22T22:31:30.082Z,1555972290.082 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:31:30.084Z,1555972290.084 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:31:30.530Z,1555972290.530 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:32.530Z,1555972292.530 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:31:32.531Z,1555972292.531 [AHRS_M2] Data Fault, FailCount= 2
2019-04-22T22:31:32.531Z,1555972292.531 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:31:32.558Z,1555972292.558 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:32.596Z,1555972292.596 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:31:32.923Z,1555972292.923 [AHRS_M2](INFO): Powering down
2019-04-22T22:31:33.767Z,1555972293.767 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:31:33.767Z,1555972293.767 [AHRS_M2] No Fault, FailCount= 2
2019-04-22T22:31:34.135Z,1555972294.135 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:31:34.554Z,1555972294.554 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:36.720Z,1555972296.720 [NavChartDb](INFO): # of records loaded: 105000
2019-04-22T22:31:37.814Z,1555972297.814 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:40.630Z,1555972300.630 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:43.134Z,1555972303.134 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:31:43.134Z,1555972303.134 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-22T22:31:43.134Z,1555972303.134 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:31:43.499Z,1555972303.499 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:31:43.538Z,1555972303.538 [CTD_Seabird](INFO): Powering down
2019-04-22T22:31:43.862Z,1555972303.862 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:44.699Z,1555972304.699 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-22T22:31:44.699Z,1555972304.699 [CTD_Seabird] No Fault, FailCount= 2
2019-04-22T22:31:44.878Z,1555972304.878 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-22T22:31:44.884Z,1555972304.884 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-22T22:31:46.702Z,1555972306.702 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:47.482Z,1555972307.482 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:31:47.482Z,1555972307.482 [AHRS_M2] Data Fault, FailCount= 3
2019-04-22T22:31:47.482Z,1555972307.482 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:31:47.571Z,1555972307.571 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:31:47.689Z,1555972307.689 [NavChartDb](INFO): # of records loaded: 110000
2019-04-22T22:31:47.870Z,1555972307.870 [AHRS_M2](INFO): Powering down
2019-04-22T22:31:48.719Z,1555972308.719 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:31:48.719Z,1555972308.719 [AHRS_M2] No Fault, FailCount= 3
2019-04-22T22:31:49.082Z,1555972309.082 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:31:49.502Z,1555972309.502 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:51.522Z,1555972311.522 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:54.774Z,1555972314.774 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:55.296Z,1555972315.296 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-22T22:31:56.223Z,1555972316.223 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-22T22:31:57.598Z,1555972317.598 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:31:57.938Z,1555972317.938 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-22T22:31:57.938Z,1555972317.938 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-22T22:31:57.938Z,1555972317.938 [CTD_Seabird](ERROR): Communications Fault
2019-04-22T22:31:58.031Z,1555972318.031 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-22T22:31:58.031Z,1555972318.031 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-22T22:31:58.342Z,1555972318.342 [CTD_Seabird](INFO): Powering down
2019-04-22T22:31:58.753Z,1555972318.753 [NavChartDb](INFO): # of records loaded: 115000
2019-04-22T22:32:00.830Z,1555972320.830 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:02.426Z,1555972322.426 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:32:02.426Z,1555972322.426 [AHRS_M2] Data Fault, FailCount= 4
2019-04-22T22:32:02.426Z,1555972322.426 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:32:02.457Z,1555972322.457 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:32:02.818Z,1555972322.818 [AHRS_M2](INFO): Powering down
2019-04-22T22:32:03.642Z,1555972323.642 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:03.689Z,1555972323.689 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-22T22:32:03.689Z,1555972323.689 [AHRS_M2] No Fault, FailCount= 4
2019-04-22T22:32:04.030Z,1555972324.030 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:32:06.547Z,1555972326.547 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-22T22:32:06.730Z,1555972326.730 [NavChartDb](INFO): # of records loaded: 117290
2019-04-22T22:32:06.735Z,1555972326.735 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-22T22:32:06.736Z,1555972326.736 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-22T22:32:06.874Z,1555972326.874 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:08.850Z,1555972328.850 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-22T22:32:09.718Z,1555972329.718 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:12.546Z,1555972332.546 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:15.778Z,1555972335.778 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:17.378Z,1555972337.378 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:32:17.378Z,1555972337.378 [AHRS_M2] Data Fault, FailCount= 5
2019-04-22T22:32:17.378Z,1555972337.378 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:32:17.451Z,1555972337.451 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:32:17.451Z,1555972337.451 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-04-22T22:32:17.767Z,1555972337.767 [AHRS_M2](INFO): Powering down
2019-04-22T22:32:17.885Z,1555972337.885 [CBIT](INFO): Critical error at 20190422T223217
2019-04-22T22:32:18.590Z,1555972338.590 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:19.513Z,1555972339.513 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:32:21.822Z,1555972341.822 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:24.650Z,1555972344.650 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:26.240Z,1555972346.240 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:32:27.886Z,1555972347.886 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:28.713Z,1555972348.713 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:32:28.713Z,1555972348.713 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-04-22T22:32:28.713Z,1555972348.713 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-22T22:32:28.715Z,1555972348.715 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-22T22:32:28.715Z,1555972348.715 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-04-22T22:32:28.715Z,1555972348.715 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-22T22:32:28.729Z,1555972348.729 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-22T22:32:28.730Z,1555972348.730 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-22T22:32:29.114Z,1555972349.114 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-22T22:32:29.114Z,1555972349.114 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-04-22T22:32:29.115Z,1555972349.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-22T22:32:29.115Z,1555972349.115 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-04-22T22:32:29.513Z,1555972349.513 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-22T22:32:29.919Z,1555972349.919 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-22T22:32:29.919Z,1555972349.919 [Default:CheckIn:C.Wait] Stopped
2019-04-22T22:32:29.919Z,1555972349.919 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-22T22:32:29.919Z,1555972349.919 [Default:CheckIn:D] Running Loop=1
2019-04-22T22:32:30.323Z,1555972350.323 [Default:CheckIn:D] Stopped
2019-04-22T22:32:30.323Z,1555972350.323 [Default:CheckIn:E] Running Loop=1
2019-04-22T22:32:30.714Z,1555972350.714 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:30.743Z,1555972350.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.721468 min 
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn:E] Stopped
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn] Stopped
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn](INFO): Running loop #2
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn] Running Loop=2
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-22T22:32:30.744Z,1555972350.744 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-22T22:32:33.075Z,1555972353.075 [NavChartDb](INFO): # of records loaded: 15000
2019-04-22T22:32:33.542Z,1555972353.542 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:36.774Z,1555972356.774 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:39.602Z,1555972359.602 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:40.010Z,1555972360.010 [NavChartDb](INFO): # of records loaded: 20000
2019-04-22T22:32:42.834Z,1555972362.834 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:45.662Z,1555972365.662 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:47.866Z,1555972367.866 [NavChartDb](INFO): # of records loaded: 25000
2019-04-22T22:32:48.894Z,1555972368.894 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:51.722Z,1555972371.722 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:53.787Z,1555972373.787 [CommandLine](IMPORTANT): got command failComponent
2019-04-22T22:32:53.787Z,1555972373.787 [CommandLine](IMPORTANT): Failed components:
2019-04-22T22:32:53.787Z,1555972373.787 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2019-04-22T22:32:53.787Z,1555972373.787 [CommandLine](IMPORTANT): ElevatorServo: Hardware Fault
2019-04-22T22:32:53.788Z,1555972373.788 [CommandLine](IMPORTANT): RudderServo: Hardware Fault
2019-04-22T22:32:53.788Z,1555972373.788 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-04-22T22:32:53.788Z,1555972373.788 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2019-04-22T22:32:54.550Z,1555972374.550 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:57.782Z,1555972377.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:32:59.142Z,1555972379.142 [NavChartDb](INFO): # of records loaded: 30000
2019-04-22T22:33:00.610Z,1555972380.610 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:03.842Z,1555972383.842 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:04.419Z,1555972384.419 [CommandLine](IMPORTANT): got command failComponent none AHRS_M2
2019-04-22T22:33:04.419Z,1555972384.419 [AHRS_M2] No Fault, FailCount= 5
2019-04-22T22:33:04.419Z,1555972384.419 [CommandLine](IMPORTANT): AHRS_M2 failureMode is No Fault
2019-04-22T22:33:04.635Z,1555972384.635 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-22T22:33:06.670Z,1555972386.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:08.406Z,1555972388.406 [NavChartDb](INFO): # of records loaded: 35000
2019-04-22T22:33:09.530Z,1555972389.530 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:11.534Z,1555972391.534 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:13.554Z,1555972393.554 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:16.685Z,1555972396.685 [NavChartDb](INFO): # of records loaded: 40000
2019-04-22T22:33:16.782Z,1555972396.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:17.982Z,1555972397.982 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-04-22T22:33:17.982Z,1555972397.982 [AHRS_M2] Data Fault, FailCount= 6
2019-04-22T22:33:17.982Z,1555972397.982 [AHRS_M2](ERROR): Data Fault
2019-04-22T22:33:18.023Z,1555972398.023 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-04-22T22:33:18.024Z,1555972398.024 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-04-22T22:33:18.371Z,1555972398.371 [AHRS_M2](INFO): Powering down
2019-04-22T22:33:18.508Z,1555972398.508 [CBIT](INFO): Critical error at 20190422T223318
2019-04-22T22:33:18.596Z,1555972398.596 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-22T22:33:19.317Z,1555972399.317 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-22T22:33:19.598Z,1555972399.598 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:22.830Z,1555972402.830 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:24.481Z,1555972404.481 [CBIT](INFO): Clearing failed state for component DropWeight
2019-04-22T22:33:24.482Z,1555972404.482 [DropWeight] No Fault, FailCount= 1
2019-04-22T22:33:25.670Z,1555972405.670 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:28.890Z,1555972408.890 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:29.220Z,1555972409.220 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-22T22:33:29.396Z,1555972409.396 [NavChartDb](INFO): # of records loaded: 44880
2019-04-22T22:33:29.423Z,1555972409.423 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-22T22:33:29.423Z,1555972409.423 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-22T22:33:31.718Z,1555972411.718 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:33.395Z,1555972413.395 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-22T22:33:34.546Z,1555972414.546 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:36.991Z,1555972416.991 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-22T22:33:36.991Z,1555972416.991 [RudderServo] No Fault, FailCount= 3
2019-04-22T22:33:37.778Z,1555972417.778 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:40.606Z,1555972420.606 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:41.490Z,1555972421.490 [RudderServo](FAULT): Overload Error
2019-04-22T22:33:41.490Z,1555972421.490 [RudderServo] Hardware Fault, FailCount= 1
2019-04-22T22:33:41.490Z,1555972421.490 [RudderServo](ERROR): Hardware Fault
2019-04-22T22:33:41.530Z,1555972421.530 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-04-22T22:33:41.840Z,1555972421.840 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:33:41.840Z,1555972421.840 [RudderServo](INFO): Powering down
2019-04-22T22:33:42.652Z,1555972422.652 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:33:42.767Z,1555972422.767 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:33:42.771Z,1555972422.771 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-22T22:33:42.772Z,1555972422.772 [RudderServo] No Fault, FailCount= 1
2019-04-22T22:33:43.394Z,1555972423.394 [NavChartDb](INFO): # of records loaded: 5000
2019-04-22T22:33:43.838Z,1555972423.838 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:46.678Z,1555972426.678 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:47.554Z,1555972427.554 [RudderServo](FAULT): Overload Error
2019-04-22T22:33:47.554Z,1555972427.554 [RudderServo] Hardware Fault, FailCount= 2
2019-04-22T22:33:47.554Z,1555972427.554 [RudderServo](ERROR): Hardware Fault
2019-04-22T22:33:47.595Z,1555972427.595 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-04-22T22:33:47.908Z,1555972427.908 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:33:47.908Z,1555972427.908 [RudderServo](INFO): Powering down
2019-04-22T22:33:48.716Z,1555972428.716 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:33:48.831Z,1555972428.831 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:33:48.836Z,1555972428.836 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-22T22:33:48.836Z,1555972428.836 [RudderServo] No Fault, FailCount= 2
2019-04-22T22:33:49.506Z,1555972429.506 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:51.526Z,1555972431.526 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:52.249Z,1555972432.249 [NavChartDb](INFO): # of records loaded: 10000
2019-04-22T22:33:53.558Z,1555972433.558 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:53.614Z,1555972433.614 [RudderServo](FAULT): Overload Error
2019-04-22T22:33:53.614Z,1555972433.614 [RudderServo] Hardware Fault, FailCount= 3
2019-04-22T22:33:53.614Z,1555972433.614 [RudderServo](ERROR): Hardware Fault
2019-04-22T22:33:53.658Z,1555972433.658 [CBIT](ERROR): Hardware Fault in component: RudderServo
2019-04-22T22:33:53.659Z,1555972433.659 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2019-04-22T22:33:53.988Z,1555972433.988 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:33:53.988Z,1555972433.988 [RudderServo](INFO): Powering down
2019-04-22T22:33:53.994Z,1555972433.994 [CBIT](INFO): Critical error at 20190422T223353
2019-04-22T22:33:54.776Z,1555972434.776 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-22T22:33:54.891Z,1555972434.891 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-22T22:33:55.251Z,1555972435.251 [CommandLine](IMPORTANT): got command failComponent
2019-04-22T22:33:55.251Z,1555972435.251 [CommandLine](IMPORTANT): Failed components:
2019-04-22T22:33:55.251Z,1555972435.251 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2019-04-22T22:33:55.251Z,1555972435.251 [CommandLine](IMPORTANT): ElevatorServo: Hardware Fault
2019-04-22T22:33:55.252Z,1555972435.252 [CommandLine](IMPORTANT): RudderServo: Hardware Fault
2019-04-22T22:33:55.252Z,1555972435.252 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-04-22T22:33:56.782Z,1555972436.782 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:33:59.606Z,1555972439.606 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:34:00.723Z,1555972440.723 [CommandLine](IMPORTANT): got command restart application
2019-04-22T22:34:01.730Z,1555972441.730 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:01.730Z,1555972441.730 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:01.898Z,1555972441.898 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-22T22:34:01.898Z,1555972441.898 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:01.899Z,1555972441.899 [CommandLine](INFO): Join timeout helper Thread ID is 986
2019-04-22T22:34:01.910Z,1555972441.910 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-22T22:34:01.910Z,1555972441.910 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:01.911Z,1555972441.911 [NavChartDb](INFO): Join timeout helper Thread ID is 987
2019-04-22T22:34:01.926Z,1555972441.926 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:01.926Z,1555972441.926 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:01.938Z,1555972441.938 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-22T22:34:01.938Z,1555972441.938 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:01.939Z,1555972441.939 [Radio_Surface](INFO): Join timeout helper Thread ID is 988
2019-04-22T22:34:02.306Z,1555972442.306 [Radio_Surface](INFO): Powering down
2019-04-22T22:34:02.307Z,1555972442.307 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:02.307Z,1555972442.307 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:02.326Z,1555972442.326 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-22T22:34:02.326Z,1555972442.326 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:02.326Z,1555972442.326 [DataOverHttps](INFO): Join timeout helper Thread ID is 989
2019-04-22T22:34:02.838Z,1555972442.838 [NAL9602](DEBUG): Fix Requested
2019-04-22T22:34:03.080Z,1555972443.080 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:03.084Z,1555972443.084 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.102Z,1555972443.102 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-22T22:34:03.102Z,1555972443.102 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.102Z,1555972443.102 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 990
2019-04-22T22:34:03.110Z,1555972443.110 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:03.111Z,1555972443.111 [WetLabsBB2FL](INFO): Powering down
2019-04-22T22:34:03.111Z,1555972443.111 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.123Z,1555972443.123 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-22T22:34:03.123Z,1555972443.123 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.123Z,1555972443.123 [CTD_Seabird](INFO): Join timeout helper Thread ID is 991
2019-04-22T22:34:03.378Z,1555972443.378 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:03.378Z,1555972443.378 [CTD_Seabird](INFO): Powering down
2019-04-22T22:34:03.390Z,1555972443.390 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.395Z,1555972443.395 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-04-22T22:34:03.395Z,1555972443.395 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.395Z,1555972443.395 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 992
2019-04-22T22:34:03.490Z,1555972443.490 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:03.490Z,1555972443.490 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.499Z,1555972443.499 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-22T22:34:03.499Z,1555972443.499 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.499Z,1555972443.499 [logger](INFO): Join timeout helper Thread ID is 993
2019-04-22T22:34:03.554Z,1555972443.554 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:03.554Z,1555972443.554 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.559Z,1555972443.559 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-22T22:34:03.559Z,1555972443.559 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.559Z,1555972443.559 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-22T22:34:03.559Z,1555972443.559 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.559Z,1555972443.559 [controlThread](INFO): Join timeout helper Thread ID is 994
2019-04-22T22:34:03.626Z,1555972443.626 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-22T22:34:03.626Z,1555972443.626 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-22T22:34:03.627Z,1555972443.627 [AHRS_M2](INFO): Powering down
2019-04-22T22:34:03.628Z,1555972443.628 [NAL9602](INFO): Powering down
2019-04-22T22:34:03.629Z,1555972443.629 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-22T22:34:03.630Z,1555972443.630 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-22T22:34:03.631Z,1555972443.631 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-22T22:34:03.631Z,1555972443.631 [MissionManager](INFO): Uninitializing Mission Default
2019-04-22T22:34:03.631Z,1555972443.631 [Default] Stopped
2019-04-22T22:34:03.631Z,1555972443.631 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-22T22:34:03.631Z,1555972443.631 [Default:B.GoToSurface] Stopped
2019-04-22T22:34:03.631Z,1555972443.631 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-22T22:34:03.632Z,1555972443.632 [Default:CheckIn] Stopped
2019-04-22T22:34:03.632Z,1555972443.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-22T22:34:03.632Z,1555972443.632 [Default:CheckIn:Read_GPS] Stopped
2019-04-22T22:34:03.634Z,1555972443.634 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-22T22:34:03.634Z,1555972443.634 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-22T22:34:03.634Z,1555972443.634 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-22T22:34:03.635Z,1555972443.635 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-22T22:34:03.635Z,1555972443.635 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-22T22:34:03.635Z,1555972443.635 [BuoyancyServo](INFO): Powering down
2019-04-22T22:34:03.646Z,1555972443.646 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-22T22:34:03.646Z,1555972443.646 [ElevatorServo](INFO): Powering down
2019-04-22T22:34:03.647Z,1555972443.647 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-22T22:34:03.647Z,1555972443.647 [MassServo](INFO): Powering down
2019-04-22T22:34:03.648Z,1555972443.648 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-22T22:34:03.648Z,1555972443.648 [RudderServo](INFO): Powering down
2019-04-22T22:34:03.649Z,1555972443.649 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-22T22:34:03.649Z,1555972443.649 [ThrusterServo](INFO): Powering down
2019-04-22T22:34:03.650Z,1555972443.650 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-22T22:34:03.650Z,1555972443.650 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-22T22:34:03.650Z,1555972443.650 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-22T22:34:03.650Z,1555972443.650 [CBIT](DEBUG): Powering off loads.
2019-04-22T22:34:03.662Z,1555972443.662 [CBIT](DEBUG): Disabling WDT.
2019-04-22T22:34:03.674Z,1555972443.674 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-22T22:34:03.675Z,1555972443.675 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.697Z,1555972443.697 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.701Z,1555972443.701 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:03.704Z,1555972443.704 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:04.018Z,1555972444.018 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:04.023Z,1555972444.023 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:04.065Z,1555972444.065 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-22T22:34:04.112Z,1555972444.112 [logger ThreadHandler](INFO): Thread cancelled.