2018-05-06T18:39:53.147Z,1525631993.147 [Supervisor](DEBUG): Initializing supervisor.
2018-05-06T18:39:53.150Z,1525631993.150 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-06T18:39:53.151Z,1525631993.151 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-05-06T18:39:53.151Z,1525631993.151 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-06T18:39:53.152Z,1525631993.152 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-06T18:39:53.152Z,1525631993.152 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-05-06T18:39:53.155Z,1525631993.155 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-06T18:39:53.166Z,1525631993.166 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-06T18:39:53.167Z,1525631993.167 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-06T18:39:53.168Z,1525631993.168 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-05-06T18:39:53.169Z,1525631993.169 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-06T18:39:53.169Z,1525631993.169 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-06T18:39:53.170Z,1525631993.170 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-05-06T18:39:53.172Z,1525631993.172 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-06T18:39:53.172Z,1525631993.172 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-06T18:39:53.176Z,1525631993.176 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-06T18:39:53.272Z,1525631993.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-06T18:39:53.272Z,1525631993.272 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-06T18:39:53.370Z,1525631993.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-06T18:39:53.371Z,1525631993.371 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-06T18:39:53.556Z,1525631993.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-06T18:39:53.557Z,1525631993.557 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-06T18:39:53.844Z,1525631993.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-06T18:39:53.846Z,1525631993.846 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-06T18:39:54.153Z,1525631994.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-06T18:39:54.155Z,1525631994.155 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-06T18:39:54.580Z,1525631994.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-06T18:39:54.582Z,1525631994.582 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-06T18:39:54.902Z,1525631994.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-06T18:39:54.902Z,1525631994.902 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-06T18:39:55.311Z,1525631995.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-06T18:39:55.312Z,1525631995.312 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-06T18:39:55.392Z,1525631995.392 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-06T18:39:55.669Z,1525631995.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-06T18:39:55.670Z,1525631995.670 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-06T18:39:55.808Z,1525631995.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-06T18:39:55.809Z,1525631995.809 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-06T18:39:56.004Z,1525631996.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-06T18:39:56.005Z,1525631996.005 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-06T18:39:56.204Z,1525631996.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-06T18:39:56.205Z,1525631996.205 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-06T18:39:56.301Z,1525631996.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-06T18:39:56.302Z,1525631996.302 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-06T18:39:56.438Z,1525631996.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-06T18:39:56.440Z,1525631996.440 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-06T18:39:56.443Z,1525631996.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-06T18:39:56.694Z,1525631996.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-06T18:39:56.695Z,1525631996.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-06T18:39:57.535Z,1525631997.535 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-06T18:39:57.670Z,1525631997.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-06T18:39:57.761Z,1525631997.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-06T18:39:57.845Z,1525631997.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-06T18:39:57.975Z,1525631997.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-06T18:39:58.132Z,1525631998.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-06T18:39:58.227Z,1525631998.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-06T18:39:58.317Z,1525631998.317 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-06T18:39:58.412Z,1525631998.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-06T18:39:58.494Z,1525631998.494 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-05-06T18:39:58.498Z,1525631998.498 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-06T18:39:58.621Z,1525631998.621 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-06T18:39:58.622Z,1525631998.622 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-06T18:39:58.646Z,1525631998.646 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-06T18:39:58.647Z,1525631998.647 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-06T18:39:58.701Z,1525631998.701 [DepthRateCalculator] Loaded
2018-05-06T18:39:58.702Z,1525631998.702 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-06T18:39:58.708Z,1525631998.708 [PitchRateCalculator] Loaded
2018-05-06T18:39:58.708Z,1525631998.708 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-06T18:39:58.723Z,1525631998.723 [SpeedCalculator] Loaded
2018-05-06T18:39:58.724Z,1525631998.724 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-06T18:39:58.745Z,1525631998.745 [TempGradientCalculator] Loaded
2018-05-06T18:39:58.745Z,1525631998.745 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-06T18:39:58.760Z,1525631998.760 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-06T18:39:58.760Z,1525631998.760 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-06T18:39:58.765Z,1525631998.765 [YawRateCalculator] Loaded
2018-05-06T18:39:58.766Z,1525631998.766 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-06T18:39:58.804Z,1525631998.804 [ElevatorOffsetCalculator] Loaded
2018-05-06T18:39:58.804Z,1525631998.804 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-06T18:39:58.805Z,1525631998.805 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-06T18:39:58.805Z,1525631998.805 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-06T18:39:58.841Z,1525631998.841 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-06T18:39:58.842Z,1525631998.842 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-06T18:39:59.285Z,1525631999.285 [AcousticModem_Benthos_ATM900] Loaded
2018-05-06T18:39:59.285Z,1525631999.285 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-06T18:39:59.424Z,1525631999.424 [DataOverHttps] Loaded
2018-05-06T18:39:59.425Z,1525631999.425 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-06T18:39:59.438Z,1525631999.438 [Depth_Keller] Loaded
2018-05-06T18:39:59.438Z,1525631999.438 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-06T18:39:59.443Z,1525631999.443 [DropWeight] Loaded
2018-05-06T18:39:59.443Z,1525631999.443 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-05-06T18:39:59.536Z,1525631999.536 [NAL9602] Loaded
2018-05-06T18:39:59.537Z,1525631999.537 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-06T18:39:59.552Z,1525631999.552 [Onboard] Loaded
2018-05-06T18:39:59.553Z,1525631999.553 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-06T18:39:59.562Z,1525631999.562 [Radio_Surface] Loaded
2018-05-06T18:39:59.563Z,1525631999.563 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-06T18:39:59.564Z,1525631999.564 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A64E0
2018-05-06T18:39:59.564Z,1525631999.564 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 881
2018-05-06T18:39:59.715Z,1525631999.715 [PNI_TCM] Loaded
2018-05-06T18:39:59.715Z,1525631999.715 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-06T18:39:59.773Z,1525631999.773 [Rowe_600LCM] Loaded
2018-05-06T18:39:59.773Z,1525631999.773 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-05-06T18:39:59.774Z,1525631999.774 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D64E0
2018-05-06T18:39:59.775Z,1525631999.775 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 882
2018-05-06T18:40:01.469Z,1525632001.469 [BPC1] Loaded
2018-05-06T18:40:01.469Z,1525632001.469 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-06T18:40:01.470Z,1525632001.470 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-06T18:40:01.472Z,1525632001.472 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-06T18:40:01.619Z,1525632001.619 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-06T18:40:01.641Z,1525632001.641 [SBIT] Loaded
2018-05-06T18:40:01.641Z,1525632001.641 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-06T18:40:01.642Z,1525632001.642 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-06T18:40:01.653Z,1525632001.653 [IBIT] Loaded
2018-05-06T18:40:01.653Z,1525632001.653 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-06T18:40:01.657Z,1525632001.657 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-06T18:40:01.802Z,1525632001.802 [CBIT] Loaded
2018-05-06T18:40:01.802Z,1525632001.802 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-06T18:40:01.803Z,1525632001.803 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-06T18:40:01.804Z,1525632001.804 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-06T18:40:02.252Z,1525632002.252 [Aanderaa_O2] Loaded
2018-05-06T18:40:02.252Z,1525632002.252 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-06T18:40:02.298Z,1525632002.298 [CTD_Seabird] Loaded
2018-05-06T18:40:02.299Z,1525632002.299 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-06T18:40:02.300Z,1525632002.300 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408964E0
2018-05-06T18:40:02.300Z,1525632002.300 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 883
2018-05-06T18:40:02.316Z,1525632002.316 [ESPComponent] Loaded
2018-05-06T18:40:02.316Z,1525632002.316 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-06T18:40:02.330Z,1525632002.330 [PAR_Licor] Loaded
2018-05-06T18:40:02.330Z,1525632002.330 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-06T18:40:02.378Z,1525632002.378 [WetLabsBB2FL] Loaded
2018-05-06T18:40:02.379Z,1525632002.379 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-06T18:40:02.380Z,1525632002.380 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2018-05-06T18:40:02.380Z,1525632002.380 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 884
2018-05-06T18:40:02.381Z,1525632002.381 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-06T18:40:02.381Z,1525632002.381 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-06T18:40:02.649Z,1525632002.649 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-06T18:40:02.651Z,1525632002.651 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-06T18:40:02.736Z,1525632002.736 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-06T18:40:02.829Z,1525632002.829 [VerticalControl] Loaded
2018-05-06T18:40:02.830Z,1525632002.830 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-06T18:40:02.830Z,1525632002.830 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-06T18:40:02.889Z,1525632002.889 [HorizontalControl] Loaded
2018-05-06T18:40:02.890Z,1525632002.890 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-06T18:40:02.890Z,1525632002.890 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-06T18:40:02.895Z,1525632002.895 [SpeedControl] Loaded
2018-05-06T18:40:02.896Z,1525632002.896 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-06T18:40:02.896Z,1525632002.896 [LoopControl](DEBUG): Construct LoopControl.
2018-05-06T18:40:02.897Z,1525632002.897 [LoopControl] Loaded
2018-05-06T18:40:02.897Z,1525632002.897 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-06T18:40:02.898Z,1525632002.898 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-06T18:40:02.898Z,1525632002.898 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-06T18:40:03.016Z,1525632003.016 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-06T18:40:03.016Z,1525632003.016 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-06T18:40:03.016Z,1525632003.016 [StratificationFrontDetector] Loaded
2018-05-06T18:40:03.017Z,1525632003.017 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-06T18:40:03.017Z,1525632003.017 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-06T18:40:03.018Z,1525632003.018 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-06T18:40:03.134Z,1525632003.134 [BuoyancyServo] Loaded
2018-05-06T18:40:03.134Z,1525632003.134 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-06T18:40:03.145Z,1525632003.145 [ElevatorServo] Loaded
2018-05-06T18:40:03.146Z,1525632003.146 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-06T18:40:03.157Z,1525632003.157 [MassServo] Loaded
2018-05-06T18:40:03.157Z,1525632003.157 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-06T18:40:03.168Z,1525632003.168 [RudderServo] Loaded
2018-05-06T18:40:03.168Z,1525632003.168 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-06T18:40:03.178Z,1525632003.178 [ThrusterServo] Loaded
2018-05-06T18:40:03.179Z,1525632003.179 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-06T18:40:03.179Z,1525632003.179 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-06T18:40:03.180Z,1525632003.180 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-06T18:40:03.289Z,1525632003.289 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-05-06T18:40:03.290Z,1525632003.290 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-05-06T18:40:03.344Z,1525632003.344 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-06T18:40:03.344Z,1525632003.344 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-06T18:40:03.360Z,1525632003.360 [NavChart] Loaded
2018-05-06T18:40:03.361Z,1525632003.361 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-06T18:40:03.364Z,1525632003.364 [UniversalFixResidualReporter] Loaded
2018-05-06T18:40:03.365Z,1525632003.365 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-06T18:40:03.365Z,1525632003.365 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-06T18:40:03.369Z,1525632003.369 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-06T18:40:03.370Z,1525632003.370 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-06T18:40:03.376Z,1525632003.376 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-06T18:40:03.377Z,1525632003.377 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0
2018-05-06T18:40:03.377Z,1525632003.377 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2018-05-06T18:40:03.382Z,1525632003.382 [Supervisor](INFO): Main Thread ID is 795
2018-05-06T18:40:03.382Z,1525632003.382 [Supervisor](DEBUG): Running supervisor.
2018-05-06T18:40:03.383Z,1525632003.383 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2018-05-06T18:40:03.385Z,1525632003.385 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2018-05-06T18:40:03.386Z,1525632003.386 [controlThread](DEBUG): Initializing ControlThread
2018-05-06T18:40:03.387Z,1525632003.387 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-06T18:40:03.387Z,1525632003.387 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-06T18:40:03.387Z,1525632003.387 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-06T18:40:03.388Z,1525632003.388 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-06T18:40:03.388Z,1525632003.388 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-06T18:40:03.389Z,1525632003.389 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-06T18:40:03.389Z,1525632003.389 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-06T18:40:03.397Z,1525632003.397 [SBIT](INFO): Initialize SBIT Component.
2018-05-06T18:40:03.397Z,1525632003.397 [SBIT](IMPORTANT): git: 2018-03-26-2-gc06366e
2018-05-06T18:40:03.398Z,1525632003.398 [SBIT](INFO): git hash: c06366e488986061b2c97b8bb696d868dcd187c8
2018-05-06T18:40:03.398Z,1525632003.398 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-06T18:40:03.399Z,1525632003.399 [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-06T18:40:03.400Z,1525632003.400 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-05-06T18:40:03.401Z,1525632003.401 [IBIT](INFO): Initialize IBIT Component.
2018-05-06T18:40:03.401Z,1525632003.401 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-06T18:40:03.402Z,1525632003.402 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-05-06T18:40:03.402Z,1525632003.402 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-05-06T18:40:03.403Z,1525632003.403 [logger ThreadHandler](INFO): Handler Thread ID is 888
2018-05-06T18:40:03.423Z,1525632003.423 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889
2018-05-06T18:40:03.429Z,1525632003.429 [Radio_Surface](INFO): Powering up
2018-05-06T18:40:03.435Z,1525632003.435 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 890
2018-05-06T18:40:03.436Z,1525632003.436 [Rowe_600LCM](INFO): Initializing
2018-05-06T18:40:03.436Z,1525632003.436 [Rowe_600LCM](INFO): Checking LCM
2018-05-06T18:40:03.535Z,1525632003.535 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-06T18:40:03.537Z,1525632003.537 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-06T18:40:03.537Z,1525632003.537 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-06T18:40:03.538Z,1525632003.538 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-06T18:40:03.540Z,1525632003.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-06T18:40:03.540Z,1525632003.540 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-06T18:40:03.541Z,1525632003.541 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-06T18:40:03.541Z,1525632003.541 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-06T18:40:03.541Z,1525632003.541 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-06T18:40:03.542Z,1525632003.542 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-06T18:40:03.542Z,1525632003.542 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-06T18:40:03.543Z,1525632003.543 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-06T18:40:03.544Z,1525632003.544 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-06T18:40:03.555Z,1525632003.555 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 891
2018-05-06T18:40:03.555Z,1525632003.555 [CTD_Seabird](INFO): Initializing
2018-05-06T18:40:03.556Z,1525632003.556 [CTD_Seabird](INFO): Checking LCM
2018-05-06T18:40:03.660Z,1525632003.660 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 892
2018-05-06T18:40:03.663Z,1525632003.663 [WetLabsBB2FL](INFO): Powering down
2018-05-06T18:40:03.688Z,1525632003.688 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-06T18:40:03.757Z,1525632003.757 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893
2018-05-06T18:40:03.769Z,1525632003.769 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-06T18:40:03.769Z,1525632003.769 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-06T18:40:03.769Z,1525632003.769 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-06T18:40:03.769Z,1525632003.769 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-06T18:40:03.770Z,1525632003.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-06T18:40:03.770Z,1525632003.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-06T18:40:03.770Z,1525632003.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-06T18:40:03.770Z,1525632003.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-06T18:40:03.770Z,1525632003.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-06T18:40:03.771Z,1525632003.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-06T18:40:03.771Z,1525632003.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-06T18:40:03.771Z,1525632003.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-06T18:40:03.771Z,1525632003.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-06T18:40:03.771Z,1525632003.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-06T18:40:03.772Z,1525632003.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-06T18:40:03.772Z,1525632003.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-06T18:40:03.775Z,1525632003.775 [MissionManager](DEBUG):
2018-05-06T18:40:03.803Z,1525632003.803 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-06T18:40:03.827Z,1525632003.827 [CTD_Seabird](INFO): LCM OK
2018-05-06T18:40:03.827Z,1525632003.827 [CTD_Seabird](INFO): Powering up
2018-05-06T18:40:03.855Z,1525632003.855 [Rowe_600LCM](INFO): LCM OK
2018-05-06T18:40:03.855Z,1525632003.855 [Rowe_600LCM](INFO): Powering up
2018-05-06T18:40:03.877Z,1525632003.877 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-06T18:40:03.895Z,1525632003.895 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-06T18:40:03.897Z,1525632003.897 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-06T18:40:03.949Z,1525632003.949 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-06T18:40:03.952Z,1525632003.952 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-06T18:40:03.974Z,1525632003.974 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-06T18:40:03.977Z,1525632003.977 [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-06T18:40:03.999Z,1525632003.999 [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-06T18:40:04.027Z,1525632004.027 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-06T18:40:04.027Z,1525632004.027 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-06T18:40:04.053Z,1525632004.053 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-06T18:40:04.409Z,1525632004.409 [ESPComponent](INFO): powering down ESP
2018-05-06T18:40:04.827Z,1525632004.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:04.912Z,1525632004.912 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:05.023Z,1525632005.023 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-06T18:40:05.031Z,1525632005.031 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-06T18:40:05.053Z,1525632005.053 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-06T18:40:05.059Z,1525632005.059 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-06T18:40:05.073Z,1525632005.073 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-06T18:40:05.079Z,1525632005.079 [MassServo](DEBUG): Initializing MassServo.
2018-05-06T18:40:05.088Z,1525632005.088 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-06T18:40:05.095Z,1525632005.095 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-06T18:40:05.123Z,1525632005.123 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-06T18:40:05.131Z,1525632005.131 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-06T18:40:05.168Z,1525632005.168 [CommandLine](FAULT): Scheduling is paused
2018-05-06T18:40:05.168Z,1525632005.168 [CBIT](INFO): Critical error at 20180506T184003
2018-05-06T18:40:05.168Z,1525632005.168 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-06T18:40:05.283Z,1525632005.283 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-05-06T18:40:05.283Z,1525632005.283 [DropWeight] Hardware Fault, FailCount= 1
2018-05-06T18:40:05.283Z,1525632005.283 [DropWeight](ERROR): Hardware Fault
2018-05-06T18:40:05.411Z,1525632005.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:05.412Z,1525632005.412 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:05.436Z,1525632005.436 [CBIT](INFO): Critical error at 20180506T184005
2018-05-06T18:40:05.438Z,1525632005.438 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-05-06T18:40:05.438Z,1525632005.438 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-05-06T18:40:05.686Z,1525632005.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:05.688Z,1525632005.688 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:06.031Z,1525632006.031 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-05-06T18:40:06.031Z,1525632006.031 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-05-06T18:40:06.031Z,1525632006.031 [BuoyancyServo] Communications Fault, FailCount= 1
2018-05-06T18:40:06.031Z,1525632006.031 [BuoyancyServo](ERROR): Communications Fault
2018-05-06T18:40:06.236Z,1525632006.236 [CBIT](INFO): Critical error at 20180506T184005
2018-05-06T18:40:06.239Z,1525632006.239 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-05-06T18:40:06.296Z,1525632006.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:06.297Z,1525632006.297 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:06.311Z,1525632006.311 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-06T18:40:06.312Z,1525632006.312 [BuoyancyServo](INFO): Powering down
2018-05-06T18:40:06.694Z,1525632006.694 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:06.703Z,1525632006.703 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:07.114Z,1525632007.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:07.123Z,1525632007.123 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:07.490Z,1525632007.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:07.544Z,1525632007.544 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:07.909Z,1525632007.909 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-06T18:40:07.939Z,1525632007.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:07.940Z,1525632007.940 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:08.033Z,1525632008.033 [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-06T18:40:08.157Z,1525632008.157 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-06T18:40:08.158Z,1525632008.158 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-06T18:40:08.167Z,1525632008.167 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-06T18:40:08.313Z,1525632008.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:08.314Z,1525632008.314 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:08.439Z,1525632008.439 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-05-06T18:40:08.439Z,1525632008.439 [BuoyancyServo] No Fault, FailCount= 1
2018-05-06T18:40:08.721Z,1525632008.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:08.735Z,1525632008.735 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:08.771Z,1525632008.771 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-06T18:40:08.771Z,1525632008.771 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-06T18:40:08.948Z,1525632008.948 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-06T18:40:09.038Z,1525632009.038 [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-06T18:40:09.040Z,1525632009.040 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-06T18:40:09.140Z,1525632009.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:09.142Z,1525632009.142 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:09.438Z,1525632009.438 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-06T18:40:09.438Z,1525632009.438 [CTD_Seabird](INFO): Powering down
2018-05-06T18:40:09.520Z,1525632009.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:09.521Z,1525632009.521 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:09.926Z,1525632009.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:09.931Z,1525632009.931 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:10.292Z,1525632010.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:10.293Z,1525632010.293 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:10.695Z,1525632010.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:10.696Z,1525632010.696 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.131Z,1525632011.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.132Z,1525632011.132 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.449Z,1525632011.449 [AcousticModem_Benthos_ATM900](DEBUG):
2018-05-06T18:40:11.518Z,1525632011.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.519Z,1525632011.519 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.869Z,1525632011.869 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-06T18:40:11.869Z,1525632011.869 [PNI_TCM] Data Fault, FailCount= 1
2018-05-06T18:40:11.869Z,1525632011.869 [PNI_TCM](ERROR): Data Fault
2018-05-06T18:40:11.892Z,1525632011.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.893Z,1525632011.893 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:11.000Z,1525632012.000 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-06T18:40:12.270Z,1525632012.270 [PNI_TCM](INFO): Powering down
2018-05-06T18:40:12.344Z,1525632012.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:12.345Z,1525632012.345 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:12.692Z,1525632012.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:12.693Z,1525632012.693 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:13.048Z,1525632013.048 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-06T18:40:13.048Z,1525632013.048 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-06T18:40:13.049Z,1525632013.049 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-06T18:40:13.049Z,1525632013.049 [AcousticModem_Benthos_ATM900](DEBUG): Apr 3 2000 15:32:56
2018-05-06T18:40:13.112Z,1525632013.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:13.113Z,1525632013.113 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-06T18:40:13.447Z,1525632013.447 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low