2018-05-04T05:51:34.344Z,1525413094.344 [Supervisor](DEBUG): Initializing supervisor.
2018-05-04T05:51:34.347Z,1525413094.347 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-04T05:51:34.347Z,1525413094.347 [SyncHandler](INFO): Protected caller Thread ID is 800
2018-05-04T05:51:34.348Z,1525413094.348 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-04T05:51:34.349Z,1525413094.349 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-04T05:51:34.349Z,1525413094.349 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 801
2018-05-04T05:51:34.351Z,1525413094.351 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-04T05:51:34.362Z,1525413094.362 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-04T05:51:34.363Z,1525413094.363 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-04T05:51:34.363Z,1525413094.363 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 802
2018-05-04T05:51:34.364Z,1525413094.364 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-04T05:51:34.365Z,1525413094.365 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-04T05:51:34.365Z,1525413094.365 [logger ThreadHandler](INFO): Protected caller Thread ID is 803
2018-05-04T05:51:34.367Z,1525413094.367 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-04T05:51:34.368Z,1525413094.368 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-04T05:51:34.372Z,1525413094.372 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-04T05:51:34.467Z,1525413094.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-04T05:51:34.468Z,1525413094.468 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-04T05:51:34.566Z,1525413094.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-04T05:51:34.567Z,1525413094.567 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-04T05:51:34.752Z,1525413094.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-04T05:51:34.752Z,1525413094.752 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-04T05:51:35.042Z,1525413095.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-04T05:51:35.044Z,1525413095.044 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-04T05:51:35.352Z,1525413095.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-04T05:51:35.354Z,1525413095.354 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-04T05:51:35.779Z,1525413095.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-04T05:51:35.781Z,1525413095.781 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-04T05:51:36.106Z,1525413096.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-04T05:51:36.107Z,1525413096.107 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-04T05:51:36.515Z,1525413096.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-04T05:51:36.515Z,1525413096.515 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-04T05:51:36.596Z,1525413096.596 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-04T05:51:36.873Z,1525413096.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-04T05:51:36.874Z,1525413096.874 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-04T05:51:37.013Z,1525413097.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-04T05:51:37.013Z,1525413097.013 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-04T05:51:37.209Z,1525413097.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-04T05:51:37.209Z,1525413097.209 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-04T05:51:37.409Z,1525413097.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-04T05:51:37.410Z,1525413097.410 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-04T05:51:37.506Z,1525413097.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-04T05:51:37.507Z,1525413097.507 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-04T05:51:37.642Z,1525413097.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-04T05:51:37.644Z,1525413097.644 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-04T05:51:37.648Z,1525413097.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-04T05:51:37.882Z,1525413097.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-04T05:51:37.882Z,1525413097.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-04T05:51:38.343Z,1525413098.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-04T05:51:38.478Z,1525413098.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-04T05:51:38.569Z,1525413098.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-04T05:51:38.653Z,1525413098.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-04T05:51:38.783Z,1525413098.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-04T05:51:38.939Z,1525413098.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-04T05:51:39.035Z,1525413099.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-04T05:51:39.125Z,1525413099.125 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-04T05:51:39.220Z,1525413099.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-04T05:51:39.302Z,1525413099.302 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-05-04T05:51:39.307Z,1525413099.307 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-04T05:51:39.430Z,1525413099.430 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-04T05:51:39.431Z,1525413099.431 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-04T05:51:39.454Z,1525413099.454 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-04T05:51:39.455Z,1525413099.455 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-04T05:51:39.509Z,1525413099.509 [DepthRateCalculator] Loaded
2018-05-04T05:51:39.510Z,1525413099.510 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-04T05:51:39.515Z,1525413099.515 [PitchRateCalculator] Loaded
2018-05-04T05:51:39.516Z,1525413099.516 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-04T05:51:39.531Z,1525413099.531 [SpeedCalculator] Loaded
2018-05-04T05:51:39.531Z,1525413099.531 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-04T05:51:39.551Z,1525413099.551 [TempGradientCalculator] Loaded
2018-05-04T05:51:39.551Z,1525413099.551 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-04T05:51:39.566Z,1525413099.566 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-04T05:51:39.566Z,1525413099.566 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-04T05:51:39.572Z,1525413099.572 [YawRateCalculator] Loaded
2018-05-04T05:51:39.572Z,1525413099.572 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-04T05:51:39.610Z,1525413099.610 [ElevatorOffsetCalculator] Loaded
2018-05-04T05:51:39.610Z,1525413099.610 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-04T05:51:39.610Z,1525413099.610 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-04T05:51:39.611Z,1525413099.611 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-04T05:51:39.647Z,1525413099.647 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-04T05:51:39.648Z,1525413099.648 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-04T05:51:40.089Z,1525413100.089 [AcousticModem_Benthos_ATM900] Loaded
2018-05-04T05:51:40.090Z,1525413100.090 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-04T05:51:40.226Z,1525413100.226 [DataOverHttps] Loaded
2018-05-04T05:51:40.226Z,1525413100.226 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-04T05:51:40.239Z,1525413100.239 [Depth_Keller] Loaded
2018-05-04T05:51:40.239Z,1525413100.239 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-04T05:51:40.244Z,1525413100.244 [DropWeight] Loaded
2018-05-04T05:51:40.244Z,1525413100.244 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-05-04T05:51:40.338Z,1525413100.338 [NAL9602] Loaded
2018-05-04T05:51:40.338Z,1525413100.338 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-04T05:51:40.353Z,1525413100.353 [Onboard] Loaded
2018-05-04T05:51:40.353Z,1525413100.353 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-04T05:51:40.363Z,1525413100.363 [Radio_Surface] Loaded
2018-05-04T05:51:40.363Z,1525413100.363 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-04T05:51:40.364Z,1525413100.364 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A64E0
2018-05-04T05:51:40.365Z,1525413100.365 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-05-04T05:51:40.515Z,1525413100.515 [PNI_TCM] Loaded
2018-05-04T05:51:40.515Z,1525413100.515 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-04T05:51:40.573Z,1525413100.573 [Rowe_600LCM] Loaded
2018-05-04T05:51:40.573Z,1525413100.573 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-05-04T05:51:40.574Z,1525413100.574 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D64E0
2018-05-04T05:51:40.574Z,1525413100.574 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 883
2018-05-04T05:51:42.263Z,1525413102.263 [BPC1] Loaded
2018-05-04T05:51:42.263Z,1525413102.263 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-04T05:51:42.264Z,1525413102.264 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-04T05:51:42.266Z,1525413102.266 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-04T05:51:42.412Z,1525413102.412 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-04T05:51:42.434Z,1525413102.434 [SBIT] Loaded
2018-05-04T05:51:42.435Z,1525413102.435 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-04T05:51:42.435Z,1525413102.435 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-04T05:51:42.446Z,1525413102.446 [IBIT] Loaded
2018-05-04T05:51:42.447Z,1525413102.447 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-04T05:51:42.450Z,1525413102.450 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-04T05:51:42.594Z,1525413102.594 [CBIT] Loaded
2018-05-04T05:51:42.594Z,1525413102.594 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-04T05:51:42.594Z,1525413102.594 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-04T05:51:42.595Z,1525413102.595 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-04T05:51:42.775Z,1525413102.775 [Aanderaa_O2] Loaded
2018-05-04T05:51:42.775Z,1525413102.775 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-04T05:51:42.821Z,1525413102.821 [CTD_Seabird] Loaded
2018-05-04T05:51:42.822Z,1525413102.822 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-04T05:51:42.823Z,1525413102.823 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408964E0
2018-05-04T05:51:42.823Z,1525413102.823 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 884
2018-05-04T05:51:42.839Z,1525413102.839 [ESPComponent] Loaded
2018-05-04T05:51:42.839Z,1525413102.839 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-04T05:51:42.853Z,1525413102.853 [PAR_Licor] Loaded
2018-05-04T05:51:42.853Z,1525413102.853 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-04T05:51:42.900Z,1525413102.900 [WetLabsBB2FL] Loaded
2018-05-04T05:51:42.900Z,1525413102.900 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-04T05:51:42.901Z,1525413102.901 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2018-05-04T05:51:42.901Z,1525413102.901 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 885
2018-05-04T05:51:42.902Z,1525413102.902 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-04T05:51:42.903Z,1525413102.903 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-04T05:51:43.877Z,1525413103.877 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-04T05:51:43.879Z,1525413103.879 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-04T05:51:43.964Z,1525413103.964 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-04T05:51:44.096Z,1525413104.096 [VerticalControl] Loaded
2018-05-04T05:51:44.097Z,1525413104.097 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-04T05:51:44.097Z,1525413104.097 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-04T05:51:44.156Z,1525413104.156 [HorizontalControl] Loaded
2018-05-04T05:51:44.156Z,1525413104.156 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-04T05:51:44.157Z,1525413104.157 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-04T05:51:44.162Z,1525413104.162 [SpeedControl] Loaded
2018-05-04T05:51:44.162Z,1525413104.162 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-04T05:51:44.163Z,1525413104.163 [LoopControl](DEBUG): Construct LoopControl.
2018-05-04T05:51:44.163Z,1525413104.163 [LoopControl] Loaded
2018-05-04T05:51:44.164Z,1525413104.164 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-04T05:51:44.164Z,1525413104.164 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-04T05:51:44.165Z,1525413104.165 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-04T05:51:44.282Z,1525413104.282 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-04T05:51:44.282Z,1525413104.282 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-04T05:51:44.283Z,1525413104.283 [StratificationFrontDetector] Loaded
2018-05-04T05:51:44.283Z,1525413104.283 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-04T05:51:44.283Z,1525413104.283 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-04T05:51:44.284Z,1525413104.284 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-04T05:51:44.399Z,1525413104.399 [BuoyancyServo] Loaded
2018-05-04T05:51:44.399Z,1525413104.399 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-04T05:51:44.410Z,1525413104.410 [ElevatorServo] Loaded
2018-05-04T05:51:44.411Z,1525413104.411 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-04T05:51:44.421Z,1525413104.421 [MassServo] Loaded
2018-05-04T05:51:44.421Z,1525413104.421 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-04T05:51:44.432Z,1525413104.432 [RudderServo] Loaded
2018-05-04T05:51:44.432Z,1525413104.432 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-04T05:51:44.443Z,1525413104.443 [ThrusterServo] Loaded
2018-05-04T05:51:44.443Z,1525413104.443 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-04T05:51:44.443Z,1525413104.443 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-04T05:51:44.444Z,1525413104.444 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-04T05:51:44.553Z,1525413104.553 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-05-04T05:51:44.554Z,1525413104.554 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-05-04T05:51:44.607Z,1525413104.607 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-04T05:51:44.607Z,1525413104.607 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-04T05:51:44.623Z,1525413104.623 [NavChart] Loaded
2018-05-04T05:51:44.623Z,1525413104.623 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-04T05:51:44.627Z,1525413104.627 [UniversalFixResidualReporter] Loaded
2018-05-04T05:51:44.627Z,1525413104.627 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-04T05:51:44.628Z,1525413104.628 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-04T05:51:44.631Z,1525413104.631 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-04T05:51:44.632Z,1525413104.632 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-04T05:51:44.638Z,1525413104.638 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-04T05:51:44.639Z,1525413104.639 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0
2018-05-04T05:51:44.639Z,1525413104.639 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 887
2018-05-04T05:51:44.644Z,1525413104.644 [Supervisor](INFO): Main Thread ID is 796
2018-05-04T05:51:44.644Z,1525413104.644 [Supervisor](DEBUG): Running supervisor.
2018-05-04T05:51:44.645Z,1525413104.645 [CommandLine ThreadHandler](INFO): Handler Thread ID is 888
2018-05-04T05:51:44.647Z,1525413104.647 [controlThread ThreadHandler](INFO): Handler Thread ID is 889
2018-05-04T05:51:44.648Z,1525413104.648 [controlThread](DEBUG): Initializing ControlThread
2018-05-04T05:51:44.648Z,1525413104.648 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-04T05:51:44.649Z,1525413104.649 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-04T05:51:44.649Z,1525413104.649 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-04T05:51:44.649Z,1525413104.649 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-04T05:51:44.650Z,1525413104.650 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-04T05:51:44.651Z,1525413104.651 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-04T05:51:44.651Z,1525413104.651 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-04T05:51:44.655Z,1525413104.655 [SBIT](INFO): Initialize SBIT Component.
2018-05-04T05:51:44.655Z,1525413104.655 [SBIT](IMPORTANT): git: 2018-03-26-2-gc06366e
2018-05-04T05:51:44.656Z,1525413104.656 [SBIT](INFO): git hash: c06366e488986061b2c97b8bb696d868dcd187c8
2018-05-04T05:51:44.656Z,1525413104.656 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-04T05:51:44.657Z,1525413104.657 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #16 PREEMPT Thu Apr 12 16:20:47 PDT 2018
2018-05-04T05:51:44.658Z,1525413104.658 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-05-04T05:51:44.659Z,1525413104.659 [IBIT](INFO): Initialize IBIT Component.
2018-05-04T05:51:44.659Z,1525413104.659 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-04T05:51:44.660Z,1525413104.660 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-05-04T05:51:44.660Z,1525413104.660 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-05-04T05:51:44.661Z,1525413104.661 [logger ThreadHandler](INFO): Handler Thread ID is 890
2018-05-04T05:51:44.681Z,1525413104.681 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 891
2018-05-04T05:51:44.687Z,1525413104.687 [Radio_Surface](INFO): Powering up
2018-05-04T05:51:44.693Z,1525413104.693 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 892
2018-05-04T05:51:44.694Z,1525413104.694 [Rowe_600LCM](INFO): Initializing
2018-05-04T05:51:44.694Z,1525413104.694 [Rowe_600LCM](INFO): Checking LCM
2018-05-04T05:51:44.793Z,1525413104.793 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-04T05:51:44.795Z,1525413104.795 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-04T05:51:44.795Z,1525413104.795 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-04T05:51:44.796Z,1525413104.796 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-04T05:51:44.797Z,1525413104.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-04T05:51:44.798Z,1525413104.798 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-04T05:51:44.798Z,1525413104.798 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-04T05:51:44.799Z,1525413104.799 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-04T05:51:44.799Z,1525413104.799 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-04T05:51:44.799Z,1525413104.799 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-04T05:51:44.800Z,1525413104.800 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-04T05:51:44.800Z,1525413104.800 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-04T05:51:44.801Z,1525413104.801 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-04T05:51:44.812Z,1525413104.812 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 893
2018-05-04T05:51:44.813Z,1525413104.813 [CTD_Seabird](INFO): Initializing
2018-05-04T05:51:44.813Z,1525413104.813 [CTD_Seabird](INFO): Checking LCM
2018-05-04T05:51:44.914Z,1525413104.914 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 894
2018-05-04T05:51:44.921Z,1525413104.921 [WetLabsBB2FL](INFO): Powering down
2018-05-04T05:51:44.940Z,1525413104.940 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-04T05:51:45.003Z,1525413105.003 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895
2018-05-04T05:51:45.010Z,1525413105.010 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-04T05:51:45.010Z,1525413105.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-04T05:51:45.011Z,1525413105.011 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-04T05:51:45.011Z,1525413105.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-04T05:51:45.011Z,1525413105.011 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-04T05:51:45.011Z,1525413105.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-04T05:51:45.011Z,1525413105.011 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-04T05:51:45.011Z,1525413105.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-04T05:51:45.012Z,1525413105.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-04T05:51:45.012Z,1525413105.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-04T05:51:45.012Z,1525413105.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-04T05:51:45.012Z,1525413105.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-04T05:51:45.012Z,1525413105.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-04T05:51:45.013Z,1525413105.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-04T05:51:45.013Z,1525413105.013 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-04T05:51:45.013Z,1525413105.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-04T05:51:45.039Z,1525413105.039 [MissionManager](DEBUG):
2018-05-04T05:51:45.039Z,1525413105.039 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-04T05:51:45.124Z,1525413105.124 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-04T05:51:45.140Z,1525413105.140 [CTD_Seabird](INFO): LCM OK
2018-05-04T05:51:45.140Z,1525413105.140 [CTD_Seabird](INFO): Powering up
2018-05-04T05:51:45.140Z,1525413105.140 [Rowe_600LCM](INFO): LCM OK
2018-05-04T05:51:45.140Z,1525413105.140 [Rowe_600LCM](INFO): Powering up
2018-05-04T05:51:45.153Z,1525413105.153 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-04T05:51:45.155Z,1525413105.155 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-04T05:51:45.185Z,1525413105.185 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-04T05:51:45.187Z,1525413105.187 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-04T05:51:45.229Z,1525413105.229 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-04T05:51:45.232Z,1525413105.232 [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-05-04T05:51:45.250Z,1525413105.250 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,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-05-04T05:51:45.279Z,1525413105.279 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-04T05:51:45.279Z,1525413105.279 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-04T05:51:45.303Z,1525413105.303 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-04T05:51:45.664Z,1525413105.664 [ESPComponent](INFO): powering down ESP
2018-05-04T05:51:46.169Z,1525413106.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:46.262Z,1525413106.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:46.381Z,1525413106.381 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-04T05:51:46.390Z,1525413106.390 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-04T05:51:46.412Z,1525413106.412 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-04T05:51:46.417Z,1525413106.417 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-04T05:51:46.438Z,1525413106.438 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-04T05:51:46.449Z,1525413106.449 [MassServo](DEBUG): Initializing MassServo.
2018-05-04T05:51:46.454Z,1525413106.454 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-04T05:51:46.462Z,1525413106.462 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-04T05:51:46.493Z,1525413106.493 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-04T05:51:46.501Z,1525413106.501 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-04T05:51:46.546Z,1525413106.546 [CommandLine](FAULT): Scheduling is paused
2018-05-04T05:51:46.546Z,1525413106.546 [CBIT](INFO): Critical error at 20180504T055144
2018-05-04T05:51:46.547Z,1525413106.547 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-04T05:51:46.662Z,1525413106.662 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-05-04T05:51:46.662Z,1525413106.662 [DropWeight] Hardware Fault, FailCount= 1
2018-05-04T05:51:46.662Z,1525413106.662 [DropWeight](ERROR): Hardware Fault
2018-05-04T05:51:46.711Z,1525413106.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:46.712Z,1525413106.712 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:46.753Z,1525413106.753 [CBIT](INFO): Critical error at 20180504T055146
2018-05-04T05:51:46.755Z,1525413106.755 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-05-04T05:51:46.756Z,1525413106.756 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-05-04T05:51:47.033Z,1525413107.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:47.034Z,1525413107.034 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:47.373Z,1525413107.373 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-05-04T05:51:47.373Z,1525413107.373 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-05-04T05:51:47.373Z,1525413107.373 [BuoyancyServo] Communications Fault, FailCount= 1
2018-05-04T05:51:47.373Z,1525413107.373 [BuoyancyServo](ERROR): Communications Fault
2018-05-04T05:51:47.578Z,1525413107.578 [CBIT](INFO): Critical error at 20180504T055146
2018-05-04T05:51:47.581Z,1525413107.581 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-05-04T05:51:47.638Z,1525413107.638 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:47.639Z,1525413107.639 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:47.644Z,1525413107.644 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-04T05:51:47.653Z,1525413107.653 [BuoyancyServo](INFO): Powering down
2018-05-04T05:51:48.069Z,1525413108.069 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:48.070Z,1525413108.070 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:48.440Z,1525413108.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:48.445Z,1525413108.445 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:48.878Z,1525413108.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:48.879Z,1525413108.879 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:49.223Z,1525413109.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:49.224Z,1525413109.224 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:49.455Z,1525413109.455 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-04T05:51:49.520Z,1525413109.520 [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-05-04T05:51:49.628Z,1525413109.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:49.641Z,1525413109.641 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:49.746Z,1525413109.746 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-05-04T05:51:49.746Z,1525413109.746 [BuoyancyServo] No Fault, FailCount= 1
2018-05-04T05:51:49.771Z,1525413109.771 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-04T05:51:49.772Z,1525413109.772 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-04T05:51:49.772Z,1525413109.772 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-04T05:51:50.106Z,1525413110.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:50.107Z,1525413110.107 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:50.143Z,1525413110.143 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-04T05:51:50.144Z,1525413110.144 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-04T05:51:50.306Z,1525413110.306 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-04T05:51:50.427Z,1525413110.427 [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-04T05:51:50.429Z,1525413110.429 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-04T05:51:50.468Z,1525413110.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:50.485Z,1525413110.485 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:50.846Z,1525413110.846 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-04T05:51:50.846Z,1525413110.846 [CTD_Seabird](INFO): Powering down
2018-05-04T05:51:50.883Z,1525413110.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:50.884Z,1525413110.884 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:51.232Z,1525413111.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:51.270Z,1525413111.270 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:51.630Z,1525413111.630 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:51.631Z,1525413111.631 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:52.049Z,1525413112.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:52.050Z,1525413112.050 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:52.457Z,1525413112.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:52.458Z,1525413112.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:52.789Z,1525413112.789 [AcousticModem_Benthos_ATM900](DEBUG):
2018-05-04T05:51:52.834Z,1525413112.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:52.835Z,1525413112.835 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:53.211Z,1525413113.211 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-04T05:51:53.211Z,1525413113.211 [PNI_TCM] Data Fault, FailCount= 1
2018-05-04T05:51:53.211Z,1525413113.211 [PNI_TCM](ERROR): Data Fault
2018-05-04T05:51:53.261Z,1525413113.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:53.262Z,1525413113.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:53.370Z,1525413113.370 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-04T05:51:53.623Z,1525413113.623 [PNI_TCM](INFO): Powering down
2018-05-04T05:51:53.688Z,1525413113.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:53.693Z,1525413113.693 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:54.049Z,1525413114.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:54.050Z,1525413114.050 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:54.390Z,1525413114.390 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-04T05:51:54.391Z,1525413114.391 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-04T05:51:54.391Z,1525413114.391 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-04T05:51:54.392Z,1525413114.392 [AcousticModem_Benthos_ATM900](DEBUG): Apr 1 2000 02:44:52
2018-05-04T05:51:54.435Z,1525413114.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:54.436Z,1525413114.436 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-04T05:51:54.789Z,1525413114.789 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low