2018-05-29T16:47:53.152Z,1527612473.152 [Supervisor](DEBUG): Initializing supervisor.
2018-05-29T16:47:53.156Z,1527612473.156 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-29T16:47:53.156Z,1527612473.156 [SyncHandler](INFO): Protected caller Thread ID is 803
2018-05-29T16:47:53.158Z,1527612473.158 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-29T16:47:53.160Z,1527612473.160 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-29T16:47:53.161Z,1527612473.161 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804
2018-05-29T16:47:53.164Z,1527612473.164 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-29T16:47:53.175Z,1527612473.175 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-29T16:47:53.176Z,1527612473.176 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-29T16:47:53.177Z,1527612473.177 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805
2018-05-29T16:47:53.179Z,1527612473.179 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-29T16:47:53.181Z,1527612473.181 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-29T16:47:53.183Z,1527612473.183 [logger ThreadHandler](INFO): Protected caller Thread ID is 806
2018-05-29T16:47:53.186Z,1527612473.186 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-29T16:47:53.186Z,1527612473.186 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-29T16:47:53.191Z,1527612473.191 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-29T16:47:53.308Z,1527612473.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-29T16:47:53.309Z,1527612473.309 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-29T16:47:53.423Z,1527612473.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-29T16:47:53.425Z,1527612473.425 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-29T16:47:53.645Z,1527612473.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-29T16:47:53.647Z,1527612473.647 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-29T16:47:53.960Z,1527612473.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-29T16:47:53.961Z,1527612473.961 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-29T16:47:54.279Z,1527612474.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-29T16:47:54.279Z,1527612474.279 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-29T16:47:54.715Z,1527612474.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-29T16:47:54.717Z,1527612474.717 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-29T16:47:55.064Z,1527612475.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-29T16:47:55.064Z,1527612475.064 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-29T16:47:55.486Z,1527612475.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-29T16:47:55.487Z,1527612475.487 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-29T16:47:55.568Z,1527612475.568 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-29T16:47:55.851Z,1527612475.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-29T16:47:55.853Z,1527612475.853 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-29T16:47:55.993Z,1527612475.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-29T16:47:55.994Z,1527612475.994 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-29T16:47:56.196Z,1527612476.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-29T16:47:56.199Z,1527612476.199 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-29T16:47:56.405Z,1527612476.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-29T16:47:56.406Z,1527612476.406 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-29T16:47:57.102Z,1527612477.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-29T16:47:57.103Z,1527612477.103 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-29T16:47:57.239Z,1527612477.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-29T16:47:57.241Z,1527612477.241 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-29T16:47:57.244Z,1527612477.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-29T16:47:57.473Z,1527612477.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-29T16:47:57.475Z,1527612477.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-29T16:47:57.553Z,1527612477.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-29T16:47:57.690Z,1527612477.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-29T16:47:57.782Z,1527612477.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-29T16:47:57.866Z,1527612477.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-29T16:47:58.001Z,1527612478.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-29T16:47:58.170Z,1527612478.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-29T16:47:58.266Z,1527612478.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-29T16:47:58.357Z,1527612478.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-29T16:47:58.453Z,1527612478.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-29T16:47:58.536Z,1527612478.536 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-29T16:47:58.552Z,1527612478.552 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-29T16:47:58.676Z,1527612478.676 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-29T16:47:58.678Z,1527612478.678 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-29T16:47:58.702Z,1527612478.702 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-29T16:47:58.702Z,1527612478.702 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-29T16:47:58.759Z,1527612478.759 [DepthRateCalculator] Loaded
2018-05-29T16:47:58.759Z,1527612478.759 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-29T16:47:58.764Z,1527612478.764 [PitchRateCalculator] Loaded
2018-05-29T16:47:58.765Z,1527612478.765 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-29T16:47:58.780Z,1527612478.780 [SpeedCalculator] Loaded
2018-05-29T16:47:58.780Z,1527612478.780 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-29T16:47:58.801Z,1527612478.801 [TempGradientCalculator] Loaded
2018-05-29T16:47:58.801Z,1527612478.801 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-29T16:47:58.816Z,1527612478.816 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-29T16:47:58.816Z,1527612478.816 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-29T16:47:58.822Z,1527612478.822 [YawRateCalculator] Loaded
2018-05-29T16:47:58.822Z,1527612478.822 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-29T16:47:58.860Z,1527612478.860 [ElevatorOffsetCalculator] Loaded
2018-05-29T16:47:58.861Z,1527612478.861 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-29T16:47:58.861Z,1527612478.861 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-29T16:47:58.862Z,1527612478.862 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-29T16:47:58.898Z,1527612478.898 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-29T16:47:58.899Z,1527612478.899 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-29T16:47:59.400Z,1527612479.400 [AHRS_M2] Loaded
2018-05-29T16:47:59.400Z,1527612479.400 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-05-29T16:47:59.443Z,1527612479.443 [AcousticModem_Benthos_ATM900] Loaded
2018-05-29T16:47:59.443Z,1527612479.443 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-29T16:47:59.577Z,1527612479.577 [DataOverHttps] Loaded
2018-05-29T16:47:59.578Z,1527612479.578 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-29T16:47:59.591Z,1527612479.591 [Depth_Keller] Loaded
2018-05-29T16:47:59.591Z,1527612479.591 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-29T16:47:59.591Z,1527612479.591 [Module Loader](CRITICAL): Could not find element GobyModem.loadAtStartup
2018-05-29T16:47:59.684Z,1527612479.684 [NAL9602] Loaded
2018-05-29T16:47:59.684Z,1527612479.684 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-29T16:47:59.700Z,1527612479.700 [Onboard] Loaded
2018-05-29T16:47:59.700Z,1527612479.700 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-29T16:47:59.710Z,1527612479.710 [Radio_Surface] Loaded
2018-05-29T16:47:59.710Z,1527612479.710 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-29T16:47:59.711Z,1527612479.711 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-05-29T16:47:59.711Z,1527612479.711 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885
2018-05-29T16:47:59.712Z,1527612479.712 [Module Loader](CRITICAL): Could not find element RDI_Pathfinder.loadAtStartup
2018-05-29T16:48:01.404Z,1527612481.404 [BPC1] Loaded
2018-05-29T16:48:01.405Z,1527612481.405 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-29T16:48:01.405Z,1527612481.405 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-29T16:48:01.406Z,1527612481.406 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-29T16:48:01.881Z,1527612481.881 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-29T16:48:01.904Z,1527612481.904 [SBIT] Loaded
2018-05-29T16:48:01.904Z,1527612481.904 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-29T16:48:01.904Z,1527612481.904 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-29T16:48:01.916Z,1527612481.916 [IBIT] Loaded
2018-05-29T16:48:01.916Z,1527612481.916 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-29T16:48:01.919Z,1527612481.919 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-29T16:48:02.172Z,1527612482.172 [CBIT] Loaded
2018-05-29T16:48:02.172Z,1527612482.172 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-29T16:48:02.173Z,1527612482.173 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-29T16:48:02.173Z,1527612482.173 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-29T16:48:02.383Z,1527612482.383 [CTD_Seabird] Loaded
2018-05-29T16:48:02.383Z,1527612482.383 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-29T16:48:02.384Z,1527612482.384 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408774E0
2018-05-29T16:48:02.384Z,1527612482.384 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-05-29T16:48:02.401Z,1527612482.401 [ESPComponent] Loaded
2018-05-29T16:48:02.401Z,1527612482.401 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-29T16:48:02.415Z,1527612482.415 [PAR_Licor] Loaded
2018-05-29T16:48:02.415Z,1527612482.415 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-29T16:48:02.416Z,1527612482.416 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-29T16:48:02.418Z,1527612482.418 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-29T16:48:02.689Z,1527612482.689 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-29T16:48:02.690Z,1527612482.690 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-29T16:48:02.774Z,1527612482.774 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-29T16:48:02.867Z,1527612482.867 [VerticalControl] Loaded
2018-05-29T16:48:02.868Z,1527612482.868 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-29T16:48:02.868Z,1527612482.868 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-29T16:48:02.927Z,1527612482.927 [HorizontalControl] Loaded
2018-05-29T16:48:02.927Z,1527612482.927 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-29T16:48:02.928Z,1527612482.928 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-29T16:48:02.933Z,1527612482.933 [SpeedControl] Loaded
2018-05-29T16:48:02.933Z,1527612482.933 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-29T16:48:02.934Z,1527612482.934 [LoopControl](DEBUG): Construct LoopControl.
2018-05-29T16:48:02.934Z,1527612482.934 [LoopControl] Loaded
2018-05-29T16:48:02.935Z,1527612482.935 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-29T16:48:02.935Z,1527612482.935 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-29T16:48:02.936Z,1527612482.936 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-29T16:48:03.055Z,1527612483.055 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-29T16:48:03.055Z,1527612483.055 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-29T16:48:03.055Z,1527612483.055 [StratificationFrontDetector] Loaded
2018-05-29T16:48:03.055Z,1527612483.055 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-29T16:48:03.056Z,1527612483.056 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-29T16:48:03.057Z,1527612483.057 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-29T16:48:03.171Z,1527612483.171 [BuoyancyServo] Loaded
2018-05-29T16:48:03.172Z,1527612483.172 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-29T16:48:03.183Z,1527612483.183 [ElevatorServo] Loaded
2018-05-29T16:48:03.183Z,1527612483.183 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-29T16:48:03.194Z,1527612483.194 [MassServo] Loaded
2018-05-29T16:48:03.194Z,1527612483.194 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-29T16:48:03.205Z,1527612483.205 [RudderServo] Loaded
2018-05-29T16:48:03.206Z,1527612483.206 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-29T16:48:03.216Z,1527612483.216 [ThrusterServo] Loaded
2018-05-29T16:48:03.217Z,1527612483.217 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-29T16:48:03.217Z,1527612483.217 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-29T16:48:03.218Z,1527612483.218 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-29T16:48:03.328Z,1527612483.328 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-05-29T16:48:03.329Z,1527612483.329 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-05-29T16:48:03.381Z,1527612483.381 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-29T16:48:03.382Z,1527612483.382 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-29T16:48:03.398Z,1527612483.398 [NavChart] Loaded
2018-05-29T16:48:03.398Z,1527612483.398 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-29T16:48:03.402Z,1527612483.402 [UniversalFixResidualReporter] Loaded
2018-05-29T16:48:03.402Z,1527612483.402 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-29T16:48:03.403Z,1527612483.403 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-29T16:48:03.406Z,1527612483.406 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-29T16:48:03.407Z,1527612483.407 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-29T16:48:03.414Z,1527612483.414 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-29T16:48:03.415Z,1527612483.415 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2018-05-29T16:48:03.415Z,1527612483.415 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2018-05-29T16:48:03.420Z,1527612483.420 [Supervisor](INFO): Main Thread ID is 799
2018-05-29T16:48:03.420Z,1527612483.420 [Supervisor](DEBUG): Running supervisor.
2018-05-29T16:48:03.421Z,1527612483.421 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2018-05-29T16:48:03.423Z,1527612483.423 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2018-05-29T16:48:03.424Z,1527612483.424 [controlThread](DEBUG): Initializing ControlThread
2018-05-29T16:48:03.425Z,1527612483.425 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-29T16:48:03.425Z,1527612483.425 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-29T16:48:03.425Z,1527612483.425 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-29T16:48:03.426Z,1527612483.426 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-29T16:48:03.426Z,1527612483.426 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-29T16:48:03.427Z,1527612483.427 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-29T16:48:03.427Z,1527612483.427 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-29T16:48:03.430Z,1527612483.430 [SBIT](INFO): Initialize SBIT Component.
2018-05-29T16:48:03.431Z,1527612483.431 [SBIT](IMPORTANT): git: 2018-03-26-67-g62fca4c
2018-05-29T16:48:03.431Z,1527612483.431 [SBIT](INFO): git hash: 62fca4cdfc31339340ec3e9fddabc998ec27ce75
2018-05-29T16:48:03.431Z,1527612483.431 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-29T16:48:03.433Z,1527612483.433 [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-05-29T16:48:03.434Z,1527612483.434 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-05-29T16:48:03.434Z,1527612483.434 [IBIT](INFO): Initialize IBIT Component.
2018-05-29T16:48:03.435Z,1527612483.435 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-29T16:48:03.435Z,1527612483.435 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-05-29T16:48:03.436Z,1527612483.436 [logger ThreadHandler](INFO): Handler Thread ID is 890
2018-05-29T16:48:03.454Z,1527612483.454 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2018-05-29T16:48:03.459Z,1527612483.459 [Radio_Surface](INFO): Powering up
2018-05-29T16:48:03.466Z,1527612483.466 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892
2018-05-29T16:48:03.466Z,1527612483.466 [CTD_Seabird](INFO): Initializing
2018-05-29T16:48:03.467Z,1527612483.467 [CTD_Seabird](INFO): Checking LCM
2018-05-29T16:48:03.546Z,1527612483.546 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-29T16:48:03.547Z,1527612483.547 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-29T16:48:03.548Z,1527612483.548 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-29T16:48:03.548Z,1527612483.548 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-29T16:48:03.557Z,1527612483.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-29T16:48:03.558Z,1527612483.558 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-29T16:48:03.559Z,1527612483.559 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-29T16:48:03.559Z,1527612483.559 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-29T16:48:03.559Z,1527612483.559 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-29T16:48:03.560Z,1527612483.560 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-29T16:48:03.581Z,1527612483.581 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-29T16:48:03.582Z,1527612483.582 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-29T16:48:03.582Z,1527612483.582 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-29T16:48:03.593Z,1527612483.593 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893
2018-05-29T16:48:03.770Z,1527612483.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-29T16:48:03.770Z,1527612483.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-29T16:48:03.770Z,1527612483.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-29T16:48:03.770Z,1527612483.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-29T16:48:03.771Z,1527612483.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-29T16:48:03.771Z,1527612483.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-29T16:48:03.771Z,1527612483.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-29T16:48:03.771Z,1527612483.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-29T16:48:03.771Z,1527612483.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-29T16:48:03.772Z,1527612483.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-29T16:48:03.772Z,1527612483.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-29T16:48:03.772Z,1527612483.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-29T16:48:03.772Z,1527612483.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-29T16:48:03.772Z,1527612483.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-29T16:48:03.773Z,1527612483.773 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-29T16:48:03.773Z,1527612483.773 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-29T16:48:03.784Z,1527612483.784 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-29T16:48:03.865Z,1527612483.865 [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>


2018-05-29T16:48:03.873Z,1527612483.873 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-29T16:48:03.885Z,1527612483.885 [CTD_Seabird](INFO): LCM OK
2018-05-29T16:48:03.885Z,1527612483.885 [CTD_Seabird](INFO): Powering up
2018-05-29T16:48:03.940Z,1527612483.940 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-29T16:48:03.952Z,1527612483.952 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-29T16:48:03.954Z,1527612483.954 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-29T16:48:03.997Z,1527612483.997 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-29T16:48:04.019Z,1527612484.019 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-29T16:48:04.050Z,1527612484.050 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-29T16:48:04.054Z,1527612484.054 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2018-05-29T16:48:04.066Z,1527612484.066 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,BPC1,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-05-29T16:48:04.094Z,1527612484.094 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-29T16:48:04.231Z,1527612484.231 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-29T16:48:04.231Z,1527612484.231 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-29T16:48:04.256Z,1527612484.256 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-29T16:48:04.579Z,1527612484.579 [ESPComponent](INFO): powering down ESP
2018-05-29T16:48:04.983Z,1527612484.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:05.007Z,1527612485.007 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:05.122Z,1527612485.122 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-29T16:48:05.130Z,1527612485.130 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-29T16:48:05.144Z,1527612485.144 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-29T16:48:05.150Z,1527612485.150 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-29T16:48:05.163Z,1527612485.163 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-29T16:48:05.170Z,1527612485.170 [MassServo](DEBUG): Initializing MassServo.
2018-05-29T16:48:05.191Z,1527612485.191 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-29T16:48:05.198Z,1527612485.198 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-29T16:48:05.211Z,1527612485.211 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-29T16:48:05.217Z,1527612485.217 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-29T16:48:05.255Z,1527612485.255 [CommandLine](FAULT): Scheduling is paused
2018-05-29T16:48:05.255Z,1527612485.255 [CBIT](INFO): Critical error at 20180529T164759
2018-05-29T16:48:05.256Z,1527612485.256 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-29T16:48:05.473Z,1527612485.473 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:05.570Z,1527612485.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:05.571Z,1527612485.571 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:05.821Z,1527612485.821 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:05.877Z,1527612485.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:05.889Z,1527612485.889 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:06.357Z,1527612486.357 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:06.405Z,1527612486.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:06.406Z,1527612486.406 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:06.761Z,1527612486.761 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:06.809Z,1527612486.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:06.810Z,1527612486.810 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:07.161Z,1527612487.161 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:07.213Z,1527612487.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:07.222Z,1527612487.222 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:07.561Z,1527612487.561 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:07.637Z,1527612487.637 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:07.638Z,1527612487.638 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:07.961Z,1527612487.961 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:08.010Z,1527612488.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:08.011Z,1527612488.011 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:08.361Z,1527612488.361 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:08.425Z,1527612488.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:08.441Z,1527612488.441 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:08.761Z,1527612488.761 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:08.823Z,1527612488.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:08.825Z,1527612488.825 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:09.161Z,1527612489.161 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:09.194Z,1527612489.194 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-29T16:48:09.306Z,1527612489.306 [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-05-29T16:48:09.475Z,1527612489.475 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-29T16:48:09.856Z,1527612489.856 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-29T16:48:09.856Z,1527612489.856 [CTD_Seabird](INFO): Powering down
2018-05-29T16:48:17.824Z,1527612497.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:17.833Z,1527612497.833 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:18.265Z,1527612498.265 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:18.265Z,1527612498.265 [AHRS_M2](FAULT): Failed to initialize
2018-05-29T16:48:18.265Z,1527612498.265 [AHRS_M2] Communications Fault, FailCount= 1
2018-05-29T16:48:18.265Z,1527612498.265 [AHRS_M2](ERROR): Communications Fault
2018-05-29T16:48:18.267Z,1527612498.267 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-05-29T16:48:18.268Z,1527612498.268 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-29T16:48:18.269Z,1527612498.269 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-29T16:48:18.270Z,1527612498.270 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-29T16:48:18.270Z,1527612498.270 [AcousticModem_Benthos_ATM900](DEBUG): Apr  6 2000 19:09:23 
2018-05-29T16:48:18.271Z,1527612498.271 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-05-29T16:48:18.346Z,1527612498.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:18.347Z,1527612498.347 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:18.610Z,1527612498.610 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-29T16:48:18.737Z,1527612498.737 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2018-05-29T16:48:18.737Z,1527612498.737 [AHRS_M2](INFO): Powering down
2018-05-29T16:48:18.882Z,1527612498.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:18.883Z,1527612498.883 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:19.018Z,1527612499.018 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-05-29T16:48:19.025Z,1527612499.025 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004663
2018-05-29T16:48:19.094Z,1527612499.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:19.095Z,1527612499.095 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:19.418Z,1527612499.418 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-05-29T16:48:19.419Z,1527612499.419 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-05-29T16:48:19.419Z,1527612499.419 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-05-29T16:48:19.474Z,1527612499.474 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:19.475Z,1527612499.475 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:19.818Z,1527612499.818 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-05-29T16:48:19.895Z,1527612499.895 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:19.896Z,1527612499.896 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:20.218Z,1527612500.218 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-05-29T16:48:20.219Z,1527612500.219 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-05-29T16:48:20.298Z,1527612500.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:20.299Z,1527612500.299 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:20.386Z,1527612500.386 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-05-29T16:48:20.386Z,1527612500.386 [AHRS_M2] No Fault, FailCount= 1
2018-05-29T16:48:20.617Z,1527612500.617 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-29T16:48:20.626Z,1527612500.626 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-05-29T16:48:20.655Z,1527612500.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:20.656Z,1527612500.656 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:21.089Z,1527612501.089 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:21.090Z,1527612501.090 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:21.476Z,1527612501.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:21.477Z,1527612501.477 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:21.937Z,1527612501.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:21.979Z,1527612501.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:21.979Z,1527612501.979 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:22.337Z,1527612502.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:22.390Z,1527612502.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:22.391Z,1527612502.391 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:22.737Z,1527612502.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:22.799Z,1527612502.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:22.800Z,1527612502.800 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:23.137Z,1527612503.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:23.198Z,1527612503.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:23.199Z,1527612503.199 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:23.541Z,1527612503.541 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:23.572Z,1527612503.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:23.573Z,1527612503.573 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:23.937Z,1527612503.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:23.986Z,1527612503.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:23.987Z,1527612503.987 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:24.337Z,1527612504.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:24.378Z,1527612504.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:24.379Z,1527612504.379 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:24.737Z,1527612504.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:24.786Z,1527612504.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:24.787Z,1527612504.787 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:25.137Z,1527612505.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:25.186Z,1527612505.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:25.187Z,1527612505.187 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:25.537Z,1527612505.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:25.585Z,1527612505.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:25.587Z,1527612505.587 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:25.937Z,1527612505.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:25.937Z,1527612505.937 [AHRS_M2](FAULT): Failed to initialize
2018-05-29T16:48:25.938Z,1527612505.938 [AHRS_M2] Communications Fault, FailCount= 2
2018-05-29T16:48:25.938Z,1527612505.938 [AHRS_M2](ERROR): Communications Fault
2018-05-29T16:48:25.986Z,1527612505.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:25.987Z,1527612505.987 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:26.050Z,1527612506.050 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-29T16:48:26.337Z,1527612506.337 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2018-05-29T16:48:26.337Z,1527612506.337 [AHRS_M2](INFO): Powering down
2018-05-29T16:48:26.462Z,1527612506.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:26.463Z,1527612506.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:26.674Z,1527612506.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:26.675Z,1527612506.675 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.066Z,1527612507.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.067Z,1527612507.067 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.470Z,1527612507.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.471Z,1527612507.471 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.858Z,1527612507.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.859Z,1527612507.859 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:27.914Z,1527612507.914 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-05-29T16:48:27.914Z,1527612507.914 [AHRS_M2] No Fault, FailCount= 2
2018-05-29T16:48:28.229Z,1527612508.229 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-29T16:48:28.291Z,1527612508.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:28.292Z,1527612508.292 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:28.654Z,1527612508.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:28.655Z,1527612508.655 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:29.054Z,1527612509.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:29.055Z,1527612509.055 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:29.537Z,1527612509.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:29.609Z,1527612509.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:29.610Z,1527612509.610 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:29.937Z,1527612509.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:29.990Z,1527612509.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:29.991Z,1527612509.991 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:30.337Z,1527612510.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:30.405Z,1527612510.405 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:30.406Z,1527612510.406 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:30.737Z,1527612510.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:30.795Z,1527612510.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:30.796Z,1527612510.796 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:31.137Z,1527612511.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:31.186Z,1527612511.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:31.187Z,1527612511.187 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:31.537Z,1527612511.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:31.605Z,1527612511.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:31.606Z,1527612511.606 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:31.937Z,1527612511.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:32.009Z,1527612512.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:32.010Z,1527612512.010 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:32.341Z,1527612512.341 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:32.390Z,1527612512.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:32.391Z,1527612512.391 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:32.737Z,1527612512.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:32.801Z,1527612512.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:32.802Z,1527612512.802 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:33.141Z,1527612513.141 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:33.209Z,1527612513.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:33.210Z,1527612513.210 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:33.537Z,1527612513.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:33.537Z,1527612513.537 [AHRS_M2](FAULT): Failed to initialize
2018-05-29T16:48:33.537Z,1527612513.537 [AHRS_M2] Communications Fault, FailCount= 3
2018-05-29T16:48:33.538Z,1527612513.538 [AHRS_M2](ERROR): Communications Fault
2018-05-29T16:48:33.598Z,1527612513.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:33.599Z,1527612513.599 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:33.640Z,1527612513.640 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-29T16:48:33.941Z,1527612513.941 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2018-05-29T16:48:33.941Z,1527612513.941 [AHRS_M2](INFO): Powering down
2018-05-29T16:48:34.089Z,1527612514.089 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:34.090Z,1527612514.090 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:34.282Z,1527612514.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:34.283Z,1527612514.283 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:34.658Z,1527612514.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:34.659Z,1527612514.659 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:35.083Z,1527612515.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:35.084Z,1527612515.084 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:35.426Z,1527612515.426 [NAL9602](INFO): No messages in MT queue
2018-05-29T16:48:35.466Z,1527612515.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:35.467Z,1527612515.467 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:35.500Z,1527612515.500 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-05-29T16:48:35.500Z,1527612515.500 [AHRS_M2] No Fault, FailCount= 3
2018-05-29T16:48:35.820Z,1527612515.820 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-29T16:48:35.859Z,1527612515.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:35.860Z,1527612515.860 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:36.293Z,1527612516.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:36.294Z,1527612516.294 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:36.670Z,1527612516.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:36.671Z,1527612516.671 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:37.137Z,1527612517.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:37.196Z,1527612517.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:37.197Z,1527612517.197 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:37.537Z,1527612517.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:37.602Z,1527612517.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:37.603Z,1527612517.603 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:37.937Z,1527612517.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:37.993Z,1527612517.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:38.005Z,1527612518.005 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:38.337Z,1527612518.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:38.396Z,1527612518.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:38.401Z,1527612518.401 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:38.741Z,1527612518.741 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:38.817Z,1527612518.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:38.818Z,1527612518.818 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:39.137Z,1527612519.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:39.205Z,1527612519.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:39.206Z,1527612519.206 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:39.537Z,1527612519.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:39.594Z,1527612519.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:39.595Z,1527612519.595 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:39.937Z,1527612519.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:40.003Z,1527612520.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:40.004Z,1527612520.004 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:40.337Z,1527612520.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:40.396Z,1527612520.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:40.397Z,1527612520.397 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:40.737Z,1527612520.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:40.800Z,1527612520.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:40.801Z,1527612520.801 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:41.137Z,1527612521.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:41.137Z,1527612521.137 [AHRS_M2](FAULT): Failed to initialize
2018-05-29T16:48:41.137Z,1527612521.137 [AHRS_M2] Communications Fault, FailCount= 4
2018-05-29T16:48:41.137Z,1527612521.137 [AHRS_M2](ERROR): Communications Fault
2018-05-29T16:48:41.211Z,1527612521.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:41.212Z,1527612521.212 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:41.258Z,1527612521.258 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-29T16:48:41.537Z,1527612521.537 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2018-05-29T16:48:41.537Z,1527612521.537 [AHRS_M2](INFO): Powering down
2018-05-29T16:48:41.663Z,1527612521.663 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:41.664Z,1527612521.664 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:41.862Z,1527612521.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:41.863Z,1527612521.863 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:42.267Z,1527612522.267 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:42.268Z,1527612522.268 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:42.668Z,1527612522.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:42.674Z,1527612522.674 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:43.062Z,1527612523.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:43.063Z,1527612523.063 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:43.107Z,1527612523.107 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-05-29T16:48:43.107Z,1527612523.107 [AHRS_M2] No Fault, FailCount= 4
2018-05-29T16:48:43.417Z,1527612523.417 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-29T16:48:43.506Z,1527612523.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:43.507Z,1527612523.507 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:43.877Z,1527612523.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:43.878Z,1527612523.878 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:44.262Z,1527612524.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:44.263Z,1527612524.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:44.739Z,1527612524.739 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:44.782Z,1527612524.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:44.783Z,1527612524.783 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:45.137Z,1527612525.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:45.194Z,1527612525.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:45.195Z,1527612525.195 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:45.537Z,1527612525.537 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:45.583Z,1527612525.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:45.584Z,1527612525.584 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:45.937Z,1527612525.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:45.983Z,1527612525.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:45.984Z,1527612525.984 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:46.337Z,1527612526.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:46.398Z,1527612526.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:46.399Z,1527612526.399 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:46.737Z,1527612526.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:46.798Z,1527612526.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:46.799Z,1527612526.799 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:47.137Z,1527612527.137 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:47.195Z,1527612527.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:47.196Z,1527612527.196 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:47.529Z,1527612527.529 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:47.577Z,1527612527.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:47.578Z,1527612527.578 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:47.726Z,1527612527.726 [CommandLine](IMPORTANT): got command quit
2018-05-29T16:48:47.937Z,1527612527.937 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:47.982Z,1527612527.982 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:47.983Z,1527612527.983 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:48.337Z,1527612528.337 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:48.397Z,1527612528.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:48.418Z,1527612528.418 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:48.732Z,1527612528.732 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T16:48:48.732Z,1527612528.732 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:48.737Z,1527612528.737 [AHRS_M2](ERROR): setMountVertical UART error: serial timeout
2018-05-29T16:48:48.737Z,1527612528.737 [AHRS_M2](FAULT): Failed to initialize
2018-05-29T16:48:48.737Z,1527612528.737 [AHRS_M2] Communications Fault, FailCount= 5
2018-05-29T16:48:48.737Z,1527612528.737 [AHRS_M2](ERROR): Communications Fault
2018-05-29T16:48:48.798Z,1527612528.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:48.799Z,1527612528.799 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:48.842Z,1527612528.842 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-29T16:48:48.842Z,1527612528.842 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2018-05-29T16:48:48.909Z,1527612528.909 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-05-29T16:48:48.909Z,1527612528.909 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:48.910Z,1527612528.910 [CommandLine](INFO): Join timeout helper Thread ID is 913
2018-05-29T16:48:48.917Z,1527612528.917 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-05-29T16:48:48.917Z,1527612528.917 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:48.918Z,1527612528.918 [NavChartDb](INFO): Join timeout helper Thread ID is 914
2018-05-29T16:48:49.137Z,1527612529.137 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2018-05-29T16:48:49.137Z,1527612529.137 [AHRS_M2](INFO): Powering down
2018-05-29T16:48:49.184Z,1527612529.184 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T16:48:49.184Z,1527612529.184 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:49.186Z,1527612529.186 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-05-29T16:48:49.186Z,1527612529.186 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:49.186Z,1527612529.186 [CTD_Seabird](INFO): Join timeout helper Thread ID is 915
2018-05-29T16:48:49.246Z,1527612529.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:49.247Z,1527612529.247 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:49.255Z,1527612529.255 [CBIT](INFO): Critical error at 20180529T164848
2018-05-29T16:48:49.451Z,1527612529.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:49.452Z,1527612529.452 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:49.486Z,1527612529.486 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T16:48:49.580Z,1527612529.580 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-29T16:48:49.580Z,1527612529.580 [CTD_Seabird](INFO): Powering down
2018-05-29T16:48:49.581Z,1527612529.581 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:49.585Z,1527612529.585 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-05-29T16:48:49.585Z,1527612529.585 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:49.586Z,1527612529.586 [Radio_Surface](INFO): Join timeout helper Thread ID is 917
2018-05-29T16:48:49.852Z,1527612529.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:49.854Z,1527612529.854 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T16:48:49.957Z,1527612529.957 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T16:48:49.958Z,1527612529.958 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:49.965Z,1527612529.965 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-05-29T16:48:49.965Z,1527612529.965 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:49.966Z,1527612529.966 [logger](INFO): Join timeout helper Thread ID is 918
2018-05-29T16:48:50.005Z,1527612530.005 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T16:48:50.006Z,1527612530.006 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.026Z,1527612530.026 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-05-29T16:48:50.026Z,1527612530.026 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.026Z,1527612530.026 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-05-29T16:48:50.027Z,1527612530.027 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.027Z,1527612530.027 [controlThread](INFO): Join timeout helper Thread ID is 919
2018-05-29T16:48:50.213Z,1527612530.213 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T16:48:50.214Z,1527612530.214 [controlThread](DEBUG): Uninitializing ControlThread
2018-05-29T16:48:50.333Z,1527612530.333 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2018-05-29T16:48:50.333Z,1527612530.333 [AHRS_M2](INFO): Powering down
2018-05-29T16:48:50.334Z,1527612530.334 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-05-29T16:48:50.454Z,1527612530.454 [NAL9602](INFO): Powering down
2018-05-29T16:48:50.457Z,1527612530.457 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-29T16:48:50.458Z,1527612530.458 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-05-29T16:48:50.459Z,1527612530.459 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-05-29T16:48:50.459Z,1527612530.459 [MissionManager](INFO): Uninitializing Mission Default
2018-05-29T16:48:50.462Z,1527612530.462 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-29T16:48:50.463Z,1527612530.463 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-05-29T16:48:50.464Z,1527612530.464 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-05-29T16:48:50.464Z,1527612530.464 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-05-29T16:48:50.464Z,1527612530.464 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-05-29T16:48:50.464Z,1527612530.464 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-29T16:48:50.465Z,1527612530.465 [BuoyancyServo](INFO): Powering down
2018-05-29T16:48:50.477Z,1527612530.477 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-05-29T16:48:50.478Z,1527612530.478 [ElevatorServo](INFO): Powering down
2018-05-29T16:48:50.497Z,1527612530.497 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-05-29T16:48:50.497Z,1527612530.497 [MassServo](INFO): Powering down
2018-05-29T16:48:50.498Z,1527612530.498 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-05-29T16:48:50.498Z,1527612530.498 [RudderServo](INFO): Powering down
2018-05-29T16:48:50.499Z,1527612530.499 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-29T16:48:50.499Z,1527612530.499 [ThrusterServo](INFO): Powering down
2018-05-29T16:48:50.500Z,1527612530.500 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-05-29T16:48:50.501Z,1527612530.501 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-05-29T16:48:50.501Z,1527612530.501 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-05-29T16:48:50.502Z,1527612530.502 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.588Z,1527612530.588 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.629Z,1527612530.629 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.793Z,1527612530.793 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-29T16:48:50.839Z,1527612530.839 [logger ThreadHandler](INFO): Thread cancelled.