2018-04-11T13:10:59.697Z,1523452259.697 [Supervisor](DEBUG): Initializing supervisor.
2018-04-11T13:10:59.709Z,1523452259.709 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-04-11T13:10:59.709Z,1523452259.709 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-04-11T13:10:59.710Z,1523452259.710 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-04-11T13:10:59.721Z,1523452259.721 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-04-11T13:10:59.721Z,1523452259.721 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-04-11T13:10:59.724Z,1523452259.724 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-04-11T13:10:59.745Z,1523452259.745 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-04-11T13:10:59.757Z,1523452259.757 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-04-11T13:10:59.757Z,1523452259.757 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-04-11T13:10:59.758Z,1523452259.758 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-04-11T13:10:59.769Z,1523452259.769 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-04-11T13:10:59.769Z,1523452259.769 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-04-11T13:10:59.771Z,1523452259.771 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-04-11T13:10:59.771Z,1523452259.771 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-04-11T13:10:59.776Z,1523452259.776 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-04-11T13:10:59.872Z,1523452259.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-04-11T13:10:59.873Z,1523452259.873 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-04-11T13:10:59.972Z,1523452259.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-04-11T13:10:59.973Z,1523452259.973 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-04-11T13:11:00.162Z,1523452260.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-04-11T13:11:00.163Z,1523452260.163 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-04-11T13:11:00.456Z,1523452260.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-04-11T13:11:00.458Z,1523452260.458 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-04-11T13:11:00.773Z,1523452260.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-04-11T13:11:00.775Z,1523452260.775 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-04-11T13:11:01.206Z,1523452261.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-04-11T13:11:01.207Z,1523452261.207 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-04-11T13:11:01.551Z,1523452261.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-04-11T13:11:01.551Z,1523452261.551 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-04-11T13:11:01.968Z,1523452261.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-04-11T13:11:01.969Z,1523452261.969 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-04-11T13:11:02.050Z,1523452262.050 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-04-11T13:11:02.343Z,1523452262.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-04-11T13:11:02.344Z,1523452262.344 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-04-11T13:11:02.649Z,1523452262.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-04-11T13:11:02.650Z,1523452262.650 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-04-11T13:11:02.848Z,1523452262.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-04-11T13:11:02.849Z,1523452262.849 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-04-11T13:11:03.051Z,1523452263.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-04-11T13:11:03.052Z,1523452263.052 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-04-11T13:11:03.148Z,1523452263.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-04-11T13:11:03.149Z,1523452263.149 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-04-11T13:11:03.285Z,1523452263.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-04-11T13:11:03.287Z,1523452263.287 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-04-11T13:11:03.290Z,1523452263.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-04-11T13:11:03.511Z,1523452263.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-04-11T13:11:03.512Z,1523452263.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-04-11T13:11:03.590Z,1523452263.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-04-11T13:11:03.727Z,1523452263.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-04-11T13:11:03.819Z,1523452263.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-04-11T13:11:03.903Z,1523452263.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-04-11T13:11:04.041Z,1523452264.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-04-11T13:11:04.209Z,1523452264.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-04-11T13:11:04.304Z,1523452264.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-04-11T13:11:04.395Z,1523452264.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-04-11T13:11:04.491Z,1523452264.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-04-11T13:11:04.573Z,1523452264.573 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-04-11T13:11:04.577Z,1523452264.577 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-04-11T13:11:04.699Z,1523452264.699 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-04-11T13:11:04.701Z,1523452264.701 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-04-11T13:11:04.725Z,1523452264.725 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-04-11T13:11:04.725Z,1523452264.725 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-04-11T13:11:04.779Z,1523452264.779 [DepthRateCalculator] Loaded
2018-04-11T13:11:04.780Z,1523452264.780 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-04-11T13:11:04.785Z,1523452264.785 [PitchRateCalculator] Loaded
2018-04-11T13:11:04.786Z,1523452264.786 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-04-11T13:11:04.801Z,1523452264.801 [SpeedCalculator] Loaded
2018-04-11T13:11:04.801Z,1523452264.801 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-04-11T13:11:04.821Z,1523452264.821 [TempGradientCalculator] Loaded
2018-04-11T13:11:04.821Z,1523452264.821 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-04-11T13:11:04.836Z,1523452264.836 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-04-11T13:11:04.836Z,1523452264.836 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-04-11T13:11:04.842Z,1523452264.842 [YawRateCalculator] Loaded
2018-04-11T13:11:04.842Z,1523452264.842 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-04-11T13:11:04.879Z,1523452264.879 [ElevatorOffsetCalculator] Loaded
2018-04-11T13:11:04.879Z,1523452264.879 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-04-11T13:11:04.880Z,1523452264.880 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-04-11T13:11:04.881Z,1523452264.881 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-04-11T13:11:04.917Z,1523452264.917 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-04-11T13:11:04.917Z,1523452264.917 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-04-11T13:11:05.505Z,1523452265.505 [AHRS_M2] Loaded
2018-04-11T13:11:05.505Z,1523452265.505 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-04-11T13:11:05.546Z,1523452265.546 [AcousticModem_Benthos_ATM900] Loaded
2018-04-11T13:11:05.547Z,1523452265.547 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-04-11T13:11:05.682Z,1523452265.682 [DataOverHttps] Loaded
2018-04-11T13:11:05.682Z,1523452265.682 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-04-11T13:11:05.694Z,1523452265.694 [Depth_Keller] Loaded
2018-04-11T13:11:05.695Z,1523452265.695 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-04-11T13:11:05.699Z,1523452265.699 [DropWeight] Loaded
2018-04-11T13:11:05.699Z,1523452265.699 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-04-11T13:11:05.791Z,1523452265.791 [NAL9602] Loaded
2018-04-11T13:11:05.791Z,1523452265.791 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-04-11T13:11:05.806Z,1523452265.806 [Onboard] Loaded
2018-04-11T13:11:05.807Z,1523452265.807 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-04-11T13:11:05.816Z,1523452265.816 [Radio_Surface] Loaded
2018-04-11T13:11:05.816Z,1523452265.816 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-04-11T13:11:05.818Z,1523452265.818 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407AE4E0
2018-04-11T13:11:05.818Z,1523452265.818 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 881
2018-04-11T13:11:05.876Z,1523452265.876 [Rowe_600LCM] Loaded
2018-04-11T13:11:05.876Z,1523452265.876 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-04-11T13:11:05.877Z,1523452265.877 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407DE4E0
2018-04-11T13:11:05.877Z,1523452265.877 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 882
2018-04-11T13:11:07.546Z,1523452267.546 [BPC1] Loaded
2018-04-11T13:11:07.546Z,1523452267.546 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-04-11T13:11:07.546Z,1523452267.546 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-04-11T13:11:07.547Z,1523452267.547 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-04-11T13:11:07.932Z,1523452267.932 [SBIT](DEBUG): Construct Startup Built In Test.
2018-04-11T13:11:07.954Z,1523452267.954 [SBIT] Loaded
2018-04-11T13:11:07.954Z,1523452267.954 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-04-11T13:11:07.955Z,1523452267.955 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-04-11T13:11:07.966Z,1523452267.966 [IBIT] Loaded
2018-04-11T13:11:07.966Z,1523452267.966 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-04-11T13:11:07.969Z,1523452267.969 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-04-11T13:11:08.112Z,1523452268.112 [CBIT] Loaded
2018-04-11T13:11:08.112Z,1523452268.112 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-04-11T13:11:08.112Z,1523452268.112 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-04-11T13:11:08.113Z,1523452268.113 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-04-11T13:11:08.291Z,1523452268.291 [Aanderaa_O2] Loaded
2018-04-11T13:11:08.291Z,1523452268.291 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-04-11T13:11:08.336Z,1523452268.336 [CTD_Seabird] Loaded
2018-04-11T13:11:08.336Z,1523452268.336 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-04-11T13:11:08.337Z,1523452268.337 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4089E4E0
2018-04-11T13:11:08.338Z,1523452268.338 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 883
2018-04-11T13:11:08.354Z,1523452268.354 [ESPComponent] Loaded
2018-04-11T13:11:08.354Z,1523452268.354 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-04-11T13:11:08.368Z,1523452268.368 [PAR_Licor] Loaded
2018-04-11T13:11:08.368Z,1523452268.368 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-04-11T13:11:08.414Z,1523452268.414 [WetLabsBB2FL] Loaded
2018-04-11T13:11:08.414Z,1523452268.414 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-04-11T13:11:08.415Z,1523452268.415 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-04-11T13:11:08.416Z,1523452268.416 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 884
2018-04-11T13:11:08.416Z,1523452268.416 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-04-11T13:11:08.417Z,1523452268.417 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-04-11T13:11:08.682Z,1523452268.682 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-04-11T13:11:08.682Z,1523452268.682 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-04-11T13:11:08.766Z,1523452268.766 [VerticalControl](DEBUG): Construct VerticalControl.
2018-04-11T13:11:08.855Z,1523452268.855 [VerticalControl] Loaded
2018-04-11T13:11:08.855Z,1523452268.855 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-04-11T13:11:08.856Z,1523452268.856 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-04-11T13:11:08.913Z,1523452268.913 [HorizontalControl] Loaded
2018-04-11T13:11:08.914Z,1523452268.914 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-04-11T13:11:08.914Z,1523452268.914 [SpeedControl](DEBUG): Construct SpeedControl.
2018-04-11T13:11:08.919Z,1523452268.919 [SpeedControl] Loaded
2018-04-11T13:11:08.919Z,1523452268.919 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-04-11T13:11:08.920Z,1523452268.920 [LoopControl](DEBUG): Construct LoopControl.
2018-04-11T13:11:08.921Z,1523452268.921 [LoopControl] Loaded
2018-04-11T13:11:08.921Z,1523452268.921 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-04-11T13:11:08.921Z,1523452268.921 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-04-11T13:11:08.922Z,1523452268.922 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-04-11T13:11:09.040Z,1523452269.040 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-04-11T13:11:09.040Z,1523452269.040 [StratificationFrontDetector](DEBUG): (re)initializing
2018-04-11T13:11:09.040Z,1523452269.040 [StratificationFrontDetector] Loaded
2018-04-11T13:11:09.041Z,1523452269.041 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-04-11T13:11:09.041Z,1523452269.041 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-04-11T13:11:09.042Z,1523452269.042 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-04-11T13:11:09.153Z,1523452269.153 [BuoyancyServo] Loaded
2018-04-11T13:11:09.154Z,1523452269.154 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-04-11T13:11:09.165Z,1523452269.165 [ElevatorServo] Loaded
2018-04-11T13:11:09.165Z,1523452269.165 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-04-11T13:11:09.175Z,1523452269.175 [MassServo] Loaded
2018-04-11T13:11:09.176Z,1523452269.176 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-04-11T13:11:09.186Z,1523452269.186 [RudderServo] Loaded
2018-04-11T13:11:09.187Z,1523452269.187 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-04-11T13:11:09.197Z,1523452269.197 [ThrusterServo] Loaded
2018-04-11T13:11:09.197Z,1523452269.197 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-04-11T13:11:09.198Z,1523452269.198 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-04-11T13:11:09.198Z,1523452269.198 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-04-11T13:11:09.305Z,1523452269.305 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-04-11T13:11:09.306Z,1523452269.306 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-04-11T13:11:09.358Z,1523452269.358 [DeadReckonUsingSpeedCalculator] Loaded
2018-04-11T13:11:09.358Z,1523452269.358 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-04-11T13:11:09.374Z,1523452269.374 [NavChart] Loaded
2018-04-11T13:11:09.374Z,1523452269.374 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-04-11T13:11:09.378Z,1523452269.378 [UniversalFixResidualReporter] Loaded
2018-04-11T13:11:09.378Z,1523452269.378 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-04-11T13:11:09.379Z,1523452269.379 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-04-11T13:11:09.382Z,1523452269.382 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-04-11T13:11:09.383Z,1523452269.383 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-04-11T13:11:09.389Z,1523452269.389 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-04-11T13:11:09.390Z,1523452269.390 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A274E0
2018-04-11T13:11:09.390Z,1523452269.390 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2018-04-11T13:11:09.395Z,1523452269.395 [Supervisor](INFO): Main Thread ID is 795
2018-04-11T13:11:09.395Z,1523452269.395 [Supervisor](DEBUG): Running supervisor.
2018-04-11T13:11:09.395Z,1523452269.395 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2018-04-11T13:11:09.398Z,1523452269.398 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2018-04-11T13:11:09.399Z,1523452269.399 [controlThread](DEBUG): Initializing ControlThread
2018-04-11T13:11:09.399Z,1523452269.399 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-04-11T13:11:09.400Z,1523452269.400 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-04-11T13:11:09.400Z,1523452269.400 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-04-11T13:11:09.400Z,1523452269.400 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-04-11T13:11:09.401Z,1523452269.401 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-04-11T13:11:09.402Z,1523452269.402 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-04-11T13:11:09.402Z,1523452269.402 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-04-11T13:11:09.407Z,1523452269.407 [SBIT](INFO): Initialize SBIT Component.
2018-04-11T13:11:09.407Z,1523452269.407 [SBIT](IMPORTANT): git: 2018-03-26-9-g8f36209
2018-04-11T13:11:09.408Z,1523452269.408 [SBIT](INFO): git hash: 8f3620926a6c43355df77008a9a99d90316223a2
2018-04-11T13:11:09.408Z,1523452269.408 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-04-11T13:11:09.409Z,1523452269.409 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-04-11T13:11:09.410Z,1523452269.410 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-04-11T13:11:09.411Z,1523452269.411 [IBIT](INFO): Initialize IBIT Component.
2018-04-11T13:11:09.411Z,1523452269.411 [CBIT](DEBUG): Initialize CBIT Component.
2018-04-11T13:11:09.412Z,1523452269.412 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-04-11T13:11:09.412Z,1523452269.412 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-04-11T13:11:09.413Z,1523452269.413 [logger ThreadHandler](INFO): Handler Thread ID is 888
2018-04-11T13:11:09.429Z,1523452269.429 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889
2018-04-11T13:11:09.435Z,1523452269.435 [Radio_Surface](INFO): Powering up
2018-04-11T13:11:09.441Z,1523452269.441 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 890
2018-04-11T13:11:09.442Z,1523452269.442 [Rowe_600LCM](INFO): Initializing
2018-04-11T13:11:09.442Z,1523452269.442 [Rowe_600LCM](INFO): Checking LCM
2018-04-11T13:11:09.529Z,1523452269.529 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 891
2018-04-11T13:11:09.530Z,1523452269.530 [CTD_Seabird](INFO): Initializing
2018-04-11T13:11:09.530Z,1523452269.530 [CTD_Seabird](INFO): Checking LCM
2018-04-11T13:11:09.533Z,1523452269.533 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-04-11T13:11:09.535Z,1523452269.535 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-04-11T13:11:09.535Z,1523452269.535 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-04-11T13:11:09.536Z,1523452269.536 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-04-11T13:11:09.537Z,1523452269.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-04-11T13:11:09.538Z,1523452269.538 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-04-11T13:11:09.538Z,1523452269.538 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-04-11T13:11:09.539Z,1523452269.539 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-04-11T13:11:09.539Z,1523452269.539 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-04-11T13:11:09.539Z,1523452269.539 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-04-11T13:11:09.540Z,1523452269.540 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-04-11T13:11:09.540Z,1523452269.540 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-04-11T13:11:09.545Z,1523452269.545 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-04-11T13:11:09.556Z,1523452269.556 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 892
2018-04-11T13:11:09.557Z,1523452269.557 [WetLabsBB2FL](INFO): Powering down
2018-04-11T13:11:09.672Z,1523452269.672 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893
2018-04-11T13:11:09.732Z,1523452269.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-04-11T13:11:09.732Z,1523452269.732 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-04-11T13:11:09.732Z,1523452269.732 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-04-11T13:11:09.733Z,1523452269.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-04-11T13:11:09.733Z,1523452269.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-04-11T13:11:09.733Z,1523452269.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-04-11T13:11:09.733Z,1523452269.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-04-11T13:11:09.733Z,1523452269.733 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-04-11T13:11:09.734Z,1523452269.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-04-11T13:11:09.734Z,1523452269.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-04-11T13:11:09.734Z,1523452269.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-04-11T13:11:09.734Z,1523452269.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-04-11T13:11:09.734Z,1523452269.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-04-11T13:11:09.734Z,1523452269.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-04-11T13:11:09.735Z,1523452269.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-04-11T13:11:09.735Z,1523452269.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-04-11T13:11:09.740Z,1523452269.740 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-04-11T13:11:09.815Z,1523452269.815 [MissionManager](DEBUG):
2018-04-11T13:11:09.825Z,1523452269.825 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-04-11T13:11:09.849Z,1523452269.849 [CTD_Seabird](INFO): LCM OK
2018-04-11T13:11:09.849Z,1523452269.849 [CTD_Seabird](INFO): Powering up
2018-04-11T13:11:09.849Z,1523452269.849 [Rowe_600LCM](INFO): LCM OK
2018-04-11T13:11:09.849Z,1523452269.849 [Rowe_600LCM](INFO): Powering up
2018-04-11T13:11:09.898Z,1523452269.898 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-04-11T13:11:09.899Z,1523452269.899 [Default:A.Wait](DEBUG): Construct Wait.
2018-04-11T13:11:09.901Z,1523452269.901 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-04-11T13:11:09.932Z,1523452269.932 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-04-11T13:11:09.950Z,1523452269.950 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-04-11T13:11:09.956Z,1523452269.956 [Default:E.Execute](DEBUG): Construct Execute.
2018-04-11T13:11:09.971Z,1523452269.971 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2018-04-11T13:11:09.977Z,1523452269.977 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-04-11T13:11:10.005Z,1523452270.005 [AHRS_M2](INFO): Start
2018-04-11T13:11:10.005Z,1523452270.005 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-04-11T13:11:10.023Z,1523452270.023 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-04-11T13:11:10.024Z,1523452270.024 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-04-11T13:11:10.047Z,1523452270.047 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-04-11T13:11:10.379Z,1523452270.379 [ESPComponent](INFO): powering down ESP
2018-04-11T13:11:10.794Z,1523452270.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:10.893Z,1523452270.893 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:10.989Z,1523452270.989 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-04-11T13:11:10.997Z,1523452270.997 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-04-11T13:11:11.011Z,1523452271.011 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-04-11T13:11:11.017Z,1523452271.017 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-04-11T13:11:11.023Z,1523452271.023 [MassServo](DEBUG): Initializing EZServoServo.
2018-04-11T13:11:11.029Z,1523452271.029 [MassServo](DEBUG): Initializing MassServo.
2018-04-11T13:11:11.035Z,1523452271.035 [RudderServo](DEBUG): Initializing EZServoServo.
2018-04-11T13:11:11.041Z,1523452271.041 [RudderServo](DEBUG): Initializing RudderServo.
2018-04-11T13:11:11.046Z,1523452271.046 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-04-11T13:11:11.053Z,1523452271.053 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-04-11T13:11:11.117Z,1523452271.117 [CommandLine](FAULT): Scheduling is paused
2018-04-11T13:11:11.118Z,1523452271.118 [CBIT](INFO): Critical error at 20180411T131109
2018-04-11T13:11:11.118Z,1523452271.118 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-04-11T13:11:11.217Z,1523452271.217 [AHRS_M2](INFO): Starting
2018-04-11T13:11:11.581Z,1523452271.581 [AHRS_M2](ERROR): readHeadingMagBin UART error: serial timeout
2018-04-11T13:11:11.581Z,1523452271.581 [AHRS_M2](FAULT): AHRS_M2 failed to initialize
2018-04-11T13:11:11.581Z,1523452271.581 [AHRS_M2] Hardware Fault, FailCount= 1
2018-04-11T13:11:11.581Z,1523452271.581 [AHRS_M2](ERROR): Hardware Fault
2018-04-11T13:11:11.586Z,1523452271.586 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-04-11T13:11:11.586Z,1523452271.586 [DropWeight] Hardware Fault, FailCount= 1
2018-04-11T13:11:11.586Z,1523452271.586 [DropWeight](ERROR): Hardware Fault
2018-04-11T13:11:11.673Z,1523452271.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:11.674Z,1523452271.674 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:12.021Z,1523452272.021 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-04-11T13:11:12.021Z,1523452272.021 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-04-11T13:11:12.021Z,1523452272.021 [BuoyancyServo] Communications Fault, FailCount= 1
2018-04-11T13:11:12.021Z,1523452272.021 [BuoyancyServo](ERROR): Communications Fault
2018-04-11T13:11:12.226Z,1523452272.226 [CBIT](INFO): Critical error at 20180411T131111
2018-04-11T13:11:12.229Z,1523452272.229 [CBIT](ERROR): Hardware Fault in component: AHRS_M2
2018-04-11T13:11:12.229Z,1523452272.229 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-04-11T13:11:12.229Z,1523452272.229 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-04-11T13:11:12.230Z,1523452272.230 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-04-11T13:11:12.235Z,1523452272.235 [AHRS_M2](INFO): Stop
2018-04-11T13:11:12.235Z,1523452272.235 [AHRS_M2](INFO): uninitialize
2018-04-11T13:11:12.235Z,1523452272.235 [AHRS_M2](INFO): Powering down
2018-04-11T13:11:12.372Z,1523452272.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:12.374Z,1523452272.374 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:12.394Z,1523452272.394 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-04-11T13:11:12.395Z,1523452272.395 [BuoyancyServo](INFO): Powering down
2018-04-11T13:11:12.476Z,1523452272.476 [CBIT](INFO): Critical error at 20180411T131112
2018-04-11T13:11:12.637Z,1523452272.637 [AHRS_M2](INFO): Stopping
2018-04-11T13:11:12.701Z,1523452272.701 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:12.702Z,1523452272.702 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.037Z,1523452273.037 [AHRS_M2](INFO): Stopping
2018-04-11T13:11:13.095Z,1523452273.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.096Z,1523452273.096 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.437Z,1523452273.437 [AHRS_M2](INFO): Stopping
2018-04-11T13:11:13.530Z,1523452273.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.532Z,1523452273.532 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.774Z,1523452273.774 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-04-11T13:11:13.837Z,1523452273.837 [AHRS_M2](INFO): Stopped
2018-04-11T13:11:13.874Z,1523452273.874 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.875Z,1523452273.875 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:13.884Z,1523452273.884 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-04-11T13:11:13.955Z,1523452273.955 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-04-11T13:11:13.955Z,1523452273.955 [AHRS_M2] No Fault, FailCount= 1
2018-04-11T13:11:14.054Z,1523452274.054 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-04-11T13:11:14.055Z,1523452274.055 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-04-11T13:11:14.056Z,1523452274.056 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-04-11T13:11:14.237Z,1523452274.237 [AHRS_M2](INFO): Start
2018-04-11T13:11:14.237Z,1523452274.237 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-04-11T13:11:14.317Z,1523452274.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:14.318Z,1523452274.318 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:14.637Z,1523452274.637 [AHRS_M2](INFO): Starting
2018-04-11T13:11:14.681Z,1523452274.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:14.683Z,1523452274.683 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:14.855Z,1523452274.855 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-04-11T13:11:14.856Z,1523452274.856 [BuoyancyServo] No Fault, FailCount= 1
2018-04-11T13:11:15.006Z,1523452275.006 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-04-11T13:11:15.037Z,1523452275.037 [AHRS_M2](INFO): Starting
2018-04-11T13:11:15.093Z,1523452275.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:15.094Z,1523452275.094 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:15.114Z,1523452275.114 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-04-11T13:11:15.114Z,1523452275.114 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-04-11T13:11:15.147Z,1523452275.147 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-04-11T13:11:15.149Z,1523452275.149 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-04-11T13:11:15.437Z,1523452275.437 [AHRS_M2](INFO): Starting
2018-04-11T13:11:15.504Z,1523452275.504 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-04-11T13:11:15.504Z,1523452275.504 [CTD_Seabird](INFO): Powering down
2018-04-11T13:11:15.777Z,1523452275.777 [AHRS_M2](ERROR): readHeadingMagBin UART error: serial timeout
2018-04-11T13:11:15.777Z,1523452275.777 [AHRS_M2](FAULT): AHRS_M2 failed to initialize
2018-04-11T13:11:15.777Z,1523452275.777 [AHRS_M2] Hardware Fault, FailCount= 2
2018-04-11T13:11:15.777Z,1523452275.777 [AHRS_M2](ERROR): Hardware Fault
2018-04-11T13:11:15.847Z,1523452275.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:15.848Z,1523452275.848 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:15.989Z,1523452275.989 [CBIT](ERROR): Hardware Fault in component: AHRS_M2
2018-04-11T13:11:15.995Z,1523452275.995 [AHRS_M2](INFO): Stop
2018-04-11T13:11:15.995Z,1523452275.995 [AHRS_M2](INFO): uninitialize
2018-04-11T13:11:15.995Z,1523452275.995 [AHRS_M2](INFO): Powering down
2018-04-11T13:11:16.099Z,1523452276.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:16.100Z,1523452276.100 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:16.397Z,1523452276.397 [AHRS_M2](INFO): Stopping
2018-04-11T13:11:16.457Z,1523452276.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:16.458Z,1523452276.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:16.797Z,1523452276.797 [AHRS_M2](INFO): Stopping
2018-04-11T13:11:16.857Z,1523452276.857 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:16.858Z,1523452276.858 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:17.197Z,1523452277.197 [AHRS_M2](INFO): Stopping
2018-04-11T13:11:17.261Z,1523452277.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:17.262Z,1523452277.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:17.597Z,1523452277.597 [AHRS_M2](INFO): Stopped
2018-04-11T13:11:17.598Z,1523452277.598 [AcousticModem_Benthos_ATM900](DEBUG):
2018-04-11T13:11:17.638Z,1523452277.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:17.639Z,1523452277.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:17.717Z,1523452277.717 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-04-11T13:11:17.717Z,1523452277.717 [AHRS_M2] No Fault, FailCount= 2
2018-04-11T13:11:17.997Z,1523452277.997 [AHRS_M2](INFO): Start
2018-04-11T13:11:17.997Z,1523452277.997 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-04-11T13:11:18.041Z,1523452278.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:18.042Z,1523452278.042 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:18.397Z,1523452278.397 [AHRS_M2](INFO): Starting
2018-04-11T13:11:18.462Z,1523452278.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:18.463Z,1523452278.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:18.801Z,1523452278.801 [AHRS_M2](INFO): Starting
2018-04-11T13:11:19.145Z,1523452279.145 [AHRS_M2](ERROR): readHeadingMagBin UART error: serial timeout
2018-04-11T13:11:19.145Z,1523452279.145 [AHRS_M2](FAULT): AHRS_M2 failed to initialize
2018-04-11T13:11:19.145Z,1523452279.145 [AHRS_M2] Hardware Fault, FailCount= 3
2018-04-11T13:11:19.145Z,1523452279.145 [AHRS_M2](ERROR): Hardware Fault
2018-04-11T13:11:19.147Z,1523452279.147 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-04-11T13:11:19.147Z,1523452279.147 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-04-11T13:11:19.148Z,1523452279.148 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-04-11T13:11:19.149Z,1523452279.149 [AcousticModem_Benthos_ATM900](DEBUG): Mar 27 2000 14:28:39
2018-04-11T13:11:19.199Z,1523452279.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:19.200Z,1523452279.200 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-11T13:11:19.283Z,1523452279.283 [CBIT](ERROR): Hardware Fault in component: AHRS_M2
2018-04-11T13:11:19.291Z,1523452279.291 [AHRS_M2](INFO): Stop
2018-04-11T13:11:19.291Z,1523452279.291 [AHRS_M2](INFO): uninitialize
2018-04-11T13:11:19.291Z,1523452279.291 [AHRS_M2](INFO): Powering down
2018-04-11T13:11:19.362Z,1523452279.362 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low