2018-04-30T10:29:32.381Z,1525084172.381 [Supervisor](DEBUG): Initializing supervisor.
2018-04-30T10:29:32.383Z,1525084172.383 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-04-30T10:29:32.384Z,1525084172.384 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-04-30T10:29:32.384Z,1525084172.384 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-04-30T10:29:32.385Z,1525084172.385 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-04-30T10:29:32.385Z,1525084172.385 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-04-30T10:29:32.388Z,1525084172.388 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-04-30T10:29:32.399Z,1525084172.399 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-04-30T10:29:32.400Z,1525084172.400 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-04-30T10:29:32.400Z,1525084172.400 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-04-30T10:29:32.401Z,1525084172.401 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-04-30T10:29:32.401Z,1525084172.401 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-04-30T10:29:32.402Z,1525084172.402 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-04-30T10:29:32.404Z,1525084172.404 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-04-30T10:29:32.404Z,1525084172.404 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-04-30T10:29:32.408Z,1525084172.408 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-04-30T10:29:32.505Z,1525084172.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-04-30T10:29:32.506Z,1525084172.506 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-04-30T10:29:32.604Z,1525084172.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-04-30T10:29:32.604Z,1525084172.604 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-04-30T10:29:32.789Z,1525084172.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-04-30T10:29:32.790Z,1525084172.790 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-04-30T10:29:33.079Z,1525084173.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-04-30T10:29:33.081Z,1525084173.081 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-04-30T10:29:33.390Z,1525084173.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-04-30T10:29:33.392Z,1525084173.392 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-04-30T10:29:33.818Z,1525084173.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-04-30T10:29:33.820Z,1525084173.820 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-04-30T10:29:34.141Z,1525084174.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-04-30T10:29:34.141Z,1525084174.141 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-04-30T10:29:34.550Z,1525084174.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-04-30T10:29:34.551Z,1525084174.551 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-04-30T10:29:34.633Z,1525084174.633 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-04-30T10:29:34.911Z,1525084174.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-04-30T10:29:34.912Z,1525084174.912 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-04-30T10:29:35.051Z,1525084175.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-04-30T10:29:35.052Z,1525084175.052 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-04-30T10:29:35.248Z,1525084175.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-04-30T10:29:35.248Z,1525084175.248 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-04-30T10:29:35.449Z,1525084175.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-04-30T10:29:35.449Z,1525084175.449 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-04-30T10:29:35.545Z,1525084175.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-04-30T10:29:35.546Z,1525084175.546 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-04-30T10:29:35.682Z,1525084175.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-04-30T10:29:35.684Z,1525084175.684 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-04-30T10:29:35.687Z,1525084175.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-04-30T10:29:35.960Z,1525084175.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-04-30T10:29:35.961Z,1525084175.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-04-30T10:29:36.553Z,1525084176.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-04-30T10:29:36.688Z,1525084176.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-04-30T10:29:36.779Z,1525084176.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-04-30T10:29:36.863Z,1525084176.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-04-30T10:29:36.993Z,1525084176.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-04-30T10:29:37.152Z,1525084177.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-04-30T10:29:37.246Z,1525084177.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-04-30T10:29:37.337Z,1525084177.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-04-30T10:29:37.431Z,1525084177.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-04-30T10:29:37.513Z,1525084177.513 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2018-04-30T10:29:37.517Z,1525084177.517 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-04-30T10:29:37.641Z,1525084177.641 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-04-30T10:29:37.642Z,1525084177.642 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-04-30T10:29:37.665Z,1525084177.665 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-04-30T10:29:37.666Z,1525084177.666 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-04-30T10:29:37.720Z,1525084177.720 [DepthRateCalculator] Loaded
2018-04-30T10:29:37.721Z,1525084177.721 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-04-30T10:29:37.726Z,1525084177.726 [PitchRateCalculator] Loaded
2018-04-30T10:29:37.727Z,1525084177.727 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-04-30T10:29:37.742Z,1525084177.742 [SpeedCalculator] Loaded
2018-04-30T10:29:37.742Z,1525084177.742 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-04-30T10:29:37.762Z,1525084177.762 [TempGradientCalculator] Loaded
2018-04-30T10:29:37.763Z,1525084177.763 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-04-30T10:29:37.777Z,1525084177.777 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-04-30T10:29:37.777Z,1525084177.777 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-04-30T10:29:37.783Z,1525084177.783 [YawRateCalculator] Loaded
2018-04-30T10:29:37.783Z,1525084177.783 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-04-30T10:29:37.821Z,1525084177.821 [ElevatorOffsetCalculator] Loaded
2018-04-30T10:29:37.821Z,1525084177.821 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-04-30T10:29:37.822Z,1525084177.822 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-04-30T10:29:37.822Z,1525084177.822 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-04-30T10:29:37.858Z,1525084177.858 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-04-30T10:29:37.859Z,1525084177.859 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-04-30T10:29:38.302Z,1525084178.302 [AcousticModem_Benthos_ATM900] Loaded
2018-04-30T10:29:38.302Z,1525084178.302 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-04-30T10:29:38.439Z,1525084178.439 [DataOverHttps] Loaded
2018-04-30T10:29:38.439Z,1525084178.439 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-04-30T10:29:38.452Z,1525084178.452 [Depth_Keller] Loaded
2018-04-30T10:29:38.453Z,1525084178.453 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-04-30T10:29:38.457Z,1525084178.457 [DropWeight] Loaded
2018-04-30T10:29:38.457Z,1525084178.457 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-04-30T10:29:38.550Z,1525084178.550 [NAL9602] Loaded
2018-04-30T10:29:38.550Z,1525084178.550 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-04-30T10:29:38.565Z,1525084178.565 [Onboard] Loaded
2018-04-30T10:29:38.566Z,1525084178.566 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-04-30T10:29:38.575Z,1525084178.575 [Radio_Surface] Loaded
2018-04-30T10:29:38.576Z,1525084178.576 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-04-30T10:29:38.577Z,1525084178.577 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A64E0
2018-04-30T10:29:38.577Z,1525084178.577 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 881
2018-04-30T10:29:38.728Z,1525084178.728 [PNI_TCM] Loaded
2018-04-30T10:29:38.728Z,1525084178.728 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-04-30T10:29:38.786Z,1525084178.786 [Rowe_600LCM] Loaded
2018-04-30T10:29:38.786Z,1525084178.786 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-04-30T10:29:38.787Z,1525084178.787 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D64E0
2018-04-30T10:29:38.787Z,1525084178.787 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 882
2018-04-30T10:29:40.472Z,1525084180.472 [BPC1] Loaded
2018-04-30T10:29:40.472Z,1525084180.472 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-04-30T10:29:40.473Z,1525084180.473 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-04-30T10:29:40.474Z,1525084180.474 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-04-30T10:29:40.621Z,1525084180.621 [SBIT](DEBUG): Construct Startup Built In Test.
2018-04-30T10:29:40.643Z,1525084180.643 [SBIT] Loaded
2018-04-30T10:29:40.644Z,1525084180.644 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-04-30T10:29:40.644Z,1525084180.644 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-04-30T10:29:40.655Z,1525084180.655 [IBIT] Loaded
2018-04-30T10:29:40.656Z,1525084180.656 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-04-30T10:29:40.659Z,1525084180.659 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-04-30T10:29:40.803Z,1525084180.803 [CBIT] Loaded
2018-04-30T10:29:40.803Z,1525084180.803 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-04-30T10:29:40.804Z,1525084180.804 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-04-30T10:29:40.804Z,1525084180.804 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-04-30T10:29:40.985Z,1525084180.985 [Aanderaa_O2] Loaded
2018-04-30T10:29:40.985Z,1525084180.985 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-04-30T10:29:41.030Z,1525084181.030 [CTD_Seabird] Loaded
2018-04-30T10:29:41.031Z,1525084181.031 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-04-30T10:29:41.032Z,1525084181.032 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408964E0
2018-04-30T10:29:41.032Z,1525084181.032 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 883
2018-04-30T10:29:41.048Z,1525084181.048 [ESPComponent] Loaded
2018-04-30T10:29:41.048Z,1525084181.048 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-04-30T10:29:41.062Z,1525084181.062 [PAR_Licor] Loaded
2018-04-30T10:29:41.062Z,1525084181.062 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-04-30T10:29:41.109Z,1525084181.109 [WetLabsBB2FL] Loaded
2018-04-30T10:29:41.109Z,1525084181.109 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-04-30T10:29:41.110Z,1525084181.110 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2018-04-30T10:29:41.111Z,1525084181.111 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 884
2018-04-30T10:29:41.111Z,1525084181.111 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-04-30T10:29:41.112Z,1525084181.112 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-04-30T10:29:41.436Z,1525084181.436 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-04-30T10:29:41.438Z,1525084181.438 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-04-30T10:29:41.523Z,1525084181.523 [VerticalControl](DEBUG): Construct VerticalControl.
2018-04-30T10:29:41.615Z,1525084181.615 [VerticalControl] Loaded
2018-04-30T10:29:41.615Z,1525084181.615 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-04-30T10:29:41.616Z,1525084181.616 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-04-30T10:29:41.674Z,1525084181.674 [HorizontalControl] Loaded
2018-04-30T10:29:41.674Z,1525084181.674 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-04-30T10:29:41.675Z,1525084181.675 [SpeedControl](DEBUG): Construct SpeedControl.
2018-04-30T10:29:41.680Z,1525084181.680 [SpeedControl] Loaded
2018-04-30T10:29:41.680Z,1525084181.680 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-04-30T10:29:41.681Z,1525084181.681 [LoopControl](DEBUG): Construct LoopControl.
2018-04-30T10:29:41.681Z,1525084181.681 [LoopControl] Loaded
2018-04-30T10:29:41.682Z,1525084181.682 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-04-30T10:29:41.682Z,1525084181.682 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-04-30T10:29:41.683Z,1525084181.683 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-04-30T10:29:41.800Z,1525084181.800 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-04-30T10:29:41.800Z,1525084181.800 [StratificationFrontDetector](DEBUG): (re)initializing
2018-04-30T10:29:41.800Z,1525084181.800 [StratificationFrontDetector] Loaded
2018-04-30T10:29:41.801Z,1525084181.801 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-04-30T10:29:41.801Z,1525084181.801 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-04-30T10:29:41.802Z,1525084181.802 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-04-30T10:29:41.917Z,1525084181.917 [BuoyancyServo] Loaded
2018-04-30T10:29:41.917Z,1525084181.917 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-04-30T10:29:41.929Z,1525084181.929 [ElevatorServo] Loaded
2018-04-30T10:29:41.929Z,1525084181.929 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-04-30T10:29:41.940Z,1525084181.940 [MassServo] Loaded
2018-04-30T10:29:41.940Z,1525084181.940 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-04-30T10:29:41.951Z,1525084181.951 [RudderServo] Loaded
2018-04-30T10:29:41.951Z,1525084181.951 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-04-30T10:29:41.962Z,1525084181.962 [ThrusterServo] Loaded
2018-04-30T10:29:41.962Z,1525084181.962 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-04-30T10:29:41.962Z,1525084181.962 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-04-30T10:29:41.963Z,1525084181.963 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-04-30T10:29:42.074Z,1525084182.074 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-04-30T10:29:42.074Z,1525084182.074 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-04-30T10:29:42.127Z,1525084182.127 [DeadReckonUsingSpeedCalculator] Loaded
2018-04-30T10:29:42.128Z,1525084182.128 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-04-30T10:29:42.143Z,1525084182.143 [NavChart] Loaded
2018-04-30T10:29:42.144Z,1525084182.144 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-04-30T10:29:42.147Z,1525084182.147 [UniversalFixResidualReporter] Loaded
2018-04-30T10:29:42.148Z,1525084182.148 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-04-30T10:29:42.148Z,1525084182.148 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-04-30T10:29:42.152Z,1525084182.152 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-04-30T10:29:42.152Z,1525084182.152 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-04-30T10:29:42.159Z,1525084182.159 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-04-30T10:29:42.160Z,1525084182.160 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0
2018-04-30T10:29:42.160Z,1525084182.160 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 885
2018-04-30T10:29:42.165Z,1525084182.165 [Supervisor](INFO): Main Thread ID is 795
2018-04-30T10:29:42.165Z,1525084182.165 [Supervisor](DEBUG): Running supervisor.
2018-04-30T10:29:42.165Z,1525084182.165 [CommandLine ThreadHandler](INFO): Handler Thread ID is 886
2018-04-30T10:29:42.168Z,1525084182.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 887
2018-04-30T10:29:42.168Z,1525084182.168 [controlThread](DEBUG): Initializing ControlThread
2018-04-30T10:29:42.169Z,1525084182.169 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-04-30T10:29:42.170Z,1525084182.170 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-04-30T10:29:42.170Z,1525084182.170 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-04-30T10:29:42.170Z,1525084182.170 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-04-30T10:29:42.171Z,1525084182.171 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-04-30T10:29:42.172Z,1525084182.172 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-04-30T10:29:42.172Z,1525084182.172 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-04-30T10:29:42.179Z,1525084182.179 [SBIT](INFO): Initialize SBIT Component.
2018-04-30T10:29:42.180Z,1525084182.180 [SBIT](IMPORTANT): git: 2018-03-26-2-gc06366e
2018-04-30T10:29:42.180Z,1525084182.180 [SBIT](INFO): git hash: c06366e488986061b2c97b8bb696d868dcd187c8
2018-04-30T10:29:42.180Z,1525084182.180 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-04-30T10:29:42.181Z,1525084182.181 [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-04-30T10:29:42.182Z,1525084182.182 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-04-30T10:29:42.183Z,1525084182.183 [IBIT](INFO): Initialize IBIT Component.
2018-04-30T10:29:42.184Z,1525084182.184 [CBIT](DEBUG): Initialize CBIT Component.
2018-04-30T10:29:42.184Z,1525084182.184 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2018-04-30T10:29:42.184Z,1525084182.184 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-04-30T10:29:42.185Z,1525084182.185 [logger ThreadHandler](INFO): Handler Thread ID is 888
2018-04-30T10:29:42.205Z,1525084182.205 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889
2018-04-30T10:29:42.211Z,1525084182.211 [Radio_Surface](INFO): Powering up
2018-04-30T10:29:42.217Z,1525084182.217 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 890
2018-04-30T10:29:42.218Z,1525084182.218 [Rowe_600LCM](INFO): Initializing
2018-04-30T10:29:42.218Z,1525084182.218 [Rowe_600LCM](INFO): Checking LCM
2018-04-30T10:29:42.321Z,1525084182.321 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-04-30T10:29:42.323Z,1525084182.323 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-04-30T10:29:42.323Z,1525084182.323 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-04-30T10:29:42.324Z,1525084182.324 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-04-30T10:29:42.326Z,1525084182.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-04-30T10:29:42.326Z,1525084182.326 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-04-30T10:29:42.327Z,1525084182.327 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-04-30T10:29:42.327Z,1525084182.327 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-04-30T10:29:42.328Z,1525084182.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-04-30T10:29:42.328Z,1525084182.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-04-30T10:29:42.328Z,1525084182.328 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-04-30T10:29:42.329Z,1525084182.329 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-04-30T10:29:42.330Z,1525084182.330 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-04-30T10:29:42.341Z,1525084182.341 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 891
2018-04-30T10:29:42.342Z,1525084182.342 [CTD_Seabird](INFO): Initializing
2018-04-30T10:29:42.343Z,1525084182.343 [CTD_Seabird](INFO): Checking LCM
2018-04-30T10:29:42.446Z,1525084182.446 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 892
2018-04-30T10:29:42.450Z,1525084182.450 [WetLabsBB2FL](INFO): Powering down
2018-04-30T10:29:42.479Z,1525084182.479 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-04-30T10:29:42.534Z,1525084182.534 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 893
2018-04-30T10:29:42.589Z,1525084182.589 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-04-30T10:29:42.589Z,1525084182.589 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-04-30T10:29:42.589Z,1525084182.589 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-04-30T10:29:42.590Z,1525084182.590 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-04-30T10:29:42.590Z,1525084182.590 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-04-30T10:29:42.590Z,1525084182.590 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-04-30T10:29:42.590Z,1525084182.590 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-04-30T10:29:42.590Z,1525084182.590 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-04-30T10:29:42.590Z,1525084182.590 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-04-30T10:29:42.591Z,1525084182.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-04-30T10:29:42.591Z,1525084182.591 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-04-30T10:29:42.591Z,1525084182.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-04-30T10:29:42.591Z,1525084182.591 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-04-30T10:29:42.591Z,1525084182.591 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-04-30T10:29:42.592Z,1525084182.592 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-04-30T10:29:42.592Z,1525084182.592 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-04-30T10:29:42.602Z,1525084182.602 [MissionManager](DEBUG):
2018-04-30T10:29:42.603Z,1525084182.603 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-04-30T10:29:42.624Z,1525084182.624 [CTD_Seabird](INFO): LCM OK
2018-04-30T10:29:42.624Z,1525084182.624 [CTD_Seabird](INFO): Powering up
2018-04-30T10:29:42.625Z,1525084182.625 [Rowe_600LCM](INFO): LCM OK
2018-04-30T10:29:42.625Z,1525084182.625 [Rowe_600LCM](INFO): Powering up
2018-04-30T10:29:42.701Z,1525084182.701 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-04-30T10:29:42.702Z,1525084182.702 [Default:A.Wait](DEBUG): Construct Wait.
2018-04-30T10:29:42.704Z,1525084182.704 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-04-30T10:29:42.753Z,1525084182.753 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-04-30T10:29:42.756Z,1525084182.756 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-04-30T10:29:42.781Z,1525084182.781 [Default:E.Execute](DEBUG): Construct Execute.
2018-04-30T10:29:42.785Z,1525084182.785 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2018-04-30T10:29:42.790Z,1525084182.790 [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-04-30T10:29:42.817Z,1525084182.817 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-04-30T10:29:42.817Z,1525084182.817 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-04-30T10:29:42.839Z,1525084182.839 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-04-30T10:29:43.195Z,1525084183.195 [ESPComponent](INFO): powering down ESP
2018-04-30T10:29:43.758Z,1525084183.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:43.857Z,1525084183.857 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:43.962Z,1525084183.962 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-04-30T10:29:43.969Z,1525084183.969 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-04-30T10:29:43.988Z,1525084183.988 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-04-30T10:29:43.993Z,1525084183.993 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-04-30T10:29:44.015Z,1525084184.015 [MassServo](DEBUG): Initializing EZServoServo.
2018-04-30T10:29:44.021Z,1525084184.021 [MassServo](DEBUG): Initializing MassServo.
2018-04-30T10:29:44.034Z,1525084184.034 [RudderServo](DEBUG): Initializing EZServoServo.
2018-04-30T10:29:44.041Z,1525084184.041 [RudderServo](DEBUG): Initializing RudderServo.
2018-04-30T10:29:44.062Z,1525084184.062 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-04-30T10:29:44.069Z,1525084184.069 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-04-30T10:29:44.122Z,1525084184.122 [CommandLine](FAULT): Scheduling is paused
2018-04-30T10:29:44.122Z,1525084184.122 [CBIT](INFO): Critical error at 20180430T102942
2018-04-30T10:29:44.123Z,1525084184.123 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-04-30T10:29:44.239Z,1525084184.239 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-04-30T10:29:44.239Z,1525084184.239 [DropWeight] Hardware Fault, FailCount= 1
2018-04-30T10:29:44.239Z,1525084184.239 [DropWeight](ERROR): Hardware Fault
2018-04-30T10:29:44.304Z,1525084184.304 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:44.305Z,1525084184.305 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:44.330Z,1525084184.330 [CBIT](INFO): Critical error at 20180430T102944
2018-04-30T10:29:44.332Z,1525084184.332 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-04-30T10:29:44.333Z,1525084184.333 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-04-30T10:29:44.625Z,1525084184.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:44.626Z,1525084184.626 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:44.961Z,1525084184.961 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-04-30T10:29:44.961Z,1525084184.961 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-04-30T10:29:44.961Z,1525084184.961 [BuoyancyServo] Communications Fault, FailCount= 1
2018-04-30T10:29:44.961Z,1525084184.961 [BuoyancyServo](ERROR): Communications Fault
2018-04-30T10:29:45.178Z,1525084185.178 [CBIT](INFO): Critical error at 20180430T102944
2018-04-30T10:29:45.181Z,1525084185.181 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-04-30T10:29:45.285Z,1525084185.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:45.286Z,1525084185.286 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:45.307Z,1525084185.307 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-04-30T10:29:45.307Z,1525084185.307 [BuoyancyServo](INFO): Powering down
2018-04-30T10:29:45.677Z,1525084185.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:45.685Z,1525084185.685 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.060Z,1525084186.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.062Z,1525084186.062 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.450Z,1525084186.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.451Z,1525084186.451 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.481Z,1525084186.481 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-04-30T10:29:46.702Z,1525084186.702 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2018-04-30T10:29:46.830Z,1525084186.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.832Z,1525084186.832 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:46.879Z,1525084186.879 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-04-30T10:29:46.885Z,1525084186.885 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-04-30T10:29:46.886Z,1525084186.886 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-04-30T10:29:47.261Z,1525084187.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:47.262Z,1525084187.262 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:47.386Z,1525084187.386 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-04-30T10:29:47.386Z,1525084187.386 [BuoyancyServo] No Fault, FailCount= 1
2018-04-30T10:29:47.648Z,1525084187.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:47.662Z,1525084187.662 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:47.693Z,1525084187.693 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-04-30T10:29:47.694Z,1525084187.694 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-04-30T10:29:47.832Z,1525084187.832 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-04-30T10:29:47.940Z,1525084187.940 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-04-30T10:29:47.942Z,1525084187.942 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-04-30T10:29:48.032Z,1525084188.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:48.050Z,1525084188.050 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:48.341Z,1525084188.341 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-04-30T10:29:48.341Z,1525084188.341 [CTD_Seabird](INFO): Powering down
2018-04-30T10:29:48.450Z,1525084188.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:48.451Z,1525084188.451 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:48.841Z,1525084188.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:48.842Z,1525084188.842 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:49.222Z,1525084189.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:49.223Z,1525084189.223 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:49.649Z,1525084189.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:49.650Z,1525084189.650 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.034Z,1525084190.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.035Z,1525084190.035 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.389Z,1525084190.389 [AcousticModem_Benthos_ATM900](DEBUG):
2018-04-30T10:29:50.442Z,1525084190.442 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.443Z,1525084190.443 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.811Z,1525084190.811 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-04-30T10:29:50.811Z,1525084190.811 [PNI_TCM] Data Fault, FailCount= 1
2018-04-30T10:29:50.811Z,1525084190.811 [PNI_TCM](ERROR): Data Fault
2018-04-30T10:29:50.842Z,1525084190.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.843Z,1525084190.843 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:50.946Z,1525084190.946 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-04-30T10:29:51.211Z,1525084191.211 [PNI_TCM](INFO): Powering down
2018-04-30T10:29:51.264Z,1525084191.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:51.273Z,1525084191.273 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:51.592Z,1525084191.592 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-04-30T10:29:51.593Z,1525084191.593 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-04-30T10:29:51.594Z,1525084191.594 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-04-30T10:29:51.654Z,1525084191.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:51.655Z,1525084191.655 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:51.989Z,1525084191.989 [AcousticModem_Benthos_ATM900](DEBUG): Mar 28 2000 07:23:12
2018-04-30T10:29:52.022Z,1525084192.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:52.023Z,1525084192.023 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-04-30T10:29:52.390Z,1525084192.390 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low