2018-03-14T21:02:28.986Z,1521061348.986 [Supervisor](DEBUG): Initializing supervisor.
2018-03-14T21:02:28.989Z,1521061348.989 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-03-14T21:02:28.989Z,1521061348.989 [SyncHandler](INFO): Protected caller Thread ID is 804
2018-03-14T21:02:28.990Z,1521061348.990 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-03-14T21:02:28.991Z,1521061348.991 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-03-14T21:02:28.991Z,1521061348.991 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 805
2018-03-14T21:02:28.994Z,1521061348.994 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-03-14T21:02:29.006Z,1521061349.006 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-03-14T21:02:29.007Z,1521061349.007 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-03-14T21:02:29.007Z,1521061349.007 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 806
2018-03-14T21:02:29.008Z,1521061349.008 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-03-14T21:02:29.009Z,1521061349.009 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-03-14T21:02:29.009Z,1521061349.009 [logger ThreadHandler](INFO): Protected caller Thread ID is 807
2018-03-14T21:02:29.011Z,1521061349.011 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-03-14T21:02:29.012Z,1521061349.012 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-03-14T21:02:29.015Z,1521061349.015 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-03-14T21:02:29.111Z,1521061349.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-03-14T21:02:29.113Z,1521061349.113 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-03-14T21:02:29.212Z,1521061349.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-03-14T21:02:29.213Z,1521061349.213 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-03-14T21:02:29.404Z,1521061349.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-03-14T21:02:29.406Z,1521061349.406 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-03-14T21:02:29.700Z,1521061349.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-03-14T21:02:29.701Z,1521061349.701 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-03-14T21:02:30.029Z,1521061350.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-03-14T21:02:30.030Z,1521061350.030 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-03-14T21:02:30.472Z,1521061350.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-03-14T21:02:30.472Z,1521061350.472 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-03-14T21:02:30.798Z,1521061350.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-03-14T21:02:30.800Z,1521061350.800 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-03-14T21:02:31.218Z,1521061351.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-03-14T21:02:31.219Z,1521061351.219 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-03-14T21:02:31.300Z,1521061351.300 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-03-14T21:02:31.583Z,1521061351.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-03-14T21:02:31.584Z,1521061351.584 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-03-14T21:02:31.723Z,1521061351.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-03-14T21:02:31.724Z,1521061351.724 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-03-14T21:02:31.920Z,1521061351.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-03-14T21:02:31.921Z,1521061351.921 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-03-14T21:02:32.125Z,1521061352.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-03-14T21:02:32.127Z,1521061352.127 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-03-14T21:02:32.234Z,1521061352.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-03-14T21:02:32.235Z,1521061352.235 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-03-14T21:02:32.805Z,1521061352.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-03-14T21:02:32.807Z,1521061352.807 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-03-14T21:02:32.810Z,1521061352.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-03-14T21:02:33.035Z,1521061353.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-03-14T21:02:33.036Z,1521061353.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-03-14T21:02:33.114Z,1521061353.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-03-14T21:02:33.251Z,1521061353.251 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-03-14T21:02:33.343Z,1521061353.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-03-14T21:02:33.427Z,1521061353.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-03-14T21:02:33.560Z,1521061353.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-03-14T21:02:33.720Z,1521061353.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-03-14T21:02:33.816Z,1521061353.816 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-03-14T21:02:33.907Z,1521061353.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-03-14T21:02:34.003Z,1521061354.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-03-14T21:02:34.085Z,1521061354.085 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-03-14T21:02:34.091Z,1521061354.091 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-03-14T21:02:34.214Z,1521061354.214 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-03-14T21:02:34.216Z,1521061354.216 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-03-14T21:02:34.240Z,1521061354.240 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-03-14T21:02:34.242Z,1521061354.242 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-03-14T21:02:34.296Z,1521061354.296 [DepthRateCalculator] Loaded
2018-03-14T21:02:34.296Z,1521061354.296 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-03-14T21:02:34.302Z,1521061354.302 [PitchRateCalculator] Loaded
2018-03-14T21:02:34.302Z,1521061354.302 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-03-14T21:02:34.318Z,1521061354.318 [SpeedCalculator] Loaded
2018-03-14T21:02:34.318Z,1521061354.318 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-03-14T21:02:34.338Z,1521061354.338 [TempGradientCalculator] Loaded
2018-03-14T21:02:34.339Z,1521061354.339 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-03-14T21:02:34.353Z,1521061354.353 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-03-14T21:02:34.354Z,1521061354.354 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-03-14T21:02:34.359Z,1521061354.359 [YawRateCalculator] Loaded
2018-03-14T21:02:34.359Z,1521061354.359 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-03-14T21:02:34.360Z,1521061354.360 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-03-14T21:02:34.360Z,1521061354.360 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-03-14T21:02:34.397Z,1521061354.397 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-03-14T21:02:34.397Z,1521061354.397 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-03-14T21:02:34.841Z,1521061354.841 [AcousticModem_Benthos_ATM900] Loaded
2018-03-14T21:02:34.841Z,1521061354.841 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-03-14T21:02:34.979Z,1521061354.979 [DataOverHttps] Loaded
2018-03-14T21:02:34.979Z,1521061354.979 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-03-14T21:02:34.992Z,1521061354.992 [Depth_Keller] Loaded
2018-03-14T21:02:34.993Z,1521061354.993 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-03-14T21:02:34.997Z,1521061354.997 [DropWeight] Loaded
2018-03-14T21:02:34.998Z,1521061354.998 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-03-14T21:02:35.094Z,1521061355.094 [NAL9602] Loaded
2018-03-14T21:02:35.094Z,1521061355.094 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-03-14T21:02:35.109Z,1521061355.109 [Onboard] Loaded
2018-03-14T21:02:35.109Z,1521061355.109 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-03-14T21:02:35.119Z,1521061355.119 [Radio_Surface] Loaded
2018-03-14T21:02:35.119Z,1521061355.119 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-03-14T21:02:35.120Z,1521061355.120 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0
2018-03-14T21:02:35.121Z,1521061355.121 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 886
2018-03-14T21:02:35.271Z,1521061355.271 [PNI_TCM] Loaded
2018-03-14T21:02:35.271Z,1521061355.271 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-03-14T21:02:35.329Z,1521061355.329 [Rowe_600LCM] Loaded
2018-03-14T21:02:35.329Z,1521061355.329 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-03-14T21:02:35.330Z,1521061355.330 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0
2018-03-14T21:02:35.331Z,1521061355.331 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 887
2018-03-14T21:02:37.022Z,1521061357.022 [BPC1] Loaded
2018-03-14T21:02:37.022Z,1521061357.022 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-03-14T21:02:37.023Z,1521061357.023 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-03-14T21:02:37.025Z,1521061357.025 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-03-14T21:02:37.174Z,1521061357.174 [SBIT](DEBUG): Construct Startup Built In Test.
2018-03-14T21:02:37.200Z,1521061357.200 [SBIT] Loaded
2018-03-14T21:02:37.200Z,1521061357.200 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-03-14T21:02:37.201Z,1521061357.201 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-03-14T21:02:37.212Z,1521061357.212 [IBIT] Loaded
2018-03-14T21:02:37.212Z,1521061357.212 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-03-14T21:02:37.215Z,1521061357.215 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-03-14T21:02:37.363Z,1521061357.363 [CBIT] Loaded
2018-03-14T21:02:37.363Z,1521061357.363 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-03-14T21:02:37.364Z,1521061357.364 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-03-14T21:02:37.365Z,1521061357.365 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-03-14T21:02:37.546Z,1521061357.546 [Aanderaa_O2] Loaded
2018-03-14T21:02:37.547Z,1521061357.547 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-03-14T21:02:37.593Z,1521061357.593 [CTD_Seabird] Loaded
2018-03-14T21:02:37.594Z,1521061357.594 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-03-14T21:02:37.595Z,1521061357.595 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408954E0
2018-03-14T21:02:37.595Z,1521061357.595 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888
2018-03-14T21:02:37.611Z,1521061357.611 [ESPComponent] Loaded
2018-03-14T21:02:37.611Z,1521061357.611 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-03-14T21:02:37.625Z,1521061357.625 [PAR_Licor] Loaded
2018-03-14T21:02:37.625Z,1521061357.625 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-03-14T21:02:37.673Z,1521061357.673 [WetLabsBB2FL] Loaded
2018-03-14T21:02:37.673Z,1521061357.673 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-03-14T21:02:37.674Z,1521061357.674 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C54E0
2018-03-14T21:02:37.674Z,1521061357.674 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889
2018-03-14T21:02:37.675Z,1521061357.675 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-03-14T21:02:37.675Z,1521061357.675 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-03-14T21:02:38.433Z,1521061358.433 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-03-14T21:02:38.434Z,1521061358.434 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-03-14T21:02:38.518Z,1521061358.518 [VerticalControl](DEBUG): Construct VerticalControl.
2018-03-14T21:02:38.613Z,1521061358.613 [VerticalControl] Loaded
2018-03-14T21:02:38.613Z,1521061358.613 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-03-14T21:02:38.614Z,1521061358.614 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-03-14T21:02:38.673Z,1521061358.673 [HorizontalControl] Loaded
2018-03-14T21:02:38.674Z,1521061358.674 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-03-14T21:02:38.674Z,1521061358.674 [SpeedControl](DEBUG): Construct SpeedControl.
2018-03-14T21:02:38.679Z,1521061358.679 [SpeedControl] Loaded
2018-03-14T21:02:38.680Z,1521061358.680 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-03-14T21:02:38.680Z,1521061358.680 [LoopControl](DEBUG): Construct LoopControl.
2018-03-14T21:02:38.681Z,1521061358.681 [LoopControl] Loaded
2018-03-14T21:02:38.681Z,1521061358.681 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-03-14T21:02:38.682Z,1521061358.682 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-03-14T21:02:38.682Z,1521061358.682 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-03-14T21:02:38.800Z,1521061358.800 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-03-14T21:02:38.800Z,1521061358.800 [StratificationFrontDetector](DEBUG): (re)initializing
2018-03-14T21:02:38.800Z,1521061358.800 [StratificationFrontDetector] Loaded
2018-03-14T21:02:38.800Z,1521061358.800 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-03-14T21:02:38.801Z,1521061358.801 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-03-14T21:02:38.801Z,1521061358.801 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-03-14T21:02:38.917Z,1521061358.917 [BuoyancyServo] Loaded
2018-03-14T21:02:38.917Z,1521061358.917 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-03-14T21:02:38.929Z,1521061358.929 [ElevatorServo] Loaded
2018-03-14T21:02:38.929Z,1521061358.929 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-03-14T21:02:38.939Z,1521061358.939 [MassServo] Loaded
2018-03-14T21:02:38.940Z,1521061358.940 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-03-14T21:02:38.950Z,1521061358.950 [RudderServo] Loaded
2018-03-14T21:02:38.951Z,1521061358.951 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-03-14T21:02:38.961Z,1521061358.961 [ThrusterServo] Loaded
2018-03-14T21:02:38.961Z,1521061358.961 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-03-14T21:02:38.962Z,1521061358.962 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-03-14T21:02:38.963Z,1521061358.963 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-03-14T21:02:39.074Z,1521061359.074 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-03-14T21:02:39.074Z,1521061359.074 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-03-14T21:02:39.127Z,1521061359.127 [DeadReckonUsingSpeedCalculator] Loaded
2018-03-14T21:02:39.127Z,1521061359.127 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-03-14T21:02:39.143Z,1521061359.143 [NavChart] Loaded
2018-03-14T21:02:39.144Z,1521061359.144 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-03-14T21:02:39.148Z,1521061359.148 [UniversalFixResidualReporter] Loaded
2018-03-14T21:02:39.148Z,1521061359.148 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-03-14T21:02:39.148Z,1521061359.148 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-03-14T21:02:39.152Z,1521061359.152 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-03-14T21:02:39.153Z,1521061359.153 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-03-14T21:02:39.159Z,1521061359.159 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-03-14T21:02:39.160Z,1521061359.160 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2018-03-14T21:02:39.160Z,1521061359.160 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 891
2018-03-14T21:02:39.165Z,1521061359.165 [Supervisor](INFO): Main Thread ID is 796
2018-03-14T21:02:39.165Z,1521061359.165 [Supervisor](DEBUG): Running supervisor.
2018-03-14T21:02:39.166Z,1521061359.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 892
2018-03-14T21:02:39.168Z,1521061359.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 893
2018-03-14T21:02:39.169Z,1521061359.169 [controlThread](DEBUG): Initializing ControlThread
2018-03-14T21:02:39.169Z,1521061359.169 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-03-14T21:02:39.170Z,1521061359.170 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-03-14T21:02:39.170Z,1521061359.170 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-03-14T21:02:39.170Z,1521061359.170 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-03-14T21:02:39.171Z,1521061359.171 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-03-14T21:02:39.172Z,1521061359.172 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-03-14T21:02:39.179Z,1521061359.179 [SBIT](INFO): Initialize SBIT Component.
2018-03-14T21:02:39.179Z,1521061359.179 [SBIT](IMPORTANT): git: 2018-02-09A-11-g1191477
2018-03-14T21:02:39.179Z,1521061359.179 [SBIT](INFO): git hash: 1191477e507aa94f59b4a9056a613189489a6cd1
2018-03-14T21:02:39.180Z,1521061359.180 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-03-14T21:02:39.181Z,1521061359.181 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2018-03-14T21:02:39.182Z,1521061359.182 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-03-14T21:02:39.183Z,1521061359.183 [IBIT](INFO): Initialize IBIT Component.
2018-03-14T21:02:39.183Z,1521061359.183 [CBIT](DEBUG): Initialize CBIT Component.
2018-03-14T21:02:39.184Z,1521061359.184 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-03-14T21:02:39.184Z,1521061359.184 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-03-14T21:02:39.185Z,1521061359.185 [logger ThreadHandler](INFO): Handler Thread ID is 894
2018-03-14T21:02:39.205Z,1521061359.205 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 895
2018-03-14T21:02:39.211Z,1521061359.211 [Radio_Surface](INFO): Powering up
2018-03-14T21:02:39.217Z,1521061359.217 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 896
2018-03-14T21:02:39.217Z,1521061359.217 [Rowe_600LCM](INFO): Initializing
2018-03-14T21:02:39.218Z,1521061359.218 [Rowe_600LCM](INFO): Checking LCM
2018-03-14T21:02:39.309Z,1521061359.309 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897
2018-03-14T21:02:39.309Z,1521061359.309 [CTD_Seabird](INFO): Initializing
2018-03-14T21:02:39.310Z,1521061359.310 [CTD_Seabird](INFO): Checking LCM
2018-03-14T21:02:39.313Z,1521061359.313 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-03-14T21:02:39.314Z,1521061359.314 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-03-14T21:02:39.315Z,1521061359.315 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-03-14T21:02:39.315Z,1521061359.315 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-03-14T21:02:39.317Z,1521061359.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-03-14T21:02:39.317Z,1521061359.317 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T21:02:39.318Z,1521061359.318 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T21:02:39.318Z,1521061359.318 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-03-14T21:02:39.319Z,1521061359.319 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-03-14T21:02:39.319Z,1521061359.319 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-03-14T21:02:39.319Z,1521061359.319 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-03-14T21:02:39.320Z,1521061359.320 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-03-14T21:02:39.325Z,1521061359.325 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 898
2018-03-14T21:02:39.326Z,1521061359.326 [WetLabsBB2FL](INFO): Powering down
2018-03-14T21:02:39.345Z,1521061359.345 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-03-14T21:02:39.436Z,1521061359.436 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 899
2018-03-14T21:02:39.450Z,1521061359.450 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-03-14T21:02:39.450Z,1521061359.450 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-03-14T21:02:39.450Z,1521061359.450 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-03-14T21:02:39.450Z,1521061359.450 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-03-14T21:02:39.451Z,1521061359.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-03-14T21:02:39.451Z,1521061359.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-03-14T21:02:39.451Z,1521061359.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-03-14T21:02:39.451Z,1521061359.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-03-14T21:02:39.451Z,1521061359.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-03-14T21:02:39.451Z,1521061359.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-03-14T21:02:39.452Z,1521061359.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-03-14T21:02:39.452Z,1521061359.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-03-14T21:02:39.452Z,1521061359.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-03-14T21:02:39.452Z,1521061359.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-03-14T21:02:39.452Z,1521061359.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-03-14T21:02:39.453Z,1521061359.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-03-14T21:02:39.560Z,1521061359.560 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-03-14T21:02:39.633Z,1521061359.633 [MissionManager](DEBUG):
2018-03-14T21:02:39.642Z,1521061359.642 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-03-14T21:02:39.668Z,1521061359.668 [CTD_Seabird](INFO): LCM OK
2018-03-14T21:02:39.668Z,1521061359.668 [CTD_Seabird](INFO): Powering up
2018-03-14T21:02:39.676Z,1521061359.676 [Rowe_600LCM](INFO): LCM OK
2018-03-14T21:02:39.677Z,1521061359.677 [Rowe_600LCM](INFO): Powering up
2018-03-14T21:02:39.699Z,1521061359.699 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-03-14T21:02:39.700Z,1521061359.700 [Default:A.Wait](DEBUG): Construct Wait.
2018-03-14T21:02:39.718Z,1521061359.718 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-03-14T21:02:39.743Z,1521061359.743 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-03-14T21:02:39.746Z,1521061359.746 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-03-14T21:02:39.764Z,1521061359.764 [Default:E.Execute](DEBUG): Construct Execute.
2018-03-14T21:02:39.768Z,1521061359.768 [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-03-14T21:02:39.773Z,1521061359.773 [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,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-03-14T21:02:39.789Z,1521061359.789 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-03-14T21:02:39.789Z,1521061359.789 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-03-14T21:02:39.815Z,1521061359.815 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-03-14T21:02:40.186Z,1521061360.186 [ESPComponent](INFO): powering down ESP
2018-03-14T21:02:40.586Z,1521061360.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:40.668Z,1521061360.668 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:40.782Z,1521061360.782 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-03-14T21:02:40.789Z,1521061360.789 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-03-14T21:02:40.803Z,1521061360.803 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-03-14T21:02:40.809Z,1521061360.809 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-03-14T21:02:40.815Z,1521061360.815 [MassServo](DEBUG): Initializing EZServoServo.
2018-03-14T21:02:40.821Z,1521061360.821 [MassServo](DEBUG): Initializing MassServo.
2018-03-14T21:02:40.834Z,1521061360.834 [RudderServo](DEBUG): Initializing EZServoServo.
2018-03-14T21:02:40.841Z,1521061360.841 [RudderServo](DEBUG): Initializing RudderServo.
2018-03-14T21:02:40.869Z,1521061360.869 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-03-14T21:02:40.877Z,1521061360.877 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-03-14T21:02:40.918Z,1521061360.918 [CommandLine](FAULT): Scheduling is paused
2018-03-14T21:02:40.918Z,1521061360.918 [CBIT](INFO): Critical error at 20180314T210239
2018-03-14T21:02:40.918Z,1521061360.918 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-03-14T21:02:41.020Z,1521061361.020 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-03-14T21:02:41.020Z,1521061361.020 [DropWeight] Hardware Fault, FailCount= 1
2018-03-14T21:02:41.020Z,1521061361.020 [DropWeight](ERROR): Hardware Fault
2018-03-14T21:02:41.124Z,1521061361.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:41.130Z,1521061361.130 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:41.155Z,1521061361.155 [CBIT](INFO): Critical error at 20180314T210241
2018-03-14T21:02:41.158Z,1521061361.158 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-03-14T21:02:41.158Z,1521061361.158 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-03-14T21:02:41.417Z,1521061361.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:41.418Z,1521061361.418 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:41.752Z,1521061361.752 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-03-14T21:02:41.753Z,1521061361.753 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-03-14T21:02:41.753Z,1521061361.753 [BuoyancyServo] Communications Fault, FailCount= 1
2018-03-14T21:02:41.753Z,1521061361.753 [BuoyancyServo](ERROR): Communications Fault
2018-03-14T21:02:41.962Z,1521061361.962 [CBIT](INFO): Critical error at 20180314T210241
2018-03-14T21:02:41.965Z,1521061361.965 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-03-14T21:02:42.007Z,1521061362.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:42.008Z,1521061362.008 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:42.028Z,1521061362.028 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-03-14T21:02:42.029Z,1521061362.029 [BuoyancyServo](INFO): Powering down
2018-03-14T21:02:42.439Z,1521061362.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:42.449Z,1521061362.449 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:42.823Z,1521061362.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:42.833Z,1521061362.833 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:43.223Z,1521061363.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:43.233Z,1521061363.233 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:43.542Z,1521061363.542 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-03-14T21:02:43.614Z,1521061363.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:43.615Z,1521061363.615 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:43.656Z,1521061363.656 [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-03-14T21:02:43.816Z,1521061363.816 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-03-14T21:02:43.841Z,1521061363.841 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-03-14T21:02:43.842Z,1521061363.842 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-03-14T21:02:44.005Z,1521061364.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:44.006Z,1521061364.006 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:44.066Z,1521061364.066 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-03-14T21:02:44.066Z,1521061364.066 [BuoyancyServo] No Fault, FailCount= 1
2018-03-14T21:02:44.416Z,1521061364.416 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:44.429Z,1521061364.429 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:44.442Z,1521061364.442 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-03-14T21:02:44.442Z,1521061364.442 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-03-14T21:02:44.826Z,1521061364.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:44.827Z,1521061364.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:44.871Z,1521061364.871 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-03-14T21:02:45.032Z,1521061365.032 [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-03-14T21:02:45.034Z,1521061365.034 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-03-14T21:02:45.375Z,1521061365.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:45.393Z,1521061365.393 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:45.597Z,1521061365.597 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-03-14T21:02:45.597Z,1521061365.597 [CTD_Seabird](INFO): Powering down
2018-03-14T21:02:45.742Z,1521061365.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:45.743Z,1521061365.743 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:46.106Z,1521061366.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:46.107Z,1521061366.107 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:46.520Z,1521061366.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:46.521Z,1521061366.521 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:46.912Z,1521061366.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:46.929Z,1521061366.929 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:47.262Z,1521061367.262 [AcousticModem_Benthos_ATM900](DEBUG):
2018-03-14T21:02:47.312Z,1521061367.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:47.319Z,1521061367.319 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:47.682Z,1521061367.682 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-03-14T21:02:47.682Z,1521061367.682 [PNI_TCM] Data Fault, FailCount= 1
2018-03-14T21:02:47.682Z,1521061367.682 [PNI_TCM](ERROR): Data Fault
2018-03-14T21:02:47.740Z,1521061367.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:47.749Z,1521061367.749 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:47.857Z,1521061367.857 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-03-14T21:02:48.082Z,1521061368.082 [PNI_TCM](INFO): Powering down
2018-03-14T21:02:48.136Z,1521061368.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:48.149Z,1521061368.149 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:48.523Z,1521061368.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:48.524Z,1521061368.524 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:48.861Z,1521061368.861 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-03-14T21:02:48.862Z,1521061368.862 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-03-14T21:02:48.862Z,1521061368.862 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-03-14T21:02:48.863Z,1521061368.863 [AcousticModem_Benthos_ATM900](DEBUG): Mar 19 2000 22:59:18
2018-03-14T21:02:48.922Z,1521061368.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:48.923Z,1521061368.923 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-03-14T21:02:49.261Z,1521061369.261 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low