2019-04-05T17:56:33.835Z,1554486993.835 [Supervisor](DEBUG): Initializing supervisor.
2019-04-05T17:56:33.837Z,1554486993.837 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-05T17:56:33.838Z,1554486993.838 [SyncHandler](INFO): Protected caller Thread ID is 808
2019-04-05T17:56:33.838Z,1554486993.838 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-05T17:56:33.839Z,1554486993.839 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-05T17:56:33.840Z,1554486993.840 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 809
2019-04-05T17:56:33.842Z,1554486993.842 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-05T17:56:33.854Z,1554486993.854 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-05T17:56:33.855Z,1554486993.855 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-05T17:56:33.856Z,1554486993.856 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 810
2019-04-05T17:56:33.857Z,1554486993.857 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-05T17:56:33.857Z,1554486993.857 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-05T17:56:33.858Z,1554486993.858 [logger ThreadHandler](INFO): Protected caller Thread ID is 811
2019-04-05T17:56:33.860Z,1554486993.860 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-05T17:56:33.860Z,1554486993.860 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-05T17:56:33.863Z,1554486993.863 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-05T17:56:33.959Z,1554486993.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-05T17:56:33.961Z,1554486993.961 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-05T17:56:34.421Z,1554486994.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-05T17:56:34.423Z,1554486994.423 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-05T17:56:34.521Z,1554486994.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-05T17:56:34.521Z,1554486994.521 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-05T17:56:34.623Z,1554486994.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-05T17:56:34.624Z,1554486994.624 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-05T17:56:34.705Z,1554486994.705 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-05T17:56:34.846Z,1554486994.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-05T17:56:34.847Z,1554486994.847 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-05T17:56:35.145Z,1554486995.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-05T17:56:35.145Z,1554486995.145 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-05T17:56:35.613Z,1554486995.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-05T17:56:35.613Z,1554486995.613 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-05T17:56:35.760Z,1554486995.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-05T17:56:35.761Z,1554486995.761 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-05T17:56:35.956Z,1554486995.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-05T17:56:35.957Z,1554486995.957 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-05T17:56:36.416Z,1554486996.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-05T17:56:36.417Z,1554486996.417 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-05T17:56:36.631Z,1554486996.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-05T17:56:36.633Z,1554486996.633 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-05T17:56:36.836Z,1554486996.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-05T17:56:36.837Z,1554486996.837 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-05T17:56:37.275Z,1554486997.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-05T17:56:37.276Z,1554486997.276 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-05T17:56:38.245Z,1554486998.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-05T17:56:38.247Z,1554486998.247 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-04-05T17:56:38.249Z,1554486998.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-04-05T17:56:38.333Z,1554486998.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-04-05T17:56:38.484Z,1554486998.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-04-05T17:56:38.592Z,1554486998.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-04-05T17:56:38.678Z,1554486998.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-04-05T17:56:38.772Z,1554486998.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-04-05T17:56:38.953Z,1554486998.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-04-05T17:56:39.183Z,1554486999.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-05T17:56:39.183Z,1554486999.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-04-05T17:56:39.277Z,1554486999.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-04-05T17:56:39.376Z,1554486999.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-04-05T17:56:39.491Z,1554486999.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-04-05T17:56:39.591Z,1554486999.591 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-04-05T17:56:39.595Z,1554486999.595 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-05T17:56:39.685Z,1554486999.685 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-05T17:56:39.798Z,1554486999.798 [VerticalControl] Loaded
2019-04-05T17:56:39.798Z,1554486999.798 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-05T17:56:39.799Z,1554486999.799 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-05T17:56:39.868Z,1554486999.868 [HorizontalControl] Loaded
2019-04-05T17:56:39.868Z,1554486999.868 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-05T17:56:39.869Z,1554486999.869 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-05T17:56:39.874Z,1554486999.874 [SpeedControl] Loaded
2019-04-05T17:56:39.875Z,1554486999.875 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-05T17:56:39.875Z,1554486999.875 [LoopControl](DEBUG): Construct LoopControl.
2019-04-05T17:56:39.876Z,1554486999.876 [LoopControl] Loaded
2019-04-05T17:56:39.876Z,1554486999.876 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-05T17:56:39.877Z,1554486999.877 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-05T17:56:39.877Z,1554486999.877 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-05T17:56:39.933Z,1554486999.933 [DepthRateCalculator] Loaded
2019-04-05T17:56:39.933Z,1554486999.933 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-05T17:56:39.938Z,1554486999.938 [PitchRateCalculator] Loaded
2019-04-05T17:56:39.938Z,1554486999.938 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-05T17:56:39.954Z,1554486999.954 [SpeedCalculator] Loaded
2019-04-05T17:56:39.954Z,1554486999.954 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-05T17:56:39.976Z,1554486999.976 [TempGradientCalculator] Loaded
2019-04-05T17:56:39.976Z,1554486999.976 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-05T17:56:39.982Z,1554486999.982 [YawRateCalculator] Loaded
2019-04-05T17:56:39.982Z,1554486999.982 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-05T17:56:40.011Z,1554487000.011 [ElevatorOffsetCalculator] Loaded
2019-04-05T17:56:40.011Z,1554487000.011 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-05T17:56:40.012Z,1554487000.012 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-05T17:56:40.014Z,1554487000.014 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-05T17:56:40.055Z,1554487000.055 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-05T17:56:40.055Z,1554487000.055 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-05T17:56:40.157Z,1554487000.157 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-05T17:56:40.158Z,1554487000.158 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-05T17:56:40.521Z,1554487000.521 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-05T17:56:40.522Z,1554487000.522 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-05T17:56:40.648Z,1554487000.648 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-05T17:56:40.648Z,1554487000.648 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-05T17:56:41.341Z,1554487001.341 [AHRS_M2] Loaded
2019-04-05T17:56:41.341Z,1554487001.341 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-04-05T17:56:41.489Z,1554487001.489 [DataOverHttps] Loaded
2019-04-05T17:56:41.489Z,1554487001.489 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-05T17:56:41.491Z,1554487001.491 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B64E0
2019-04-05T17:56:41.491Z,1554487001.491 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 891
2019-04-05T17:56:41.505Z,1554487001.505 [Depth_Keller] Loaded
2019-04-05T17:56:41.505Z,1554487001.505 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-05T17:56:41.510Z,1554487001.510 [DropWeight] Loaded
2019-04-05T17:56:41.510Z,1554487001.510 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-04-05T17:56:41.609Z,1554487001.609 [NAL9602] Loaded
2019-04-05T17:56:41.609Z,1554487001.609 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-05T17:56:41.625Z,1554487001.625 [Onboard] Loaded
2019-04-05T17:56:41.625Z,1554487001.625 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-05T17:56:41.629Z,1554487001.629 [Radio_Surface] Loaded
2019-04-05T17:56:41.629Z,1554487001.629 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-05T17:56:41.630Z,1554487001.630 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E64E0
2019-04-05T17:56:41.630Z,1554487001.630 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 892
2019-04-05T17:56:43.489Z,1554487003.489 [BPC1] Loaded
2019-04-05T17:56:43.490Z,1554487003.490 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-05T17:56:43.490Z,1554487003.490 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-05T17:56:43.491Z,1554487003.491 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-05T17:56:43.612Z,1554487003.612 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-05T17:56:43.612Z,1554487003.612 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-05T17:56:43.672Z,1554487003.672 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-05T17:56:43.673Z,1554487003.673 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-05T17:56:43.694Z,1554487003.694 [NavChart] Loaded
2019-04-05T17:56:43.694Z,1554487003.694 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-05T17:56:43.698Z,1554487003.698 [UniversalFixResidualReporter] Loaded
2019-04-05T17:56:43.698Z,1554487003.698 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-05T17:56:43.699Z,1554487003.699 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-05T17:56:43.700Z,1554487003.700 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-05T17:56:43.860Z,1554487003.860 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-05T17:56:43.872Z,1554487003.872 [SBIT] Loaded
2019-04-05T17:56:43.872Z,1554487003.872 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-05T17:56:43.873Z,1554487003.873 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-05T17:56:43.885Z,1554487003.885 [IBIT] Loaded
2019-04-05T17:56:43.885Z,1554487003.885 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-05T17:56:43.888Z,1554487003.888 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-05T17:56:44.028Z,1554487004.028 [CBIT] Loaded
2019-04-05T17:56:44.028Z,1554487004.028 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-05T17:56:44.028Z,1554487004.028 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-05T17:56:44.029Z,1554487004.029 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-05T17:56:44.148Z,1554487004.148 [BuoyancyServo] Loaded
2019-04-05T17:56:44.148Z,1554487004.148 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-05T17:56:44.160Z,1554487004.160 [ElevatorServo] Loaded
2019-04-05T17:56:44.160Z,1554487004.160 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-05T17:56:44.171Z,1554487004.171 [MassServo] Loaded
2019-04-05T17:56:44.172Z,1554487004.172 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-05T17:56:44.183Z,1554487004.183 [RudderServo] Loaded
2019-04-05T17:56:44.183Z,1554487004.183 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-05T17:56:44.194Z,1554487004.194 [ThrusterServo] Loaded
2019-04-05T17:56:44.194Z,1554487004.194 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-05T17:56:44.195Z,1554487004.195 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-05T17:56:44.196Z,1554487004.196 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-05T17:56:44.219Z,1554487004.219 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-05T17:56:44.220Z,1554487004.220 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-05T17:56:44.542Z,1554487004.542 [CTD_NeilBrown] Loaded
2019-04-05T17:56:44.542Z,1554487004.542 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-04-05T17:56:44.543Z,1554487004.543 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0
2019-04-05T17:56:44.543Z,1554487004.543 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 893
2019-04-05T17:56:44.553Z,1554487004.553 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-05T17:56:44.558Z,1554487004.558 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-05T17:56:44.560Z,1554487004.560 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-05T17:56:44.565Z,1554487004.565 [CTD_Seabird](INFO): created writer for : depth
2019-04-05T17:56:44.565Z,1554487004.565 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-05T17:56:44.570Z,1554487004.570 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-05T17:56:44.571Z,1554487004.571 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-05T17:56:44.576Z,1554487004.576 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-05T17:56:44.576Z,1554487004.576 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-05T17:56:44.582Z,1554487004.582 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-05T17:56:44.582Z,1554487004.582 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-05T17:56:44.588Z,1554487004.588 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-05T17:56:44.588Z,1554487004.588 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-05T17:56:44.593Z,1554487004.593 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-05T17:56:44.620Z,1554487004.620 [CTD_Seabird] Loaded
2019-04-05T17:56:44.620Z,1554487004.620 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-05T17:56:44.621Z,1554487004.621 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0
2019-04-05T17:56:44.621Z,1554487004.621 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894
2019-04-05T17:56:44.669Z,1554487004.669 [WetLabsBB2FL] Loaded
2019-04-05T17:56:44.670Z,1554487004.670 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-05T17:56:44.671Z,1554487004.671 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0
2019-04-05T17:56:44.671Z,1554487004.671 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895
2019-04-05T17:56:44.672Z,1554487004.672 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-05T17:56:44.676Z,1554487004.676 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-05T17:56:44.676Z,1554487004.676 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-05T17:56:44.683Z,1554487004.683 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-05T17:56:44.684Z,1554487004.684 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-04-05T17:56:44.684Z,1554487004.684 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 896
2019-04-05T17:56:44.689Z,1554487004.689 [Supervisor](INFO): Main Thread ID is 804
2019-04-05T17:56:44.689Z,1554487004.689 [Supervisor](DEBUG): Running supervisor.
2019-04-05T17:56:44.690Z,1554487004.690 [CommandLine ThreadHandler](INFO): Handler Thread ID is 897
2019-04-05T17:56:44.692Z,1554487004.692 [controlThread ThreadHandler](INFO): Handler Thread ID is 898
2019-04-05T17:56:44.693Z,1554487004.693 [controlThread](DEBUG): Initializing ControlThread
2019-04-05T17:56:44.693Z,1554487004.693 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-05T17:56:44.695Z,1554487004.695 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-05T17:56:44.696Z,1554487004.696 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-05T17:56:44.696Z,1554487004.696 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-05T17:56:44.697Z,1554487004.697 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-05T17:56:44.697Z,1554487004.697 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-05T17:56:44.697Z,1554487004.697 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-05T17:56:44.698Z,1554487004.698 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-05T17:56:44.698Z,1554487004.698 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-05T17:56:44.698Z,1554487004.698 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-05T17:56:44.705Z,1554487004.705 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-05T17:56:44.705Z,1554487004.705 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-05T17:56:44.706Z,1554487004.706 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-05T17:56:44.706Z,1554487004.706 [SBIT](INFO): Initialize SBIT Component.
2019-04-05T17:56:44.707Z,1554487004.707 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-05T17:56:44.707Z,1554487004.707 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-05T17:56:44.707Z,1554487004.707 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-05T17:56:44.708Z,1554487004.708 [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:56:44.709Z,1554487004.709 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-04-05T17:56:44.710Z,1554487004.710 [IBIT](INFO): Initialize IBIT Component.
2019-04-05T17:56:44.711Z,1554487004.711 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-05T17:56:44.712Z,1554487004.712 [logger ThreadHandler](INFO): Handler Thread ID is 899
2019-04-05T17:56:44.722Z,1554487004.722 [CBIT](DEBUG): Initialized mux pins.
2019-04-05T17:56:44.723Z,1554487004.723 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-04-05T17:56:44.723Z,1554487004.723 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-05T17:56:44.731Z,1554487004.731 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 900
2019-04-05T17:56:44.732Z,1554487004.732 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-05T17:56:44.743Z,1554487004.743 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 901
2019-04-05T17:56:44.746Z,1554487004.746 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-05T17:56:44.747Z,1554487004.747 [CBIT](DEBUG): Initializing heartbeat.
2019-04-05T17:56:44.755Z,1554487004.755 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 902
2019-04-05T17:56:44.763Z,1554487004.763 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 903
2019-04-05T17:56:44.763Z,1554487004.763 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T17:56:44.767Z,1554487004.767 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T17:56:44.768Z,1554487004.768 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 905
2019-04-05T17:56:44.769Z,1554487004.769 [WetLabsBB2FL](INFO): Powering down
2019-04-05T17:56:44.803Z,1554487004.803 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 906
2019-04-05T17:56:44.809Z,1554487004.809 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2019-04-05T17:56:44.818Z,1554487004.818 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-05T17:56:44.819Z,1554487004.819 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-05T17:56:44.830Z,1554487004.830 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-05T17:56:44.832Z,1554487004.832 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2019-04-05T17:56:44.832Z,1554487004.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-05T17:56:44.833Z,1554487004.833 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2019-04-05T17:56:44.833Z,1554487004.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-05T17:56:44.835Z,1554487004.835 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2019-04-05T17:56:44.835Z,1554487004.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-05T17:56:44.836Z,1554487004.836 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2019-04-05T17:56:44.837Z,1554487004.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-05T17:56:44.838Z,1554487004.838 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2019-04-05T17:56:44.838Z,1554487004.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-05T17:56:44.840Z,1554487004.840 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2019-04-05T17:56:44.840Z,1554487004.840 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-05T17:56:44.842Z,1554487004.842 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2019-04-05T17:56:44.842Z,1554487004.842 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:56:44.854Z,1554487004.854 [CBIT](DEBUG): Backplane powered.
2019-04-05T17:56:44.856Z,1554487004.856 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-05T17:56:44.877Z,1554487004.877 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2019-04-05T17:56:44.888Z,1554487004.888 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-05T17:56:44.902Z,1554487004.902 [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:56:44.926Z,1554487004.926 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-05T17:56:44.931Z,1554487004.931 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:56:44.994Z,1554487004.994 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-05T17:56:44.996Z,1554487004.996 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-05T17:56:44.997Z,1554487004.997 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-05T17:56:45.025Z,1554487005.025 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-05T17:56:45.028Z,1554487005.028 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-05T17:56:45.067Z,1554487005.067 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-05T17:56:45.070Z,1554487005.070 [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:56:45.090Z,1554487005.090 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-05T17:56:45.096Z,1554487005.096 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-05T17:56:45.146Z,1554487005.146 [Radio_Surface](INFO): Powering up
2019-04-05T17:56:45.215Z,1554487005.215 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-04-05T17:56:45.271Z,1554487005.271 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-05T17:56:45.331Z,1554487005.331 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:56:45.339Z,1554487005.339 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-05T17:56:45.340Z,1554487005.340 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:56:45.367Z,1554487005.367 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-05T17:56:45.368Z,1554487005.368 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:56:45.375Z,1554487005.375 [MassServo](DEBUG): Initializing MassServo.
2019-04-05T17:56:45.375Z,1554487005.375 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:56:45.383Z,1554487005.383 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-05T17:56:45.384Z,1554487005.384 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:56:45.391Z,1554487005.391 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-05T17:56:45.520Z,1554487005.520 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-04-05T17:56:45.521Z,1554487005.521 [DropWeight] Hardware Fault, FailCount= 1
2019-04-05T17:56:45.521Z,1554487005.521 [DropWeight](ERROR): Hardware Fault
2019-04-05T17:56:45.548Z,1554487005.548 [CommandLine](FAULT): Scheduling is paused
2019-04-05T17:56:45.549Z,1554487005.549 [CBIT](INFO): Critical error at 20190405T175645
2019-04-05T17:56:45.549Z,1554487005.549 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-05T17:56:45.564Z,1554487005.564 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-04-05T17:56:45.564Z,1554487005.564 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-04-05T17:56:46.238Z,1554487006.238 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-05T17:56:46.239Z,1554487006.239 [RudderServo](FAULT): Rudder failed to initialize
2019-04-05T17:56:46.239Z,1554487006.239 [RudderServo] Communications Fault, FailCount= 1
2019-04-05T17:56:46.239Z,1554487006.239 [RudderServo](ERROR): Communications Fault
2019-04-05T17:56:46.376Z,1554487006.376 [CBIT](INFO): Critical error at 20190405T175645
2019-04-05T17:56:46.379Z,1554487006.379 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-05T17:56:46.563Z,1554487006.563 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-05T17:56:46.563Z,1554487006.563 [RudderServo](INFO): Powering down
2019-04-05T17:56:47.239Z,1554487007.239 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-05T17:56:47.363Z,1554487007.363 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-05T17:56:47.368Z,1554487007.368 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-05T17:56:47.368Z,1554487007.368 [RudderServo] No Fault, FailCount= 1
2019-04-05T17:56:48.718Z,1554487008.718 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:49.138Z,1554487009.138 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:49.634Z,1554487009.634 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:50.206Z,1554487010.206 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:50.610Z,1554487010.610 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:51.075Z,1554487011.075 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:51.678Z,1554487011.678 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-04-05T17:56:52.783Z,1554487012.783 [Depth_Keller](ERROR): Pressure reading out of range: -11.721126 decibar
2019-04-05T17:56:55.090Z,1554487015.090 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:55.122Z,1554487015.122 [CBIT](CRITICAL): Environmental Failure. Press:14.442752 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-05T17:56:55.494Z,1554487015.494 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:55.526Z,1554487015.526 [CBIT](INFO): Critical error at 20190405T175655
2019-04-05T17:56:55.899Z,1554487015.899 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:56.302Z,1554487016.302 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:56.715Z,1554487016.715 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:57.118Z,1554487017.118 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:57.518Z,1554487017.518 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:57.815Z,1554487017.815 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T17:56:57.815Z,1554487017.815 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-05T17:56:57.815Z,1554487017.815 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T17:56:57.918Z,1554487017.918 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:57.979Z,1554487017.979 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T17:56:58.219Z,1554487018.219 [CTD_Seabird](INFO): Powering down
2019-04-05T17:56:58.323Z,1554487018.323 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:56:58.323Z,1554487018.323 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-04-05T17:56:58.323Z,1554487018.323 [AHRS_M2] Communications Fault, FailCount= 1
2019-04-05T17:56:58.323Z,1554487018.323 [AHRS_M2](ERROR): Communications Fault
2019-04-05T17:56:58.354Z,1554487018.354 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-04-05T17:56:58.411Z,1554487018.411 [AHRS_M2](INFO): Powering down
2019-04-05T17:56:59.292Z,1554487019.292 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-05T17:56:59.292Z,1554487019.292 [AHRS_M2] No Fault, FailCount= 1
2019-04-05T17:56:59.292Z,1554487019.292 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T17:56:59.292Z,1554487019.292 [CTD_Seabird] No Fault, FailCount= 1
2019-04-05T17:56:59.586Z,1554487019.586 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T17:56:59.595Z,1554487019.595 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T17:56:59.623Z,1554487019.623 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-05T17:56:59.733Z,1554487019.733 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-05T17:57:03.178Z,1554487023.178 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:03.586Z,1554487023.586 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:03.982Z,1554487023.982 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:04.390Z,1554487024.390 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:04.790Z,1554487024.790 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:05.214Z,1554487025.214 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:05.594Z,1554487025.594 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:06.014Z,1554487026.014 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:06.407Z,1554487026.407 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:06.814Z,1554487026.814 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:07.218Z,1554487027.218 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:07.622Z,1554487027.622 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:08.018Z,1554487028.018 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:08.438Z,1554487028.438 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:08.830Z,1554487028.830 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:09.234Z,1554487029.234 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:09.646Z,1554487029.646 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:10.050Z,1554487030.050 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:10.450Z,1554487030.450 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:10.846Z,1554487030.846 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:11.254Z,1554487031.254 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:11.670Z,1554487031.670 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:11.675Z,1554487031.675 [NAL9602](INFO): Powering up NAL9602
2019-04-05T17:57:12.086Z,1554487032.086 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:12.502Z,1554487032.502 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:12.503Z,1554487032.503 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-04-05T17:57:12.503Z,1554487032.503 [AHRS_M2] Communications Fault, FailCount= 2
2019-04-05T17:57:12.503Z,1554487032.503 [AHRS_M2](ERROR): Communications Fault
2019-04-05T17:57:12.534Z,1554487032.534 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-04-05T17:57:12.607Z,1554487032.607 [AHRS_M2](INFO): Powering down
2019-04-05T17:57:12.639Z,1554487032.639 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T17:57:12.639Z,1554487032.639 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-05T17:57:12.639Z,1554487032.639 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T17:57:12.727Z,1554487032.727 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T17:57:13.015Z,1554487033.015 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-05T17:57:13.020Z,1554487033.020 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-05T17:57:13.042Z,1554487033.042 [CTD_Seabird](INFO): Powering down
2019-04-05T17:57:13.488Z,1554487033.488 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-05T17:57:13.488Z,1554487033.488 [AHRS_M2] No Fault, FailCount= 2
2019-04-05T17:57:13.779Z,1554487033.779 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-05T17:57:14.268Z,1554487034.268 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-05T17:57:14.268Z,1554487034.268 [CTD_Seabird] No Fault, FailCount= 2
2019-04-05T17:57:14.382Z,1554487034.382 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-05T17:57:14.386Z,1554487034.386 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-05T17:57:17.495Z,1554487037.495 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:17.983Z,1554487037.983 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:18.594Z,1554487038.594 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:19.014Z,1554487039.014 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:19.414Z,1554487039.414 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:19.834Z,1554487039.834 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:20.346Z,1554487040.346 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:20.492Z,1554487040.492 [NavChartDb](INFO): # of records loaded: 5000
2019-04-05T17:57:20.758Z,1554487040.758 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:21.162Z,1554487041.162 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:21.671Z,1554487041.671 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:22.074Z,1554487042.074 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:22.491Z,1554487042.491 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:22.990Z,1554487042.990 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:23.394Z,1554487043.394 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:23.842Z,1554487043.842 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:24.390Z,1554487044.390 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:24.523Z,1554487044.523 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001758
CHAN A1 (24V): 0.002843
CHAN A2 (12V): -0.006995
CHAN A3 (5V): -0.002289
CHAN B0 (3.3V): 0.000234
CHAN B1 (3.15aV): 0.000099
CHAN B2 (3.15bV): -0.000232
CHAN B3 (GND): 0.001828
OPEN: -0.000370
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-05T17:57:24.859Z,1554487044.859 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:25.266Z,1554487045.266 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:25.694Z,1554487045.694 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:26.107Z,1554487046.107 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:26.526Z,1554487046.526 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:27.006Z,1554487047.006 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:27.006Z,1554487047.006 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-04-05T17:57:27.007Z,1554487047.007 [AHRS_M2] Communications Fault, FailCount= 3
2019-04-05T17:57:27.007Z,1554487047.007 [AHRS_M2](ERROR): Communications Fault
2019-04-05T17:57:27.082Z,1554487047.082 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-04-05T17:57:27.087Z,1554487047.087 [AHRS_M2](INFO): Powering down
2019-04-05T17:57:27.435Z,1554487047.435 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-05T17:57:27.435Z,1554487047.435 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-05T17:57:27.435Z,1554487047.435 [CTD_Seabird](ERROR): Communications Fault
2019-04-05T17:57:27.528Z,1554487047.528 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-05T17:57:27.528Z,1554487047.528 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-05T17:57:27.839Z,1554487047.839 [CTD_Seabird](INFO): Powering down
2019-04-05T17:57:28.067Z,1554487048.067 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-04-05T17:57:28.067Z,1554487048.067 [AHRS_M2] No Fault, FailCount= 3
2019-04-05T17:57:28.298Z,1554487048.298 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-04-05T17:57:28.556Z,1554487048.556 [CommandLine](IMPORTANT): got command restart system
2019-04-05T17:57:31.127Z,1554487051.127 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:31.127Z,1554487051.127 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.142Z,1554487051.142 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-05T17:57:31.143Z,1554487051.143 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.143Z,1554487051.143 [CommandLine](INFO): Join timeout helper Thread ID is 928
2019-04-05T17:57:31.146Z,1554487051.146 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-05T17:57:31.147Z,1554487051.147 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.147Z,1554487051.147 [NavChartDb](INFO): Join timeout helper Thread ID is 929
2019-04-05T17:57:31.148Z,1554487051.148 [NavChartDb](INFO): # of records loaded: 6268
2019-04-05T17:57:31.155Z,1554487051.155 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-05T17:57:31.156Z,1554487051.156 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:31.156Z,1554487051.156 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.174Z,1554487051.174 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-05T17:57:31.175Z,1554487051.175 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.175Z,1554487051.175 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 930
2019-04-05T17:57:31.667Z,1554487051.667 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:31.667Z,1554487051.667 [WetLabsBB2FL](INFO): Powering down
2019-04-05T17:57:31.668Z,1554487051.668 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.670Z,1554487051.670 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-05T17:57:31.671Z,1554487051.671 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:31.671Z,1554487051.671 [CTD_Seabird](INFO): Join timeout helper Thread ID is 931
2019-04-05T17:57:31.922Z,1554487051.922 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:32.019Z,1554487052.019 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:32.020Z,1554487052.020 [CTD_Seabird](INFO): Powering down
2019-04-05T17:57:32.035Z,1554487052.035 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.039Z,1554487052.039 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-04-05T17:57:32.039Z,1554487052.039 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.039Z,1554487052.039 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 932
2019-04-05T17:57:32.127Z,1554487052.127 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:32.128Z,1554487052.128 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.139Z,1554487052.139 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-05T17:57:32.140Z,1554487052.140 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.140Z,1554487052.140 [Radio_Surface](INFO): Join timeout helper Thread ID is 933
2019-04-05T17:57:32.370Z,1554487052.370 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2019-04-05T17:57:32.415Z,1554487052.415 [Radio_Surface](INFO): Powering down
2019-04-05T17:57:32.416Z,1554487052.416 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:32.416Z,1554487052.416 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.420Z,1554487052.420 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-05T17:57:32.420Z,1554487052.420 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.420Z,1554487052.420 [DataOverHttps](INFO): Join timeout helper Thread ID is 934
2019-04-05T17:57:32.423Z,1554487052.423 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:32.424Z,1554487052.424 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.440Z,1554487052.440 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-05T17:57:32.440Z,1554487052.440 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.440Z,1554487052.440 [logger](INFO): Join timeout helper Thread ID is 935
2019-04-05T17:57:32.442Z,1554487052.442 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:32.443Z,1554487052.443 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.444Z,1554487052.444 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-05T17:57:32.445Z,1554487052.445 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.445Z,1554487052.445 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-05T17:57:32.445Z,1554487052.445 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.445Z,1554487052.445 [controlThread](INFO): Join timeout helper Thread ID is 936
2019-04-05T17:57:32.501Z,1554487052.501 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-05T17:57:32.501Z,1554487052.501 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-05T17:57:32.501Z,1554487052.501 [AHRS_M2](INFO): Powering down
2019-04-05T17:57:32.571Z,1554487052.571 [NAL9602](INFO): Powering down
2019-04-05T17:57:32.573Z,1554487052.573 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-05T17:57:32.574Z,1554487052.574 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-05T17:57:32.575Z,1554487052.575 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-05T17:57:32.575Z,1554487052.575 [MissionManager](INFO): Uninitializing Mission Default
2019-04-05T17:57:32.578Z,1554487052.578 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-05T17:57:32.580Z,1554487052.580 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-05T17:57:32.580Z,1554487052.580 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-05T17:57:32.580Z,1554487052.580 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-05T17:57:32.581Z,1554487052.581 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-05T17:57:32.581Z,1554487052.581 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-05T17:57:32.581Z,1554487052.581 [BuoyancyServo](INFO): Powering down
2019-04-05T17:57:32.595Z,1554487052.595 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-05T17:57:32.595Z,1554487052.595 [ElevatorServo](INFO): Powering down
2019-04-05T17:57:32.596Z,1554487052.596 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-05T17:57:32.596Z,1554487052.596 [MassServo](INFO): Powering down
2019-04-05T17:57:32.596Z,1554487052.596 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-05T17:57:32.597Z,1554487052.597 [RudderServo](INFO): Powering down
2019-04-05T17:57:32.597Z,1554487052.597 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-05T17:57:32.597Z,1554487052.597 [ThrusterServo](INFO): Powering down
2019-04-05T17:57:32.598Z,1554487052.598 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-05T17:57:32.599Z,1554487052.599 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-05T17:57:32.599Z,1554487052.599 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-05T17:57:32.599Z,1554487052.599 [CBIT](DEBUG): Powering off loads.
2019-04-05T17:57:32.610Z,1554487052.610 [CBIT](DEBUG): Disabling WDT.
2019-04-05T17:57:32.622Z,1554487052.622 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-05T17:57:32.623Z,1554487052.623 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.703Z,1554487052.703 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:32.709Z,1554487052.709 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:33.118Z,1554487053.118 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:33.121Z,1554487053.121 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:33.124Z,1554487053.124 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:33.133Z,1554487053.133 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-05T17:57:33.180Z,1554487053.180 [logger ThreadHandler](INFO): Thread cancelled.