2019-04-05T17:58:30.352Z,1554487110.352 [Supervisor](DEBUG): Initializing supervisor.
2019-04-05T17:58:30.355Z,1554487110.355 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-05T17:58:30.355Z,1554487110.355 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-04-05T17:58:30.356Z,1554487110.356 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-05T17:58:30.356Z,1554487110.356 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-05T17:58:30.357Z,1554487110.357 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-04-05T17:58:30.360Z,1554487110.360 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-05T17:58:30.372Z,1554487110.372 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-05T17:58:30.373Z,1554487110.373 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-05T17:58:30.373Z,1554487110.373 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-04-05T17:58:30.374Z,1554487110.374 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-05T17:58:30.375Z,1554487110.375 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-05T17:58:30.375Z,1554487110.375 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-04-05T17:58:30.377Z,1554487110.377 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-05T17:58:30.378Z,1554487110.378 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-05T17:58:30.381Z,1554487110.381 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-05T17:58:30.477Z,1554487110.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-05T17:58:30.479Z,1554487110.479 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-05T17:58:30.960Z,1554487110.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-05T17:58:30.962Z,1554487110.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-05T17:58:31.060Z,1554487111.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-05T17:58:31.061Z,1554487111.061 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-05T17:58:31.163Z,1554487111.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-05T17:58:31.163Z,1554487111.163 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-05T17:58:31.244Z,1554487111.244 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-05T17:58:31.386Z,1554487111.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-05T17:58:31.386Z,1554487111.386 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-05T17:58:31.685Z,1554487111.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-05T17:58:31.685Z,1554487111.685 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-05T17:58:32.147Z,1554487112.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-05T17:58:32.147Z,1554487112.147 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-05T17:58:32.295Z,1554487112.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-05T17:58:32.295Z,1554487112.295 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-05T17:58:32.491Z,1554487112.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-05T17:58:32.492Z,1554487112.492 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-05T17:58:32.974Z,1554487112.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-05T17:58:32.974Z,1554487112.974 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-05T17:58:33.190Z,1554487113.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-05T17:58:33.192Z,1554487113.192 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-05T17:58:33.395Z,1554487113.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-05T17:58:33.396Z,1554487113.396 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-05T17:58:33.798Z,1554487113.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-05T17:58:33.798Z,1554487113.798 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-05T17:58:34.490Z,1554487114.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-05T17:58:34.492Z,1554487114.492 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-04-05T17:58:34.495Z,1554487114.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-04-05T17:58:34.579Z,1554487114.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-04-05T17:58:34.730Z,1554487114.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-04-05T17:58:34.839Z,1554487114.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-04-05T17:58:34.924Z,1554487114.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-04-05T17:58:35.019Z,1554487115.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-04-05T17:58:35.199Z,1554487115.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-04-05T17:58:35.433Z,1554487115.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-05T17:58:35.433Z,1554487115.433 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-04-05T17:58:35.527Z,1554487115.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-04-05T17:58:35.626Z,1554487115.626 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-04-05T17:58:35.741Z,1554487115.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-04-05T17:58:35.841Z,1554487115.841 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-05T17:58:35.850Z,1554487115.850 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-05T17:58:35.940Z,1554487115.940 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-05T17:58:36.054Z,1554487116.054 [VerticalControl] Loaded
2019-04-05T17:58:36.055Z,1554487116.055 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-05T17:58:36.055Z,1554487116.055 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-05T17:58:36.125Z,1554487116.125 [HorizontalControl] Loaded
2019-04-05T17:58:36.125Z,1554487116.125 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-05T17:58:36.126Z,1554487116.126 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-05T17:58:36.131Z,1554487116.131 [SpeedControl] Loaded
2019-04-05T17:58:36.131Z,1554487116.131 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-05T17:58:36.132Z,1554487116.132 [LoopControl](DEBUG): Construct LoopControl.
2019-04-05T17:58:36.133Z,1554487116.133 [LoopControl] Loaded
2019-04-05T17:58:36.133Z,1554487116.133 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-05T17:58:36.133Z,1554487116.133 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-05T17:58:36.134Z,1554487116.134 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-05T17:58:36.190Z,1554487116.190 [DepthRateCalculator] Loaded
2019-04-05T17:58:36.190Z,1554487116.190 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-05T17:58:36.195Z,1554487116.195 [PitchRateCalculator] Loaded
2019-04-05T17:58:36.195Z,1554487116.195 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-05T17:58:36.211Z,1554487116.211 [SpeedCalculator] Loaded
2019-04-05T17:58:36.212Z,1554487116.212 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-05T17:58:36.234Z,1554487116.234 [TempGradientCalculator] Loaded
2019-04-05T17:58:36.234Z,1554487116.234 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-05T17:58:36.239Z,1554487116.239 [YawRateCalculator] Loaded
2019-04-05T17:58:36.240Z,1554487116.240 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-05T17:58:36.269Z,1554487116.269 [ElevatorOffsetCalculator] Loaded
2019-04-05T17:58:36.269Z,1554487116.269 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-05T17:58:36.270Z,1554487116.270 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-05T17:58:36.271Z,1554487116.271 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-05T17:58:36.313Z,1554487116.313 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-05T17:58:36.313Z,1554487116.313 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-05T17:58:36.415Z,1554487116.415 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-05T17:58:36.416Z,1554487116.416 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-05T17:58:36.738Z,1554487116.738 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-05T17:58:36.739Z,1554487116.739 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-05T17:58:36.865Z,1554487116.865 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-05T17:58:36.866Z,1554487116.866 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-05T17:58:37.477Z,1554487117.477 [DataOverHttps] Loaded
2019-04-05T17:58:37.477Z,1554487117.477 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-05T17:58:37.478Z,1554487117.478 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2019-04-05T17:58:37.479Z,1554487117.479 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 889
2019-04-05T17:58:37.516Z,1554487117.516 [Depth_Keller] Loaded
2019-04-05T17:58:37.516Z,1554487117.516 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-05T17:58:37.521Z,1554487117.521 [DropWeight] Loaded
2019-04-05T17:58:37.522Z,1554487117.522 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-04-05T17:58:37.621Z,1554487117.621 [NAL9602] Loaded
2019-04-05T17:58:37.621Z,1554487117.621 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-05T17:58:37.637Z,1554487117.637 [Onboard] Loaded
2019-04-05T17:58:37.638Z,1554487117.638 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-05T17:58:37.641Z,1554487117.641 [Radio_Surface] Loaded
2019-04-05T17:58:37.641Z,1554487117.641 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-05T17:58:37.642Z,1554487117.642 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2019-04-05T17:58:37.643Z,1554487117.643 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890
2019-04-05T17:58:39.149Z,1554487119.149 [BPC1] Loaded
2019-04-05T17:58:39.149Z,1554487119.149 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-05T17:58:39.150Z,1554487119.150 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-05T17:58:39.150Z,1554487119.150 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-05T17:58:39.388Z,1554487119.388 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-05T17:58:39.389Z,1554487119.389 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-05T17:58:39.564Z,1554487119.564 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-05T17:58:39.564Z,1554487119.564 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-05T17:58:39.592Z,1554487119.592 [NavChart] Loaded
2019-04-05T17:58:39.629Z,1554487119.629 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-05T17:58:39.633Z,1554487119.633 [UniversalFixResidualReporter] Loaded
2019-04-05T17:58:39.633Z,1554487119.633 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-05T17:58:39.633Z,1554487119.633 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-05T17:58:39.634Z,1554487119.634 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-05T17:58:39.860Z,1554487119.860 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-05T17:58:39.872Z,1554487119.872 [SBIT] Loaded
2019-04-05T17:58:39.872Z,1554487119.872 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-05T17:58:39.873Z,1554487119.873 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-05T17:58:39.885Z,1554487119.885 [IBIT] Loaded
2019-04-05T17:58:39.885Z,1554487119.885 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-05T17:58:39.888Z,1554487119.888 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-05T17:58:40.031Z,1554487120.031 [CBIT] Loaded
2019-04-05T17:58:40.031Z,1554487120.031 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-05T17:58:40.032Z,1554487120.032 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-05T17:58:40.032Z,1554487120.032 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-05T17:58:40.151Z,1554487120.151 [BuoyancyServo] Loaded
2019-04-05T17:58:40.152Z,1554487120.152 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-05T17:58:40.163Z,1554487120.163 [ElevatorServo] Loaded
2019-04-05T17:58:40.164Z,1554487120.164 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-05T17:58:40.175Z,1554487120.175 [MassServo] Loaded
2019-04-05T17:58:40.175Z,1554487120.175 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-05T17:58:40.187Z,1554487120.187 [RudderServo] Loaded
2019-04-05T17:58:40.187Z,1554487120.187 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-05T17:58:40.198Z,1554487120.198 [ThrusterServo] Loaded
2019-04-05T17:58:40.198Z,1554487120.198 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-05T17:58:40.199Z,1554487120.199 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-05T17:58:40.199Z,1554487120.199 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-05T17:58:40.223Z,1554487120.223 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-05T17:58:40.224Z,1554487120.224 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-05T17:58:40.550Z,1554487120.550 [CTD_NeilBrown] Loaded
2019-04-05T17:58:40.550Z,1554487120.550 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-04-05T17:58:40.552Z,1554487120.552 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0
2019-04-05T17:58:40.552Z,1554487120.552 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 891
2019-04-05T17:58:40.562Z,1554487120.562 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-05T17:58:40.567Z,1554487120.567 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-05T17:58:40.568Z,1554487120.568 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-05T17:58:40.573Z,1554487120.573 [CTD_Seabird](INFO): created writer for : depth
2019-04-05T17:58:40.574Z,1554487120.574 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-05T17:58:40.579Z,1554487120.579 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-05T17:58:40.579Z,1554487120.579 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-05T17:58:40.584Z,1554487120.584 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-05T17:58:40.585Z,1554487120.585 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-05T17:58:40.590Z,1554487120.590 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-05T17:58:40.591Z,1554487120.591 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-05T17:58:40.596Z,1554487120.596 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-05T17:58:40.597Z,1554487120.597 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-05T17:58:40.601Z,1554487120.601 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-05T17:58:40.629Z,1554487120.629 [CTD_Seabird] Loaded
2019-04-05T17:58:40.630Z,1554487120.630 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-05T17:58:40.631Z,1554487120.631 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0
2019-04-05T17:58:40.631Z,1554487120.631 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 892
2019-04-05T17:58:40.681Z,1554487120.681 [WetLabsBB2FL] Loaded
2019-04-05T17:58:40.681Z,1554487120.681 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-05T17:58:40.682Z,1554487120.682 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0
2019-04-05T17:58:40.683Z,1554487120.683 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 893
2019-04-05T17:58:40.683Z,1554487120.683 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-05T17:58:40.687Z,1554487120.687 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-05T17:58:40.688Z,1554487120.688 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-05T17:58:40.695Z,1554487120.695 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-05T17:58:40.696Z,1554487120.696 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-04-05T17:58:40.696Z,1554487120.696 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894
2019-04-05T17:58:40.701Z,1554487120.701 [Supervisor](INFO): Main Thread ID is 803
2019-04-05T17:58:40.701Z,1554487120.701 [Supervisor](DEBUG): Running supervisor.
2019-04-05T17:58:40.702Z,1554487120.702 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895
2019-04-05T17:58:40.704Z,1554487120.704 [controlThread ThreadHandler](INFO): Handler Thread ID is 896
2019-04-05T17:58:40.704Z,1554487120.704 [controlThread](DEBUG): Initializing ControlThread
2019-04-05T17:58:40.705Z,1554487120.705 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-05T17:58:40.707Z,1554487120.707 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-05T17:58:40.708Z,1554487120.708 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-05T17:58:40.708Z,1554487120.708 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-05T17:58:40.708Z,1554487120.708 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-05T17:58:40.709Z,1554487120.709 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-05T17:58:40.709Z,1554487120.709 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-05T17:58:40.709Z,1554487120.709 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-05T17:58:40.710Z,1554487120.710 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-05T17:58:40.710Z,1554487120.710 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-05T17:58:40.715Z,1554487120.715 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T17:58:40.716Z,1554487120.716 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-05T17:58:40.716Z,1554487120.716 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-05T17:58:40.717Z,1554487120.717 [SBIT](INFO): Initialize SBIT Component.
2019-04-05T17:58:40.717Z,1554487120.717 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-05T17:58:40.717Z,1554487120.717 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-05T17:58:40.718Z,1554487120.718 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-05T17:58:40.719Z,1554487120.719 [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-05T17:58:40.720Z,1554487120.720 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-04-05T17:58:40.720Z,1554487120.720 [IBIT](INFO): Initialize IBIT Component.
2019-04-05T17:58:40.721Z,1554487120.721 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-05T17:58:40.722Z,1554487120.722 [logger ThreadHandler](INFO): Handler Thread ID is 897
2019-04-05T17:58:40.733Z,1554487120.733 [CBIT](DEBUG): Initialized mux pins.
2019-04-05T17:58:40.733Z,1554487120.733 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-05T17:58:40.741Z,1554487120.741 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898
2019-04-05T17:58:40.742Z,1554487120.742 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-05T17:58:40.753Z,1554487120.753 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899
2019-04-05T17:58:40.757Z,1554487120.757 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-05T17:58:40.757Z,1554487120.757 [CBIT](DEBUG): Initializing heartbeat.
2019-04-05T17:58:40.765Z,1554487120.765 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 900
2019-04-05T17:58:40.777Z,1554487120.777 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 901
2019-04-05T17:58:40.778Z,1554487120.778 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T17:58:40.781Z,1554487120.781 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T17:58:40.782Z,1554487120.782 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 903
2019-04-05T17:58:40.784Z,1554487120.784 [WetLabsBB2FL](INFO): Powering down
2019-04-05T17:58:40.811Z,1554487120.811 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 904
2019-04-05T17:58:40.820Z,1554487120.820 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-04-05T17:58:40.829Z,1554487120.829 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-05T17:58:40.829Z,1554487120.829 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-05T17:58:40.830Z,1554487120.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T17:58:40.831Z,1554487120.831 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-04-05T17:58:40.832Z,1554487120.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T17:58:40.833Z,1554487120.833 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-04-05T17:58:40.833Z,1554487120.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T17:58:40.835Z,1554487120.835 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-04-05T17:58:40.835Z,1554487120.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T17:58:40.836Z,1554487120.836 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-04-05T17:58:40.837Z,1554487120.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T17:58:40.838Z,1554487120.838 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-04-05T17:58:40.839Z,1554487120.839 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T17:58:40.840Z,1554487120.840 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-04-05T17:58:40.840Z,1554487120.840 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T17:58:40.854Z,1554487120.854 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-04-05T17:58:40.854Z,1554487120.854 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:58:40.856Z,1554487120.856 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-04-05T17:58:40.865Z,1554487120.865 [CBIT](DEBUG): Backplane powered.
2019-04-05T17:58:40.866Z,1554487120.866 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-05T17:58:40.890Z,1554487120.890 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-05T17:58:40.905Z,1554487120.905 [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-05T17:58:40.905Z,1554487120.905 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-05T17:58:40.929Z,1554487120.929 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:58:40.974Z,1554487120.974 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-05T17:58:40.975Z,1554487120.975 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-05T17:58:40.987Z,1554487120.987 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-05T17:58:41.007Z,1554487121.007 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-05T17:58:41.010Z,1554487121.010 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-05T17:58:41.016Z,1554487121.016 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-05T17:58:41.059Z,1554487121.059 [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-05T17:58:41.063Z,1554487121.063 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-05T17:58:41.096Z,1554487121.096 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-04-05T17:58:41.157Z,1554487121.157 [Radio_Surface](INFO): Powering up
2019-04-05T17:58:41.169Z,1554487121.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-05T17:58:41.514Z,1554487121.514 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:58:41.521Z,1554487121.521 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-05T17:58:41.522Z,1554487121.522 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:58:41.529Z,1554487121.529 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-05T17:58:41.530Z,1554487121.530 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:58:41.537Z,1554487121.537 [MassServo](DEBUG): Initializing MassServo.
2019-04-05T17:58:41.538Z,1554487121.538 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:58:41.545Z,1554487121.545 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-05T17:58:41.546Z,1554487121.546 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:58:41.577Z,1554487121.577 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-05T17:58:41.649Z,1554487121.649 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-04-05T17:58:41.649Z,1554487121.649 [DropWeight] Hardware Fault, FailCount= 1
2019-04-05T17:58:41.650Z,1554487121.650 [DropWeight](ERROR): Hardware Fault
2019-04-05T17:58:41.697Z,1554487121.697 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-05T17:58:41.722Z,1554487121.722 [CommandLine](FAULT): Scheduling is paused
2019-04-05T17:58:41.723Z,1554487121.723 [CBIT](INFO): Critical error at 20190405T175841
2019-04-05T17:58:41.723Z,1554487121.723 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-05T17:58:41.738Z,1554487121.738 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-04-05T17:58:41.738Z,1554487121.738 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-04-05T17:58:42.030Z,1554487122.030 [CBIT](INFO): Critical error at 20190405T175841
2019-04-05T17:58:50.989Z,1554487130.989 [CBIT](CRITICAL): Environmental Failure. Press:14.442752 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-05T17:58:51.359Z,1554487131.359 [CBIT](INFO): Critical error at 20190405T175850
2019-04-05T17:58:53.829Z,1554487133.829 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T17:58:53.829Z,1554487133.829 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T17:58:53.829Z,1554487133.829 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T17:58:53.862Z,1554487133.862 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T17:58:54.233Z,1554487134.233 [CTD_Seabird](INFO): Powering down
2019-04-05T17:58:55.410Z,1554487135.410 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T17:58:55.410Z,1554487135.410 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T17:58:55.605Z,1554487135.605 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T17:58:55.607Z,1554487135.607 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T17:58:55.826Z,1554487135.826 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:59:07.866Z,1554487147.866 [NAL9602](INFO): Powering up NAL9602
2019-04-05T17:59:08.657Z,1554487148.657 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T17:59:08.657Z,1554487148.657 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-05T17:59:08.657Z,1554487148.657 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T17:59:08.702Z,1554487148.702 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T17:59:09.061Z,1554487149.061 [CTD_Seabird](INFO): Powering down
2019-04-05T17:59:09.514Z,1554487149.514 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-05T17:59:09.519Z,1554487149.519 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-05T17:59:10.006Z,1554487150.006 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T17:59:10.007Z,1554487150.007 [CTD_Seabird] No Fault, FailCount= 2
2019-04-05T17:59:10.401Z,1554487150.401 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T17:59:10.405Z,1554487150.405 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T17:59:16.000Z,1554487156.000 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T17:59:20.686Z,1554487160.686 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002721
CHAN A1 (24V): 0.002792
CHAN A2 (12V): -0.006733
CHAN A3 (5V): -0.002227
CHAN B0 (3.3V): -0.000162
CHAN B1 (3.15aV): -0.000087
CHAN B2 (3.15bV): 0.000181
CHAN B3 (GND): 0.001615
OPEN: -0.000283
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-05T17:59:23.473Z,1554487163.473 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T17:59:23.473Z,1554487163.473 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-05T17:59:23.473Z,1554487163.473 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T17:59:23.875Z,1554487163.875 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T17:59:23.875Z,1554487163.875 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-05T17:59:23.877Z,1554487163.877 [CTD_Seabird](INFO): Powering down
2019-04-05T17:59:28.743Z,1554487168.743 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T17:59:28.743Z,1554487168.743 [NAL9602] Communications Fault, FailCount= 1
2019-04-05T17:59:28.743Z,1554487168.743 [NAL9602](ERROR): Communications Fault
2019-04-05T17:59:28.890Z,1554487168.890 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T17:59:29.138Z,1554487169.138 [NAL9602](INFO): Powering down
2019-04-05T17:59:30.202Z,1554487170.202 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T17:59:30.202Z,1554487170.202 [NAL9602] No Fault, FailCount= 1
2019-04-05T17:59:47.811Z,1554487187.811 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T17:59:53.844Z,1554487193.844 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:59:54.746Z,1554487194.746 [BPC1](ERROR): BPC1A: No match for serial number 02AF in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-05T17:59:54.748Z,1554487194.748 [BPC1](ERROR): BPC1B: No match for serial number 021A in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-05T17:59:54.748Z,1554487194.748 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-05T17:59:54.748Z,1554487194.748 [BPC1] Data Fault, FailCount= 1
2019-04-05T17:59:54.748Z,1554487194.748 [BPC1](ERROR): Data Fault
2019-04-05T17:59:55.058Z,1554487195.058 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-05T17:59:57.344Z,1554487197.344 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T17:59:59.158Z,1554487199.158 [NAL9602](INFO): Powering up NAL9602
2019-04-05T17:59:59.912Z,1554487199.912 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T18:00:03.232Z,1554487203.232 [SBIT](IMPORTANT): SBIT PASSED
2019-04-05T18:00:03.326Z,1554487203.326 [CommandLine](IMPORTANT): got command configSet list
2019-04-05T18:00:03.326Z,1554487203.326 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-05T18:00:03.327Z,1554487203.327 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-04-05T18:00:03.626Z,1554487203.626 [MissionManager](IMPORTANT): Started mission Startup
2019-04-05T18:00:03.626Z,1554487203.626 [Startup] Running Loop=1
2019-04-05T18:00:03.626Z,1554487203.626 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-05T18:00:03.626Z,1554487203.626 [Startup:A.GoToSurface] Running Loop=1
2019-04-05T18:00:03.626Z,1554487203.626 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-05T18:00:03.627Z,1554487203.627 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-05T18:00:03.627Z,1554487203.627 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-05T18:00:03.628Z,1554487203.628 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-05T18:00:03.628Z,1554487203.628 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-05T18:00:03.628Z,1554487203.628 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-05T18:00:03.642Z,1554487203.642 [Startup:StartupSatComms] Running Loop=1
2019-04-05T18:00:03.642Z,1554487203.642 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-05T18:00:03.642Z,1554487203.642 [Startup:StartupSatComms:A] Running Loop=1
2019-04-05T18:00:04.043Z,1554487204.043 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-05T18:00:19.762Z,1554487219.762 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:00:19.762Z,1554487219.762 [NAL9602] Communications Fault, FailCount= 2
2019-04-05T18:00:19.762Z,1554487219.762 [NAL9602](ERROR): Communications Fault
2019-04-05T18:00:19.796Z,1554487219.796 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:00:20.166Z,1554487220.166 [NAL9602](INFO): Powering down
2019-04-05T18:00:21.018Z,1554487221.018 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:00:21.018Z,1554487221.018 [NAL9602] No Fault, FailCount= 2
2019-04-05T18:00:31.127Z,1554487231.127 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T18:00:31.172Z,1554487231.172 [NavChartDb](INFO): # of records loaded: 19732
2019-04-05T18:00:31.178Z,1554487231.178 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-05T18:00:31.213Z,1554487231.213 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T18:00:38.438Z,1554487238.438 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T18:00:40.824Z,1554487240.824 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:00:40.824Z,1554487240.824 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-05T18:00:40.824Z,1554487240.824 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:00:40.830Z,1554487240.830 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:00:40.830Z,1554487240.830 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-05T18:00:40.830Z,1554487240.830 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:00:40.853Z,1554487240.853 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:00:40.853Z,1554487240.853 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:00:41.217Z,1554487241.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:00:41.217Z,1554487241.217 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-05T18:00:41.217Z,1554487241.217 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:00:41.218Z,1554487241.218 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-05T18:00:41.612Z,1554487241.612 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:00:50.482Z,1554487250.482 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:00:51.812Z,1554487251.812 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:01:03.306Z,1554487263.306 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:01:03.838Z,1554487263.838 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-05T18:00:03.6Z
2019-04-05T18:01:03.838Z,1554487263.838 [Startup:StartupSatComms:A] Stopped
2019-04-05T18:01:03.838Z,1554487263.838 [Startup:StartupSatComms:B] Running Loop=1
2019-04-05T18:01:04.270Z,1554487264.270 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-05T18:01:11.106Z,1554487271.106 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:01:11.106Z,1554487271.106 [NAL9602] Communications Fault, FailCount= 3
2019-04-05T18:01:11.106Z,1554487271.106 [NAL9602](ERROR): Communications Fault
2019-04-05T18:01:11.179Z,1554487271.179 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:01:11.510Z,1554487271.510 [NAL9602](INFO): Powering down
2019-04-05T18:01:12.359Z,1554487272.359 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:01:12.359Z,1554487272.359 [NAL9602] No Fault, FailCount= 3
2019-04-05T18:01:16.071Z,1554487276.071 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005329
2019-04-05T18:01:16.923Z,1554487276.923 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:01:23.944Z,1554487283.944 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190405T175013/Courier0004.lzma
2019-04-05T18:01:24.751Z,1554487284.751 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175013/Courier0004.lzma.bak
2019-04-05T18:01:24.751Z,1554487284.751 [DataOverHttps](INFO): SBD MOMSN=10477967
2019-04-05T18:01:32.400Z,1554487292.400 [NavChartDb](INFO): # of records loaded: 20000
2019-04-05T18:01:40.958Z,1554487300.958 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20190405T175633/Courier0000.lzma
2019-04-05T18:01:41.762Z,1554487301.762 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175633/Courier0000.lzma.bak
2019-04-05T18:01:41.763Z,1554487301.763 [DataOverHttps](INFO): SBD MOMSN=10477969
2019-04-05T18:01:41.810Z,1554487301.810 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:01:49.604Z,1554487309.604 [NavChartDb](INFO): # of records loaded: 25000
2019-04-05T18:01:53.570Z,1554487313.570 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:01:53.570Z,1554487313.570 [CTD_Seabird] No Fault, FailCount= 3
2019-04-05T18:01:53.909Z,1554487313.909 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:01:53.911Z,1554487313.911 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:01:59.092Z,1554487319.092 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T18:02:02.414Z,1554487322.414 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:02:02.414Z,1554487322.414 [NAL9602] Communications Fault, FailCount= 4
2019-04-05T18:02:02.414Z,1554487322.414 [NAL9602](ERROR): Communications Fault
2019-04-05T18:02:02.446Z,1554487322.446 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:02:02.822Z,1554487322.822 [NAL9602](INFO): Powering down
2019-04-05T18:02:03.661Z,1554487323.661 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:02:03.661Z,1554487323.661 [NAL9602] No Fault, FailCount= 4
2019-04-05T18:02:04.058Z,1554487324.058 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-05T18:01:03.8Z
2019-04-05T18:02:04.058Z,1554487324.058 [Startup:StartupSatComms:B] Stopped
2019-04-05T18:02:04.058Z,1554487324.058 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-05T18:02:04.058Z,1554487324.058 [Startup:StartupSatComms] Stopped
2019-04-05T18:02:04.058Z,1554487324.058 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-05T18:02:04.059Z,1554487324.059 [Startup](INFO): Completed Startup
2019-04-05T18:02:04.059Z,1554487324.059 [MissionManager](INFO): Startup is completed.
2019-04-05T18:02:04.059Z,1554487324.059 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-05T18:02:04.059Z,1554487324.059 [Startup] Stopped
2019-04-05T18:02:04.059Z,1554487324.059 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-05T18:02:04.059Z,1554487324.059 [Startup:A.GoToSurface] Stopped
2019-04-05T18:02:04.059Z,1554487324.059 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-05T18:02:04.486Z,1554487324.486 [MissionManager](IMPORTANT): Started mission Default
2019-04-05T18:02:04.486Z,1554487324.486 [Default] Running Loop=1
2019-04-05T18:02:04.486Z,1554487324.486 [Default](DEBUG): Aggregate::initialize Default
2019-04-05T18:02:04.486Z,1554487324.486 [Default:B.GoToSurface] Running Loop=1
2019-04-05T18:02:04.487Z,1554487324.487 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-05T18:02:04.487Z,1554487324.487 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-05T18:02:04.487Z,1554487324.487 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-05T18:02:04.487Z,1554487324.487 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-05T18:02:04.488Z,1554487324.488 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-05T18:02:04.488Z,1554487324.488 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-05T18:02:04.488Z,1554487324.488 [Default:A.Wait] Running Loop=1
2019-04-05T18:02:04.488Z,1554487324.488 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-05T18:02:05.605Z,1554487325.605 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T18:02:06.081Z,1554487326.081 [NavChartDb](INFO): # of records loaded: 30000
2019-04-05T18:02:06.969Z,1554487326.969 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:02:06.969Z,1554487326.969 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T18:02:06.969Z,1554487326.969 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:02:07.057Z,1554487327.057 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20190405T175830/Courier0000.lzma
2019-04-05T18:02:07.302Z,1554487327.302 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:02:07.373Z,1554487327.373 [CTD_Seabird](INFO): Powering down
2019-04-05T18:02:07.850Z,1554487327.850 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175830/Courier0000.lzma.bak
2019-04-05T18:02:07.850Z,1554487327.850 [DataOverHttps](INFO): SBD MOMSN=10477980
2019-04-05T18:02:08.522Z,1554487328.522 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:02:08.522Z,1554487328.522 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T18:02:08.713Z,1554487328.713 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:02:08.721Z,1554487328.721 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:02:17.806Z,1554487337.806 [Default:A.Wait](INFO): Done Waiting.
2019-04-05T18:02:17.806Z,1554487337.806 [Default:A.Wait] Stopped
2019-04-05T18:02:17.806Z,1554487337.806 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-05T18:02:18.213Z,1554487338.213 [Default:CheckIn] Running Loop=1
2019-04-05T18:02:18.213Z,1554487338.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-05T18:02:18.213Z,1554487338.213 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-05T18:02:18.597Z,1554487338.597 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-05T18:02:21.773Z,1554487341.773 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:02:21.773Z,1554487341.773 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-05T18:02:21.773Z,1554487341.773 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:02:21.885Z,1554487341.885 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:02:22.177Z,1554487342.177 [CTD_Seabird](INFO): Powering down
2019-04-05T18:02:23.454Z,1554487343.454 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:02:23.454Z,1554487343.454 [CTD_Seabird] No Fault, FailCount= 2
2019-04-05T18:02:23.541Z,1554487343.541 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:02:23.549Z,1554487343.549 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:02:26.456Z,1554487346.456 [NavChartDb](INFO): # of records loaded: 35000
2019-04-05T18:02:33.122Z,1554487353.122 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:02:36.593Z,1554487356.593 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:02:36.593Z,1554487356.593 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-05T18:02:36.593Z,1554487356.593 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:02:36.798Z,1554487356.798 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:02:36.799Z,1554487356.799 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-05T18:02:36.997Z,1554487356.997 [CTD_Seabird](INFO): Powering down
2019-04-05T18:02:38.423Z,1554487358.423 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T18:02:38.451Z,1554487358.451 [NavChartDb](INFO): # of records loaded: 37376
2019-04-05T18:02:38.459Z,1554487358.459 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-05T18:02:38.525Z,1554487358.525 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T18:02:41.623Z,1554487361.623 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:02:41.623Z,1554487361.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-05T18:02:41.623Z,1554487361.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:02:41.625Z,1554487361.625 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:02:41.625Z,1554487361.625 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-05T18:02:41.625Z,1554487361.625 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:02:41.635Z,1554487361.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:02:41.635Z,1554487361.635 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:02:42.046Z,1554487362.046 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:02:42.046Z,1554487362.046 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-05T18:02:42.046Z,1554487362.046 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:02:42.046Z,1554487362.046 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-05T18:02:42.432Z,1554487362.432 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:02:44.098Z,1554487364.098 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T18:02:53.728Z,1554487373.728 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:02:53.729Z,1554487373.729 [NAL9602] Communications Fault, FailCount= 5
2019-04-05T18:02:53.729Z,1554487373.729 [NAL9602](ERROR): Communications Fault
2019-04-05T18:02:53.758Z,1554487373.758 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:02:53.758Z,1554487373.758 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-04-05T18:02:54.130Z,1554487374.130 [NAL9602](INFO): Powering down
2019-04-05T18:02:54.168Z,1554487374.168 [CBIT](INFO): Critical error at 20190405T180253
2019-04-05T18:03:00.733Z,1554487380.733 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:03:09.779Z,1554487389.779 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:03:18.201Z,1554487398.201 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T18:03:19.699Z,1554487399.699 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T18:03:22.080Z,1554487402.080 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:03:33.360Z,1554487413.360 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T18:03:33.519Z,1554487413.519 [NavChartDb](INFO): # of records loaded: 16998
2019-04-05T18:03:33.523Z,1554487413.523 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-05T18:03:33.523Z,1554487413.523 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T18:03:37.689Z,1554487417.689 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T18:03:49.489Z,1554487429.489 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:03:58.165Z,1554487438.165 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:04:06.599Z,1554487446.599 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:04:13.534Z,1554487453.534 [NavChartDb](INFO): # of records loaded: 20000
2019-04-05T18:04:20.222Z,1554487460.222 [NavChartDb](INFO): # of records loaded: 25000
2019-04-05T18:04:26.902Z,1554487466.902 [NavChartDb](INFO): # of records loaded: 30000
2019-04-05T18:04:38.033Z,1554487478.033 [NavChartDb](INFO): # of records loaded: 35000
2019-04-05T18:04:42.828Z,1554487482.828 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:04:42.828Z,1554487482.828 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-05T18:04:42.828Z,1554487482.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:04:42.829Z,1554487482.829 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:04:42.830Z,1554487482.830 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-05T18:04:42.830Z,1554487482.830 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:04:42.851Z,1554487482.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:04:42.851Z,1554487482.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:04:43.248Z,1554487483.248 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:04:43.248Z,1554487483.248 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-05T18:04:43.249Z,1554487483.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:04:43.249Z,1554487483.249 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-05T18:04:43.636Z,1554487483.636 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:04:47.054Z,1554487487.054 [NavChartDb](INFO): # of records loaded: 40000
2019-04-05T18:04:53.758Z,1554487493.758 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:04:53.758Z,1554487493.758 [NAL9602] No Fault, FailCount= 5
2019-04-05T18:04:54.118Z,1554487494.118 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:04:54.493Z,1554487494.493 [NavChartDb](INFO): # of records loaded: 45000
2019-04-05T18:05:01.380Z,1554487501.380 [NavChartDb](INFO): # of records loaded: 50000
2019-04-05T18:05:06.674Z,1554487506.674 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:05:06.674Z,1554487506.674 [CTD_Seabird] No Fault, FailCount= 3
2019-04-05T18:05:07.025Z,1554487507.025 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:05:07.027Z,1554487507.027 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:05:13.664Z,1554487513.664 [NavChartDb](INFO): # of records loaded: 55000
2019-04-05T18:05:14.730Z,1554487514.730 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:05:14.730Z,1554487514.730 [NAL9602] Communications Fault, FailCount= 1
2019-04-05T18:05:14.730Z,1554487514.730 [NAL9602](ERROR): Communications Fault
2019-04-05T18:05:14.785Z,1554487514.785 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:05:15.060Z,1554487515.060 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T18:05:15.134Z,1554487515.134 [NAL9602](INFO): Powering down
2019-04-05T18:05:15.979Z,1554487515.979 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:05:15.979Z,1554487515.979 [NAL9602] No Fault, FailCount= 1
2019-04-05T18:05:19.509Z,1554487519.509 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T18:05:20.077Z,1554487520.077 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:05:20.077Z,1554487520.077 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T18:05:20.077Z,1554487520.077 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:05:20.418Z,1554487520.418 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:05:20.481Z,1554487520.481 [CTD_Seabird](INFO): Powering down
2019-04-05T18:05:21.626Z,1554487521.626 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:05:21.626Z,1554487521.626 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T18:05:21.825Z,1554487521.825 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:05:21.834Z,1554487521.834 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:05:23.645Z,1554487523.645 [NavChartDb](INFO): # of records loaded: 60000
2019-04-05T18:05:34.084Z,1554487534.084 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T18:05:34.283Z,1554487534.283 [NavChartDb](INFO): # of records loaded: 62026
2019-04-05T18:05:34.288Z,1554487534.288 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-05T18:05:34.289Z,1554487534.289 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T18:05:34.877Z,1554487534.877 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:05:34.877Z,1554487534.877 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-05T18:05:34.877Z,1554487534.877 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:05:34.988Z,1554487534.988 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:05:35.281Z,1554487535.281 [CTD_Seabird](INFO): Powering down
2019-04-05T18:05:36.575Z,1554487536.575 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:05:36.575Z,1554487536.575 [CTD_Seabird] No Fault, FailCount= 2
2019-04-05T18:05:36.641Z,1554487536.641 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:05:36.645Z,1554487536.645 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:05:40.979Z,1554487540.979 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T18:05:45.434Z,1554487545.434 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:05:49.693Z,1554487549.693 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:05:49.693Z,1554487549.693 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-05T18:05:49.693Z,1554487549.693 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:05:49.914Z,1554487549.914 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:05:49.915Z,1554487549.915 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-05T18:05:50.097Z,1554487550.097 [CTD_Seabird](INFO): Powering down
2019-04-05T18:05:53.427Z,1554487553.427 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:06:01.912Z,1554487561.912 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:06:06.038Z,1554487566.038 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:06:06.038Z,1554487566.038 [NAL9602] Communications Fault, FailCount= 2
2019-04-05T18:06:06.038Z,1554487566.038 [NAL9602](ERROR): Communications Fault
2019-04-05T18:06:06.067Z,1554487566.067 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:06:06.442Z,1554487566.442 [NAL9602](INFO): Powering down
2019-04-05T18:06:07.282Z,1554487567.282 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:06:07.282Z,1554487567.282 [NAL9602] No Fault, FailCount= 2
2019-04-05T18:06:09.819Z,1554487569.819 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:06:20.109Z,1554487580.109 [NavChartDb](INFO): # of records loaded: 20000
2019-04-05T18:06:29.941Z,1554487589.941 [NavChartDb](INFO): # of records loaded: 25000
2019-04-05T18:06:36.747Z,1554487596.747 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:06:39.158Z,1554487599.158 [NavChartDb](INFO): # of records loaded: 30000
2019-04-05T18:06:43.640Z,1554487603.640 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:06:43.640Z,1554487603.640 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-04-05T18:06:43.640Z,1554487603.640 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:06:43.641Z,1554487603.641 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:06:43.642Z,1554487603.642 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-04-05T18:06:43.642Z,1554487603.642 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:06:43.664Z,1554487603.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:06:43.664Z,1554487603.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:06:44.057Z,1554487604.057 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:06:44.057Z,1554487604.057 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-04-05T18:06:44.058Z,1554487604.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:06:44.058Z,1554487604.058 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-04-05T18:06:44.448Z,1554487604.448 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:06:46.004Z,1554487606.004 [NavChartDb](INFO): # of records loaded: 35000
2019-04-05T18:06:57.350Z,1554487617.350 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:06:57.350Z,1554487617.350 [NAL9602] Communications Fault, FailCount= 3
2019-04-05T18:06:57.350Z,1554487617.350 [NAL9602](ERROR): Communications Fault
2019-04-05T18:06:57.383Z,1554487617.383 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:06:57.762Z,1554487617.762 [NAL9602](INFO): Powering down
2019-04-05T18:06:58.602Z,1554487618.602 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:06:58.603Z,1554487618.603 [NAL9602] No Fault, FailCount= 3
2019-04-05T18:07:00.049Z,1554487620.049 [NavChartDb](INFO): # of records loaded: 40000
2019-04-05T18:07:14.248Z,1554487634.248 [NavChartDb](INFO): # of records loaded: 45000
2019-04-05T18:07:16.661Z,1554487636.661 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T18:07:18.208Z,1554487638.208 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T18:07:18.386Z,1554487638.386 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-04-05T18:02:18.2Z
2019-04-05T18:07:18.386Z,1554487638.386 [Default:CheckIn:Read_GPS] Stopped
2019-04-05T18:07:18.386Z,1554487638.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-05T18:07:18.786Z,1554487638.786 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-05T18:07:26.728Z,1554487646.728 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20190405T175830/Courier0004.lzma
2019-04-05T18:07:27.534Z,1554487647.534 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175830/Courier0004.lzma.bak
2019-04-05T18:07:27.534Z,1554487647.534 [DataOverHttps](INFO): SBD MOMSN=10478283
2019-04-05T18:07:28.055Z,1554487648.055 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:07:31.180Z,1554487651.180 [NavChartDb](INFO): # of records loaded: 50000
2019-04-05T18:07:44.191Z,1554487664.191 [DataOverHttps](INFO): Sending 719 bytes from file Logs/20190402T231601/Express0001.lzma
2019-04-05T18:07:44.994Z,1554487664.994 [DataOverHttps](INFO): Moved sent file to Logs/20190402T231601/Express0001.lzma.bak
2019-04-05T18:07:44.994Z,1554487664.994 [DataOverHttps](INFO): SBD MOMSN=10477671
2019-04-05T18:07:48.666Z,1554487668.666 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:07:48.666Z,1554487668.666 [NAL9602] Communications Fault, FailCount= 4
2019-04-05T18:07:48.666Z,1554487668.666 [NAL9602](ERROR): Communications Fault
2019-04-05T18:07:48.719Z,1554487668.719 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:07:49.070Z,1554487669.070 [NAL9602](INFO): Powering down
2019-04-05T18:07:49.950Z,1554487669.950 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:07:49.950Z,1554487669.950 [NAL9602] No Fault, FailCount= 4
2019-04-05T18:07:50.631Z,1554487670.631 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T18:07:50.852Z,1554487670.852 [NavChartDb](INFO): # of records loaded: 53494
2019-04-05T18:07:50.868Z,1554487670.868 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-05T18:07:50.881Z,1554487670.881 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T18:07:54.018Z,1554487674.018 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T18:08:02.331Z,1554487682.331 [DataOverHttps](INFO): Sending 1153 bytes from file Logs/20190405T174808/Express0001.lzma
2019-04-05T18:08:03.135Z,1554487683.135 [DataOverHttps](INFO): Moved sent file to Logs/20190405T174808/Express0001.lzma.bak
2019-04-05T18:08:03.135Z,1554487683.135 [DataOverHttps](INFO): SBD MOMSN=10478287
2019-04-05T18:08:07.895Z,1554487687.895 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:08:19.370Z,1554487699.370 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:08:19.802Z,1554487699.802 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:08:19.802Z,1554487699.802 [CTD_Seabird] No Fault, FailCount= 3
2019-04-05T18:08:20.087Z,1554487700.087 [DataOverHttps](INFO): Sending 1030 bytes from file Logs/20190405T175013/Express0001.lzma
2019-04-05T18:08:20.157Z,1554487700.157 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:08:20.165Z,1554487700.165 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:08:20.448Z,1554487700.448 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:08:20.890Z,1554487700.890 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175013/Express0001.lzma.bak
2019-04-05T18:08:20.890Z,1554487700.890 [DataOverHttps](INFO): SBD MOMSN=10478330
2019-04-05T18:08:30.401Z,1554487710.401 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:08:33.209Z,1554487713.209 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:08:33.209Z,1554487713.209 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T18:08:33.209Z,1554487713.209 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:08:33.543Z,1554487713.543 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:08:33.613Z,1554487713.613 [CTD_Seabird](INFO): Powering down
2019-04-05T18:08:34.767Z,1554487714.767 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:08:34.767Z,1554487714.767 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T18:08:34.957Z,1554487714.957 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:08:34.959Z,1554487714.959 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:08:38.172Z,1554487718.172 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190405T175013/Express0005.lzma
2019-04-05T18:08:38.978Z,1554487718.978 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175013/Express0005.lzma.bak
2019-04-05T18:08:38.978Z,1554487718.978 [DataOverHttps](INFO): SBD MOMSN=10478361
2019-04-05T18:08:39.974Z,1554487719.974 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:08:39.974Z,1554487719.974 [NAL9602] Communications Fault, FailCount= 5
2019-04-05T18:08:39.974Z,1554487719.974 [NAL9602](ERROR): Communications Fault
2019-04-05T18:08:40.031Z,1554487720.031 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:08:40.031Z,1554487720.031 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-04-05T18:08:40.378Z,1554487720.378 [NAL9602](INFO): Powering down
2019-04-05T18:08:40.408Z,1554487720.408 [CBIT](INFO): Critical error at 20190405T180840
2019-04-05T18:08:40.486Z,1554487720.486 [NavChartDb](INFO): # of records loaded: 20000
2019-04-05T18:08:44.844Z,1554487724.844 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:08:44.844Z,1554487724.844 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-04-05T18:08:44.844Z,1554487724.844 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:08:44.845Z,1554487724.845 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:08:44.846Z,1554487724.846 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-04-05T18:08:44.846Z,1554487724.846 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:08:44.877Z,1554487724.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:08:44.877Z,1554487724.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:08:45.269Z,1554487725.269 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:08:45.269Z,1554487725.269 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-04-05T18:08:45.270Z,1554487725.270 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:08:45.270Z,1554487725.270 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-04-05T18:08:45.652Z,1554487725.652 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:08:48.017Z,1554487728.017 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:08:48.017Z,1554487728.017 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-05T18:08:48.017Z,1554487728.017 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:08:48.083Z,1554487728.083 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:08:48.421Z,1554487728.421 [CTD_Seabird](INFO): Powering down
2019-04-05T18:08:49.718Z,1554487729.718 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:08:49.718Z,1554487729.718 [CTD_Seabird] No Fault, FailCount= 2
2019-04-05T18:08:49.765Z,1554487729.765 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:08:49.773Z,1554487729.773 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:08:50.698Z,1554487730.698 [NavChartDb](INFO): # of records loaded: 25000
2019-04-05T18:08:57.111Z,1554487737.111 [DataOverHttps](INFO): Sending 903 bytes from file Logs/20190405T175633/Express0001.lzma
2019-04-05T18:08:57.914Z,1554487737.914 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175633/Express0001.lzma.bak
2019-04-05T18:08:57.915Z,1554487737.915 [DataOverHttps](INFO): SBD MOMSN=10478363
2019-04-05T18:09:01.351Z,1554487741.351 [NavChartDb](INFO): # of records loaded: 30000
2019-04-05T18:09:02.817Z,1554487742.817 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:09:02.817Z,1554487742.817 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-05T18:09:02.817Z,1554487742.817 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:09:03.034Z,1554487743.034 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:09:03.035Z,1554487743.035 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-05T18:09:03.221Z,1554487743.221 [CTD_Seabird](INFO): Powering down
2019-04-05T18:09:12.063Z,1554487752.063 [NavChartDb](INFO): # of records loaded: 35000
2019-04-05T18:09:16.146Z,1554487756.146 [DataOverHttps](INFO): Sending 1030 bytes from file Logs/20190405T175830/Express0001.lzma
2019-04-05T18:09:16.950Z,1554487756.950 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175830/Express0001.lzma.bak
2019-04-05T18:09:16.950Z,1554487756.950 [DataOverHttps](INFO): SBD MOMSN=10478395
2019-04-05T18:09:22.901Z,1554487762.901 [NavChartDb](INFO): # of records loaded: 40000
2019-04-05T18:09:33.741Z,1554487773.741 [NavChartDb](INFO): # of records loaded: 45000
2019-04-05T18:09:34.609Z,1554487774.609 [DataOverHttps](INFO): Sending 357 bytes from file Logs/20190405T175830/Express0005.lzma
2019-04-05T18:09:35.414Z,1554487775.414 [DataOverHttps](INFO): Moved sent file to Logs/20190405T175830/Express0005.lzma.bak
2019-04-05T18:09:35.415Z,1554487775.415 [DataOverHttps](INFO): SBD MOMSN=10478429
2019-04-05T18:09:36.153Z,1554487776.153 [Default:CheckIn:Read_Iridium] Stopped
2019-04-05T18:09:36.153Z,1554487776.153 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-05T18:09:36.153Z,1554487776.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-05T18:09:43.964Z,1554487783.964 [NavChartDb](INFO): # of records loaded: 50000
2019-04-05T18:09:50.819Z,1554487790.819 [NavChartDb](INFO): # of records loaded: 55000
2019-04-05T18:09:55.954Z,1554487795.954 [CBIT](INFO): Clearing failed state for component BPC1
2019-04-05T18:09:55.955Z,1554487795.955 [BPC1] No Fault, FailCount= 1
2019-04-05T18:09:57.699Z,1554487797.699 [NavChartDb](INFO): # of records loaded: 60000
2019-04-05T18:10:04.510Z,1554487804.510 [NavChartDb](INFO): # of records loaded: 65000
2019-04-05T18:10:11.247Z,1554487811.247 [NavChartDb](INFO): # of records loaded: 70000
2019-04-05T18:10:21.238Z,1554487821.238 [NavChartDb](INFO): # of records loaded: 75000
2019-04-05T18:10:28.197Z,1554487828.197 [NavChartDb](INFO): # of records loaded: 80000
2019-04-05T18:10:35.171Z,1554487835.171 [NavChartDb](INFO): # of records loaded: 85000
2019-04-05T18:10:39.995Z,1554487839.995 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:10:39.995Z,1554487839.995 [NAL9602] No Fault, FailCount= 5
2019-04-05T18:10:40.366Z,1554487840.366 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:10:42.041Z,1554487842.041 [NavChartDb](INFO): # of records loaded: 90000
2019-04-05T18:10:46.044Z,1554487846.044 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:10:46.044Z,1554487846.044 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-04-05T18:10:46.044Z,1554487846.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:10:46.068Z,1554487846.068 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:10:46.068Z,1554487846.068 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-04-05T18:10:46.068Z,1554487846.068 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:10:46.090Z,1554487846.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:10:46.090Z,1554487846.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:10:46.482Z,1554487846.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:10:46.482Z,1554487846.482 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-04-05T18:10:46.482Z,1554487846.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:10:46.482Z,1554487846.482 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-04-05T18:10:46.852Z,1554487846.852 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:10:50.303Z,1554487850.303 [NavChartDb](INFO): # of records loaded: 95000
2019-04-05T18:10:59.508Z,1554487859.508 [NavChartDb](INFO): # of records loaded: 100000
2019-04-05T18:11:00.971Z,1554487860.971 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:11:00.971Z,1554487860.971 [NAL9602] Communications Fault, FailCount= 1
2019-04-05T18:11:00.971Z,1554487860.971 [NAL9602](ERROR): Communications Fault
2019-04-05T18:11:01.003Z,1554487861.003 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:11:01.374Z,1554487861.374 [NAL9602](INFO): Powering down
2019-04-05T18:11:02.226Z,1554487862.226 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:11:02.226Z,1554487862.226 [NAL9602] No Fault, FailCount= 1
2019-04-05T18:11:03.410Z,1554487863.410 [BPC1](ERROR): BPC1A: No match for serial number 02AF in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-05T18:11:03.411Z,1554487863.411 [BPC1](ERROR): BPC1B: No match for serial number 021A in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-05T18:11:03.412Z,1554487863.412 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-05T18:11:03.412Z,1554487863.412 [BPC1] Data Fault, FailCount= 2
2019-04-05T18:11:03.412Z,1554487863.412 [BPC1](ERROR): Data Fault
2019-04-05T18:11:03.475Z,1554487863.475 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-05T18:11:06.739Z,1554487866.739 [NavChartDb](INFO): # of records loaded: 105000
2019-04-05T18:11:14.816Z,1554487874.816 [NavChartDb](INFO): # of records loaded: 110000
2019-04-05T18:11:21.891Z,1554487881.891 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T18:11:22.724Z,1554487882.724 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T18:11:27.362Z,1554487887.362 [NavChartDb](INFO): # of records loaded: 115000
2019-04-05T18:11:31.674Z,1554487891.674 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:11:32.939Z,1554487892.939 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:11:32.939Z,1554487892.939 [CTD_Seabird] No Fault, FailCount= 3
2019-04-05T18:11:33.249Z,1554487893.249 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:11:33.257Z,1554487893.257 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:11:35.126Z,1554487895.126 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T18:11:35.410Z,1554487895.410 [NavChartDb](INFO): # of records loaded: 117290
2019-04-05T18:11:35.415Z,1554487895.415 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-05T18:11:35.416Z,1554487895.416 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T18:11:37.118Z,1554487897.118 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T18:11:44.487Z,1554487904.487 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:11:46.301Z,1554487906.301 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:11:46.301Z,1554487906.301 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T18:11:46.301Z,1554487906.301 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:11:46.666Z,1554487906.666 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:11:46.705Z,1554487906.705 [CTD_Seabird](INFO): Powering down
2019-04-05T18:11:47.898Z,1554487907.898 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:11:47.898Z,1554487907.898 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T18:11:48.049Z,1554487908.049 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:11:48.057Z,1554487908.057 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:11:51.452Z,1554487911.452 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:11:52.290Z,1554487912.290 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:11:52.290Z,1554487912.290 [NAL9602] Communications Fault, FailCount= 2
2019-04-05T18:11:52.290Z,1554487912.290 [NAL9602](ERROR): Communications Fault
2019-04-05T18:11:52.351Z,1554487912.351 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:11:52.694Z,1554487912.694 [NAL9602](INFO): Powering down
2019-04-05T18:11:53.539Z,1554487913.539 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:11:53.539Z,1554487913.539 [NAL9602] No Fault, FailCount= 2
2019-04-05T18:11:58.415Z,1554487918.415 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:12:01.101Z,1554487921.101 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:12:01.101Z,1554487921.101 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-05T18:12:01.101Z,1554487921.101 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:12:01.222Z,1554487921.222 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:12:01.505Z,1554487921.505 [CTD_Seabird](INFO): Powering down
2019-04-05T18:12:02.450Z,1554487922.450 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:12:02.450Z,1554487922.450 [CTD_Seabird] No Fault, FailCount= 2
2019-04-05T18:12:02.849Z,1554487922.849 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:12:02.853Z,1554487922.853 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:12:08.489Z,1554487928.489 [NavChartDb](INFO): # of records loaded: 20000
2019-04-05T18:12:15.901Z,1554487935.901 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:12:15.901Z,1554487935.901 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-05T18:12:15.901Z,1554487935.901 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:12:16.115Z,1554487936.115 [NavChartDb](INFO): # of records loaded: 25000
2019-04-05T18:12:16.176Z,1554487936.176 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:12:16.177Z,1554487936.177 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-05T18:12:16.305Z,1554487936.305 [CTD_Seabird](INFO): Powering down
2019-04-05T18:12:22.999Z,1554487942.999 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:12:24.539Z,1554487944.539 [NavChartDb](INFO): # of records loaded: 30000
2019-04-05T18:12:33.759Z,1554487953.759 [NavChartDb](INFO): # of records loaded: 35000
2019-04-05T18:12:43.614Z,1554487963.614 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:12:43.614Z,1554487963.614 [NAL9602] Communications Fault, FailCount= 3
2019-04-05T18:12:43.614Z,1554487963.614 [NAL9602](ERROR): Communications Fault
2019-04-05T18:12:43.667Z,1554487963.667 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:12:44.018Z,1554487964.018 [NAL9602](INFO): Powering down
2019-04-05T18:12:44.108Z,1554487964.108 [NavChartDb](INFO): # of records loaded: 40000
2019-04-05T18:12:44.878Z,1554487964.878 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:12:44.878Z,1554487964.878 [NAL9602] No Fault, FailCount= 3
2019-04-05T18:12:45.842Z,1554487965.842 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T18:12:46.481Z,1554487966.481 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T18:12:46.876Z,1554487966.876 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:12:46.876Z,1554487966.876 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-04-05T18:12:46.876Z,1554487966.876 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:12:46.878Z,1554487966.878 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:12:46.878Z,1554487966.878 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-04-05T18:12:46.878Z,1554487966.878 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:12:46.892Z,1554487966.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:12:46.897Z,1554487966.897 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:12:47.305Z,1554487967.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:12:47.305Z,1554487967.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-04-05T18:12:47.305Z,1554487967.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:12:47.305Z,1554487967.305 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-04-05T18:12:47.680Z,1554487967.680 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:12:54.620Z,1554487974.620 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T18:12:54.755Z,1554487974.755 [NavChartDb](INFO): # of records loaded: 44880
2019-04-05T18:12:54.759Z,1554487974.759 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-05T18:12:54.760Z,1554487974.760 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T18:12:58.529Z,1554487978.529 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T18:13:08.429Z,1554487988.429 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T18:13:14.327Z,1554487994.327 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:13:19.665Z,1554487999.665 [NavChartDb](INFO): # of records loaded: 10000
2019-04-05T18:13:28.653Z,1554488008.653 [NavChartDb](INFO): # of records loaded: 15000
2019-04-05T18:13:34.931Z,1554488014.931 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:13:34.931Z,1554488014.931 [NAL9602] Communications Fault, FailCount= 4
2019-04-05T18:13:34.931Z,1554488014.931 [NAL9602](ERROR): Communications Fault
2019-04-05T18:13:34.963Z,1554488014.963 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:13:35.334Z,1554488015.334 [NAL9602](INFO): Powering down
2019-04-05T18:13:36.174Z,1554488016.174 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-05T18:13:36.174Z,1554488016.174 [NAL9602] No Fault, FailCount= 4
2019-04-05T18:13:38.465Z,1554488018.465 [NavChartDb](INFO): # of records loaded: 20000
2019-04-05T18:13:40.467Z,1554488020.467 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T18:13:41.833Z,1554488021.833 [CBIT](INFO): Clearing failed state for component DropWeight
2019-04-05T18:13:41.834Z,1554488021.834 [DropWeight] No Fault, FailCount= 1
2019-04-05T18:13:41.975Z,1554488021.975 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T18:14:01.914Z,1554488041.914 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T18:14:02.582Z,1554488042.582 [NavChartDb](INFO): # of records loaded: 24424
2019-04-05T18:14:02.590Z,1554488042.590 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-05T18:14:05.635Z,1554488045.635 [NAL9602](INFO): Powering up NAL9602
2019-04-05T18:14:06.114Z,1554488046.114 [NavChartDb](INFO): Creating index for soundings
2019-04-05T18:14:15.555Z,1554488055.555 [NavChartDb](INFO): Creating index for 0p0
2019-04-05T18:14:20.725Z,1554488060.725 [NavChartDb](INFO): Creating index for 1p8
2019-04-05T18:14:24.405Z,1554488064.405 [NavChartDb](INFO): Creating index for 3p6
2019-04-05T18:14:26.239Z,1554488066.239 [NAL9602](ERROR): NAL9602 initialization error.
2019-04-05T18:14:26.239Z,1554488066.239 [NAL9602] Communications Fault, FailCount= 5
2019-04-05T18:14:26.239Z,1554488066.239 [NAL9602](ERROR): Communications Fault
2019-04-05T18:14:26.274Z,1554488066.274 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-04-05T18:14:26.275Z,1554488066.275 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2019-04-05T18:14:26.646Z,1554488066.646 [NAL9602](INFO): Powering down
2019-04-05T18:14:26.680Z,1554488066.680 [CBIT](INFO): Critical error at 20190405T181426
2019-04-05T18:14:28.076Z,1554488068.076 [NavChartDb](INFO): Creating index for 5p4
2019-04-05T18:14:30.935Z,1554488070.935 [NavChartDb](INFO): Creating index for 9p1
2019-04-05T18:14:32.099Z,1554488072.099 [NavChartDb](INFO): Creating index for 10p9
2019-04-05T18:14:34.506Z,1554488074.506 [NavChartDb](INFO): Creating index for 18p2
2019-04-05T18:14:36.765Z,1554488076.765 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-04-05T18:14:36.765Z,1554488076.765 [Default:CheckIn:C.Wait] Stopped
2019-04-05T18:14:36.765Z,1554488076.765 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-05T18:14:36.766Z,1554488076.766 [Default:CheckIn:D] Running Loop=1
2019-04-05T18:14:37.087Z,1554488077.087 [NavChartDb](INFO): Creating index for 36p5
2019-04-05T18:14:37.170Z,1554488077.170 [Default:CheckIn:D] Stopped
2019-04-05T18:14:37.170Z,1554488077.170 [Default:CheckIn:E] Running Loop=1
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.544722 min 
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn:E] Stopped
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn] Stopped
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn](INFO): Running loop #2
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn] Running Loop=2
2019-04-05T18:14:37.570Z,1554488077.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-05T18:14:37.571Z,1554488077.571 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-05T18:14:39.015Z,1554488079.015 [NavChartDb](INFO): Creating index for 54p8
2019-04-05T18:14:40.492Z,1554488080.492 [NavChartDb](INFO): Creating index for 73p1
2019-04-05T18:14:42.269Z,1554488082.269 [NavChartDb](INFO): Creating index for 91p4
2019-04-05T18:14:45.658Z,1554488085.658 [NavChartDb](INFO): Creating index for 182p8
2019-04-05T18:14:46.082Z,1554488086.082 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:14:46.082Z,1554488086.082 [CTD_Seabird] No Fault, FailCount= 3
2019-04-05T18:14:46.333Z,1554488086.333 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:14:46.341Z,1554488086.341 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:14:47.185Z,1554488087.185 [NavChartDb](INFO): Creating index for 274p3
2019-04-05T18:14:48.076Z,1554488088.076 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:14:48.076Z,1554488088.076 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-04-05T18:14:48.076Z,1554488088.076 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-05T18:14:48.078Z,1554488088.078 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-05T18:14:48.078Z,1554488088.078 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-04-05T18:14:48.078Z,1554488088.078 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-05T18:14:48.092Z,1554488088.092 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-05T18:14:48.097Z,1554488088.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-05T18:14:48.489Z,1554488088.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-05T18:14:48.489Z,1554488088.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-04-05T18:14:48.490Z,1554488088.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-05T18:14:48.490Z,1554488088.490 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-04-05T18:14:48.884Z,1554488088.884 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T18:14:49.086Z,1554488089.086 [NavChartDb](INFO): Creating index for 365p7
2019-04-05T18:14:50.232Z,1554488090.232 [NavChartDb](INFO): Creating index for 457p2
2019-04-05T18:14:52.036Z,1554488092.036 [NavChartDb](INFO): Creating index for 548p6
2019-04-05T18:14:53.481Z,1554488093.481 [NavChartDb](INFO): Creating index for 640p0
2019-04-05T18:14:55.997Z,1554488095.997 [NavChartDb](INFO): Creating index for 731p5
2019-04-05T18:14:58.428Z,1554488098.428 [NavChartDb](INFO): Creating index for 822p9
2019-04-05T18:14:59.385Z,1554488099.385 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T18:14:59.385Z,1554488099.385 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T18:14:59.385Z,1554488099.385 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T18:14:59.430Z,1554488099.430 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T18:14:59.789Z,1554488099.789 [CTD_Seabird](INFO): Powering down
2019-04-05T18:15:01.014Z,1554488101.014 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T18:15:01.014Z,1554488101.014 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T18:15:01.133Z,1554488101.133 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T18:15:01.141Z,1554488101.141 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T18:15:02.528Z,1554488102.528 [NavChartDb](INFO): Creating index for 914p4
2019-04-05T18:15:03.880Z,1554488103.880 [NavChartDb](INFO): Creating index for 1005p8
2019-04-05T18:15:04.927Z,1554488104.927 [NavChartDb](INFO): Creating index for 1097p2
2019-04-05T18:15:05.524Z,1554488105.524 [NavChartDb](INFO): Creating index for 1188p7
2019-04-05T18:15:06.498Z,1554488106.498 [NavChartDb](I