2018-05-29T21:14:25.740Z,1527628465.740 [Supervisor](DEBUG): Initializing supervisor.
2018-05-29T21:14:25.742Z,1527628465.742 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-29T21:14:25.743Z,1527628465.743 [SyncHandler](INFO): Protected caller Thread ID is 3409
2018-05-29T21:14:25.743Z,1527628465.743 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-29T21:14:25.744Z,1527628465.744 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-29T21:14:25.745Z,1527628465.745 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3410
2018-05-29T21:14:25.747Z,1527628465.747 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-29T21:14:25.759Z,1527628465.759 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-29T21:14:25.760Z,1527628465.760 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-29T21:14:25.761Z,1527628465.761 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3411
2018-05-29T21:14:25.761Z,1527628465.761 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-29T21:14:25.762Z,1527628465.762 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-29T21:14:25.763Z,1527628465.763 [logger ThreadHandler](INFO): Protected caller Thread ID is 3412
2018-05-29T21:14:25.765Z,1527628465.765 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-29T21:14:25.765Z,1527628465.765 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-29T21:14:25.767Z,1527628465.767 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-29T21:14:25.864Z,1527628465.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-29T21:14:25.864Z,1527628465.864 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-29T21:14:25.967Z,1527628465.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-29T21:14:25.968Z,1527628465.968 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-29T21:14:26.179Z,1527628466.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-29T21:14:26.180Z,1527628466.180 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-29T21:14:26.519Z,1527628466.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-29T21:14:26.519Z,1527628466.519 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-29T21:14:26.875Z,1527628466.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-29T21:14:26.876Z,1527628466.876 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-29T21:14:27.435Z,1527628467.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-29T21:14:27.435Z,1527628467.435 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-29T21:14:27.846Z,1527628467.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-29T21:14:27.847Z,1527628467.847 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-29T21:14:28.327Z,1527628468.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-29T21:14:28.328Z,1527628468.328 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-29T21:14:28.408Z,1527628468.408 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-29T21:14:28.720Z,1527628468.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-29T21:14:28.721Z,1527628468.721 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-29T21:14:28.868Z,1527628468.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-29T21:14:28.868Z,1527628468.868 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-29T21:14:29.083Z,1527628469.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-29T21:14:29.084Z,1527628469.084 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-29T21:14:29.306Z,1527628469.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-29T21:14:29.307Z,1527628469.307 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-29T21:14:29.404Z,1527628469.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-29T21:14:29.405Z,1527628469.405 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-29T21:14:29.548Z,1527628469.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-29T21:14:29.550Z,1527628469.550 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-29T21:14:29.551Z,1527628469.551 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-29T21:14:29.792Z,1527628469.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-29T21:14:29.793Z,1527628469.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-29T21:14:29.869Z,1527628469.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-29T21:14:30.017Z,1527628470.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-29T21:14:30.110Z,1527628470.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-29T21:14:30.195Z,1527628470.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-29T21:14:30.335Z,1527628470.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-29T21:14:30.526Z,1527628470.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-29T21:14:30.689Z,1527628470.689 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-29T21:14:30.866Z,1527628470.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-29T21:14:31.282Z,1527628471.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-29T21:14:31.366Z,1527628471.366 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-05-29T21:14:31.366Z,1527628471.366 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-29T21:14:31.382Z,1527628471.382 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-29T21:14:31.491Z,1527628471.491 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-29T21:14:31.491Z,1527628471.491 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-29T21:14:31.505Z,1527628471.505 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-29T21:14:31.505Z,1527628471.505 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-29T21:14:31.550Z,1527628471.550 [DepthRateCalculator] Loaded
2018-05-29T21:14:31.550Z,1527628471.550 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-29T21:14:31.556Z,1527628471.556 [PitchRateCalculator] Loaded
2018-05-29T21:14:31.556Z,1527628471.556 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-29T21:14:31.573Z,1527628471.573 [SpeedCalculator] Loaded
2018-05-29T21:14:31.573Z,1527628471.573 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-29T21:14:31.596Z,1527628471.596 [TempGradientCalculator] Loaded
2018-05-29T21:14:31.596Z,1527628471.596 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-29T21:14:31.612Z,1527628471.612 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-29T21:14:31.613Z,1527628471.613 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-29T21:14:31.618Z,1527628471.618 [YawRateCalculator] Loaded
2018-05-29T21:14:31.619Z,1527628471.619 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-29T21:14:31.661Z,1527628471.661 [ElevatorOffsetCalculator] Loaded
2018-05-29T21:14:31.661Z,1527628471.661 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-29T21:14:31.662Z,1527628471.662 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-29T21:14:31.662Z,1527628471.662 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-29T21:14:31.688Z,1527628471.688 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-29T21:14:31.688Z,1527628471.688 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-29T21:14:31.978Z,1527628471.978 [AcousticModem_Benthos_ATM900] Loaded
2018-05-29T21:14:31.978Z,1527628471.978 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-29T21:14:32.047Z,1527628472.047 [DataOverHttps] Loaded
2018-05-29T21:14:32.048Z,1527628472.048 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-29T21:14:32.062Z,1527628472.062 [Depth_Keller] Loaded
2018-05-29T21:14:32.062Z,1527628472.062 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-29T21:14:32.166Z,1527628472.166 [NAL9602] Loaded
2018-05-29T21:14:32.167Z,1527628472.167 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-29T21:14:32.183Z,1527628472.183 [Onboard] Loaded
2018-05-29T21:14:32.184Z,1527628472.184 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-29T21:14:32.194Z,1527628472.194 [Radio_Surface] Loaded
2018-05-29T21:14:32.195Z,1527628472.195 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-29T21:14:32.196Z,1527628472.196 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-05-29T21:14:32.196Z,1527628472.196 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3492
2018-05-29T21:14:32.246Z,1527628472.246 [PNI_TCM] Loaded
2018-05-29T21:14:32.246Z,1527628472.246 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-29T21:14:34.133Z,1527628474.133 [BPC1] Loaded
2018-05-29T21:14:34.133Z,1527628474.133 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-29T21:14:34.134Z,1527628474.134 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-29T21:14:34.134Z,1527628474.134 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-29T21:14:34.276Z,1527628474.276 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-29T21:14:34.300Z,1527628474.300 [SBIT] Loaded
2018-05-29T21:14:34.300Z,1527628474.300 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-29T21:14:34.301Z,1527628474.301 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-29T21:14:34.313Z,1527628474.313 [IBIT] Loaded
2018-05-29T21:14:34.313Z,1527628474.313 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-29T21:14:34.316Z,1527628474.316 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-29T21:14:34.474Z,1527628474.474 [CBIT] Loaded
2018-05-29T21:14:34.474Z,1527628474.474 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-29T21:14:34.475Z,1527628474.475 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-29T21:14:34.475Z,1527628474.475 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-29T21:14:34.659Z,1527628474.659 [CTD_Seabird] Loaded
2018-05-29T21:14:34.659Z,1527628474.659 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-29T21:14:34.660Z,1527628474.660 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408774E0
2018-05-29T21:14:34.660Z,1527628474.660 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3493
2018-05-29T21:14:34.678Z,1527628474.678 [ESPComponent] Loaded
2018-05-29T21:14:34.678Z,1527628474.678 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-29T21:14:34.693Z,1527628474.693 [PAR_Licor] Loaded
2018-05-29T21:14:34.693Z,1527628474.693 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-29T21:14:34.694Z,1527628474.694 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-29T21:14:34.694Z,1527628474.694 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-29T21:14:34.954Z,1527628474.954 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-29T21:14:34.955Z,1527628474.955 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-29T21:14:35.028Z,1527628475.028 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-29T21:14:35.126Z,1527628475.126 [VerticalControl] Loaded
2018-05-29T21:14:35.126Z,1527628475.126 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-29T21:14:35.127Z,1527628475.127 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-29T21:14:35.191Z,1527628475.191 [HorizontalControl] Loaded
2018-05-29T21:14:35.191Z,1527628475.191 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-29T21:14:35.192Z,1527628475.192 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-29T21:14:35.197Z,1527628475.197 [SpeedControl] Loaded
2018-05-29T21:14:35.198Z,1527628475.198 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-29T21:14:35.198Z,1527628475.198 [LoopControl](DEBUG): Construct LoopControl.
2018-05-29T21:14:35.199Z,1527628475.199 [LoopControl] Loaded
2018-05-29T21:14:35.199Z,1527628475.199 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-29T21:14:35.200Z,1527628475.200 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-29T21:14:35.200Z,1527628475.200 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-29T21:14:35.287Z,1527628475.287 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-29T21:14:35.287Z,1527628475.287 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-29T21:14:35.287Z,1527628475.287 [StratificationFrontDetector] Loaded
2018-05-29T21:14:35.288Z,1527628475.288 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-29T21:14:35.288Z,1527628475.288 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-29T21:14:35.289Z,1527628475.289 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-29T21:14:35.395Z,1527628475.395 [BuoyancyServo] Loaded
2018-05-29T21:14:35.395Z,1527628475.395 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-29T21:14:35.407Z,1527628475.407 [ElevatorServo] Loaded
2018-05-29T21:14:35.407Z,1527628475.407 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-29T21:14:35.419Z,1527628475.419 [MassServo] Loaded
2018-05-29T21:14:35.419Z,1527628475.419 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-29T21:14:35.430Z,1527628475.430 [RudderServo] Loaded
2018-05-29T21:14:35.431Z,1527628475.431 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-29T21:14:35.442Z,1527628475.442 [ThrusterServo] Loaded
2018-05-29T21:14:35.442Z,1527628475.442 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-29T21:14:35.442Z,1527628475.442 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-29T21:14:35.443Z,1527628475.443 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-29T21:14:35.546Z,1527628475.546 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-05-29T21:14:35.546Z,1527628475.546 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-05-29T21:14:35.605Z,1527628475.605 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-29T21:14:35.605Z,1527628475.605 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-29T21:14:35.622Z,1527628475.622 [NavChart] Loaded
2018-05-29T21:14:35.623Z,1527628475.623 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-29T21:14:35.627Z,1527628475.627 [UniversalFixResidualReporter] Loaded
2018-05-29T21:14:35.627Z,1527628475.627 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-29T21:14:35.628Z,1527628475.628 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-29T21:14:35.640Z,1527628475.640 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-29T21:14:35.641Z,1527628475.641 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-29T21:14:35.650Z,1527628475.650 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-29T21:14:35.651Z,1527628475.651 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2018-05-29T21:14:35.652Z,1527628475.652 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3494
2018-05-29T21:14:35.657Z,1527628475.657 [Supervisor](INFO): Main Thread ID is 3408
2018-05-29T21:14:35.657Z,1527628475.657 [Supervisor](DEBUG): Running supervisor.
2018-05-29T21:14:35.657Z,1527628475.657 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3495
2018-05-29T21:14:35.660Z,1527628475.660 [controlThread ThreadHandler](INFO): Handler Thread ID is 3496
2018-05-29T21:14:35.660Z,1527628475.660 [controlThread](DEBUG): Initializing ControlThread
2018-05-29T21:14:35.661Z,1527628475.661 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-29T21:14:35.662Z,1527628475.662 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-29T21:14:35.662Z,1527628475.662 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-29T21:14:35.662Z,1527628475.662 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-29T21:14:35.663Z,1527628475.663 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-29T21:14:35.664Z,1527628475.664 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-29T21:14:35.664Z,1527628475.664 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-29T21:14:35.670Z,1527628475.670 [SBIT](INFO): Initialize SBIT Component.
2018-05-29T21:14:35.670Z,1527628475.670 [SBIT](IMPORTANT): git: 2018-05-22-7-gb0071a0
2018-05-29T21:14:35.671Z,1527628475.671 [SBIT](INFO): git hash: b0071a06ae6f4745c5194a3e349d38d425fd7397
2018-05-29T21:14:35.671Z,1527628475.671 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-29T21:14:35.672Z,1527628475.672 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-05-29T21:14:35.673Z,1527628475.673 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-05-29T21:14:35.674Z,1527628475.674 [IBIT](INFO): Initialize IBIT Component.
2018-05-29T21:14:35.675Z,1527628475.675 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-29T21:14:35.675Z,1527628475.675 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-05-29T21:14:35.676Z,1527628475.676 [logger ThreadHandler](INFO): Handler Thread ID is 3497
2018-05-29T21:14:35.693Z,1527628475.693 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3498
2018-05-29T21:14:35.699Z,1527628475.699 [Radio_Surface](INFO): Powering up
2018-05-29T21:14:35.705Z,1527628475.705 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3499
2018-05-29T21:14:35.706Z,1527628475.706 [CTD_Seabird](INFO): Initializing
2018-05-29T21:14:35.706Z,1527628475.706 [CTD_Seabird](INFO): Checking LCM
2018-05-29T21:14:35.715Z,1527628475.715 [CTD_Seabird](INFO): LCM OK
2018-05-29T21:14:35.715Z,1527628475.715 [CTD_Seabird](INFO): Powering up
2018-05-29T21:14:35.721Z,1527628475.721 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3500
2018-05-29T21:14:35.725Z,1527628475.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-29T21:14:35.725Z,1527628475.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-29T21:14:35.725Z,1527628475.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-29T21:14:35.725Z,1527628475.725 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-29T21:14:35.726Z,1527628475.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-29T21:14:35.726Z,1527628475.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-29T21:14:35.726Z,1527628475.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-29T21:14:35.726Z,1527628475.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-29T21:14:35.727Z,1527628475.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-29T21:14:35.727Z,1527628475.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-29T21:14:35.727Z,1527628475.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-29T21:14:35.727Z,1527628475.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-29T21:14:35.727Z,1527628475.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-29T21:14:35.728Z,1527628475.728 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-29T21:14:35.728Z,1527628475.728 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-29T21:14:35.728Z,1527628475.728 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-29T21:14:35.785Z,1527628475.785 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-29T21:14:35.787Z,1527628475.787 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-29T21:14:35.788Z,1527628475.788 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-29T21:14:35.788Z,1527628475.788 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-29T21:14:35.790Z,1527628475.790 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-29T21:14:35.791Z,1527628475.791 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-29T21:14:35.791Z,1527628475.791 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-29T21:14:35.792Z,1527628475.792 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-29T21:14:35.792Z,1527628475.792 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-29T21:14:35.793Z,1527628475.793 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-29T21:14:35.793Z,1527628475.793 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-29T21:14:35.794Z,1527628475.794 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-29T21:14:35.795Z,1527628475.795 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-29T21:14:35.826Z,1527628475.826 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-29T21:14:35.874Z,1527628475.874 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2018-05-29T21:14:35.874Z,1527628475.874 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-29T21:14:35.946Z,1527628475.946 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-29T21:14:35.947Z,1527628475.947 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-29T21:14:35.965Z,1527628475.965 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-29T21:14:35.992Z,1527628475.992 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-29T21:14:35.994Z,1527628475.994 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-29T21:14:36.016Z,1527628476.016 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-29T21:14:36.020Z,1527628476.020 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-05-29T21:14:36.029Z,1527628476.029 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-29T21:14:36.055Z,1527628476.055 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-29T21:14:36.055Z,1527628476.055 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-29T21:14:36.086Z,1527628476.086 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-29T21:14:36.347Z,1527628476.347 [ESPComponent](INFO): powering down ESP
2018-05-29T21:14:36.794Z,1527628476.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:36.818Z,1527628476.818 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:36.917Z,1527628476.917 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-29T21:14:36.925Z,1527628476.925 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-29T21:14:36.940Z,1527628476.940 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-29T21:14:36.945Z,1527628476.945 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-29T21:14:36.959Z,1527628476.959 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-29T21:14:36.965Z,1527628476.965 [MassServo](DEBUG): Initializing MassServo.
2018-05-29T21:14:36.983Z,1527628476.983 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-29T21:14:36.989Z,1527628476.989 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-29T21:14:37.010Z,1527628477.010 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-29T21:14:37.017Z,1527628477.017 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-29T21:14:37.066Z,1527628477.066 [CommandLine](FAULT): Scheduling is paused
2018-05-29T21:14:37.067Z,1527628477.067 [CBIT](INFO): Critical error at 20180529T211435
2018-05-29T21:14:37.067Z,1527628477.067 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-29T21:14:37.314Z,1527628477.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:37.316Z,1527628477.316 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:37.597Z,1527628477.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:37.598Z,1527628477.598 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:38.105Z,1527628478.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:38.106Z,1527628478.106 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:38.485Z,1527628478.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:38.486Z,1527628478.486 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:38.861Z,1527628478.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:38.862Z,1527628478.862 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:39.285Z,1527628479.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:39.286Z,1527628479.286 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:39.642Z,1527628479.642 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:39.643Z,1527628479.643 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:40.037Z,1527628480.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:40.039Z,1527628480.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:40.457Z,1527628480.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:40.458Z,1527628480.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:41.235Z,1527628481.235 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-29T21:14:41.340Z,1527628481.340 [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-29T21:14:41.410Z,1527628481.410 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-29T21:14:41.792Z,1527628481.792 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-29T21:14:41.792Z,1527628481.792 [CTD_Seabird](INFO): Powering down
2018-05-29T21:14:49.390Z,1527628489.390 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-29T21:14:49.390Z,1527628489.390 [PNI_TCM] Data Fault, FailCount= 1
2018-05-29T21:14:49.390Z,1527628489.390 [PNI_TCM](ERROR): Data Fault
2018-05-29T21:14:49.418Z,1527628489.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:49.419Z,1527628489.419 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:49.813Z,1527628489.813 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-29T21:14:49.858Z,1527628489.858 [PNI_TCM](INFO): Powering down
2018-05-29T21:14:50.015Z,1527628490.015 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:50.017Z,1527628490.017 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:50.294Z,1527628490.294 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:50.295Z,1527628490.295 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:50.628Z,1527628490.628 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004511
2018-05-29T21:14:50.662Z,1527628490.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:50.663Z,1527628490.663 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:51.071Z,1527628491.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:51.072Z,1527628491.072 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:51.166Z,1527628491.166 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-05-29T21:14:51.166Z,1527628491.166 [PNI_TCM] No Fault, FailCount= 1
2018-05-29T21:14:51.514Z,1527628491.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:51.515Z,1527628491.515 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:51.890Z,1527628491.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:51.891Z,1527628491.891 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:52.268Z,1527628492.268 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:52.277Z,1527628492.277 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:52.691Z,1527628492.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:52.692Z,1527628492.692 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:53.071Z,1527628493.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:53.072Z,1527628493.072 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:53.468Z,1527628493.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:53.469Z,1527628493.469 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:53.882Z,1527628493.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:53.883Z,1527628493.883 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:54.290Z,1527628494.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:54.291Z,1527628494.291 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:54.694Z,1527628494.694 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:54.695Z,1527628494.695 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:55.090Z,1527628495.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:55.091Z,1527628495.091 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:55.490Z,1527628495.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:55.491Z,1527628495.491 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:55.870Z,1527628495.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:55.871Z,1527628495.871 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:56.222Z,1527628496.222 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2018-05-29T21:14:56.223Z,1527628496.223 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2018-05-29T21:14:56.223Z,1527628496.223 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2018-05-29T21:14:56.290Z,1527628496.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:56.291Z,1527628496.291 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:56.346Z,1527628496.346 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2018-05-29T21:14:56.622Z,1527628496.622 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-05-29T21:14:56.833Z,1527628496.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:56.834Z,1527628496.834 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:57.103Z,1527628497.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:57.105Z,1527628497.105 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:57.472Z,1527628497.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:57.485Z,1527628497.485 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:57.558Z,1527628497.558 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2018-05-29T21:14:57.558Z,1527628497.558 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2018-05-29T21:14:57.893Z,1527628497.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:57.895Z,1527628497.895 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:58.281Z,1527628498.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:58.282Z,1527628498.282 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:58.644Z,1527628498.644 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-29T21:14:58.644Z,1527628498.644 [PNI_TCM] Data Fault, FailCount= 2
2018-05-29T21:14:58.644Z,1527628498.644 [PNI_TCM](ERROR): Data Fault
2018-05-29T21:14:58.689Z,1527628498.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:58.690Z,1527628498.690 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:58.746Z,1527628498.746 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-29T21:14:59.042Z,1527628499.042 [PNI_TCM](INFO): Powering down
2018-05-29T21:14:59.155Z,1527628499.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:59.156Z,1527628499.156 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:59.470Z,1527628499.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:59.471Z,1527628499.471 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:59.823Z,1527628499.823 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-29T21:14:59.823Z,1527628499.823 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-29T21:14:59.870Z,1527628499.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:14:59.871Z,1527628499.871 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:00.282Z,1527628500.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:00.283Z,1527628500.283 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:00.433Z,1527628500.433 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-05-29T21:15:00.433Z,1527628500.433 [PNI_TCM] No Fault, FailCount= 2
2018-05-29T21:15:00.694Z,1527628500.694 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:00.695Z,1527628500.695 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:01.082Z,1527628501.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:01.083Z,1527628501.083 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:01.494Z,1527628501.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:01.495Z,1527628501.495 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:01.899Z,1527628501.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:01.900Z,1527628501.900 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:02.270Z,1527628502.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:02.271Z,1527628502.271 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:02.686Z,1527628502.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:02.687Z,1527628502.687 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:03.074Z,1527628503.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:03.075Z,1527628503.075 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:03.501Z,1527628503.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:03.502Z,1527628503.502 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:03.892Z,1527628503.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:03.905Z,1527628503.905 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:04.292Z,1527628504.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:04.305Z,1527628504.305 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:04.705Z,1527628504.705 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:04.706Z,1527628504.706 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:05.093Z,1527628505.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:05.095Z,1527628505.095 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:05.505Z,1527628505.505 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:05.506Z,1527628505.506 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:05.905Z,1527628505.905 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:05.906Z,1527628505.906 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:06.291Z,1527628506.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:06.292Z,1527628506.292 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:06.666Z,1527628506.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:06.668Z,1527628506.668 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.119Z,1527628507.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.120Z,1527628507.120 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.422Z,1527628507.422 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-05-29T21:15:07.474Z,1527628507.474 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.476Z,1527628507.476 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.826Z,1527628507.826 [NAL9602](INFO): No messages in MT queue
2018-05-29T21:15:07.857Z,1527628507.857 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-29T21:15:07.857Z,1527628507.857 [PNI_TCM] Data Fault, FailCount= 3
2018-05-29T21:15:07.857Z,1527628507.857 [PNI_TCM](ERROR): Data Fault
2018-05-29T21:15:07.912Z,1527628507.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.925Z,1527628507.925 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:07.950Z,1527628507.950 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-29T21:15:08.249Z,1527628508.249 [PNI_TCM](INFO): Powering down
2018-05-29T21:15:08.347Z,1527628508.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:08.349Z,1527628508.349 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:08.623Z,1527628508.623 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-29T21:15:08.624Z,1527628508.624 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-29T21:15:08.625Z,1527628508.625 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-29T21:15:08.625Z,1527628508.625 [AcousticModem_Benthos_ATM900](DEBUG): Apr  6 2000 20:01:08 
2018-05-29T21:15:08.705Z,1527628508.705 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:08.707Z,1527628508.707 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:09.023Z,1527628509.023 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-05-29T21:15:09.109Z,1527628509.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:09.110Z,1527628509.110 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:09.510Z,1527628509.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:09.511Z,1527628509.511 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:09.551Z,1527628509.551 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-05-29T21:15:09.551Z,1527628509.551 [PNI_TCM] No Fault, FailCount= 3
2018-05-29T21:15:09.822Z,1527628509.822 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-05-29T21:15:09.914Z,1527628509.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:09.915Z,1527628509.915 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:10.222Z,1527628510.222 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-05-29T21:15:10.223Z,1527628510.223 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-05-29T21:15:10.223Z,1527628510.223 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-05-29T21:15:10.283Z,1527628510.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:10.284Z,1527628510.284 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:10.623Z,1527628510.623 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-05-29T21:15:10.717Z,1527628510.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:10.729Z,1527628510.729 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:11.022Z,1527628511.022 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-05-29T21:15:11.023Z,1527628511.023 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-05-29T21:15:11.107Z,1527628511.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:11.108Z,1527628511.108 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:11.423Z,1527628511.423 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-05-29T21:15:11.465Z,1527628511.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:11.466Z,1527628511.466 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:11.882Z,1527628511.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:11.884Z,1527628511.884 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:12.274Z,1527628512.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:12.275Z,1527628512.275 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:12.662Z,1527628512.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:12.663Z,1527628512.663 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:13.062Z,1527628513.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:13.063Z,1527628513.063 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:13.462Z,1527628513.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:13.463Z,1527628513.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:13.862Z,1527628513.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:13.863Z,1527628513.863 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:14.266Z,1527628514.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:14.267Z,1527628514.267 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:14.674Z,1527628514.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:14.675Z,1527628514.675 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:15.066Z,1527628515.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:15.067Z,1527628515.067 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:15.470Z,1527628515.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:15.471Z,1527628515.471 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:15.874Z,1527628515.874 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:15.875Z,1527628515.875 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:16.280Z,1527628516.280 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:16.300Z,1527628516.300 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:16.674Z,1527628516.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:16.675Z,1527628516.675 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:17.043Z,1527628517.043 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-29T21:15:17.043Z,1527628517.043 [PNI_TCM] Data Fault, FailCount= 4
2018-05-29T21:15:17.043Z,1527628517.043 [PNI_TCM](ERROR): Data Fault
2018-05-29T21:15:17.106Z,1527628517.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:17.107Z,1527628517.107 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:17.118Z,1527628517.118 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-29T21:15:17.443Z,1527628517.443 [PNI_TCM](INFO): Powering down
2018-05-29T21:15:17.565Z,1527628517.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:17.566Z,1527628517.566 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:17.866Z,1527628517.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:17.867Z,1527628517.867 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:18.278Z,1527628518.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:18.279Z,1527628518.279 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:18.669Z,1527628518.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:18.670Z,1527628518.670 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:18.694Z,1527628518.694 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-05-29T21:15:18.694Z,1527628518.694 [PNI_TCM] No Fault, FailCount= 4
2018-05-29T21:15:19.078Z,1527628519.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:19.079Z,1527628519.079 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:19.481Z,1527628519.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:19.483Z,1527628519.483 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:19.917Z,1527628519.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:19.918Z,1527628519.918 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:20.273Z,1527628520.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:20.274Z,1527628520.274 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:20.671Z,1527628520.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:20.672Z,1527628520.672 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:21.083Z,1527628521.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:21.084Z,1527628521.084 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:21.478Z,1527628521.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:21.479Z,1527628521.479 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:21.882Z,1527628521.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:21.883Z,1527628521.883 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:22.190Z,1527628522.190 [CommandLine](IMPORTANT): got command restart system
2018-05-29T21:15:22.278Z,1527628522.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:22.279Z,1527628522.279 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:22.674Z,1527628522.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:22.675Z,1527628522.675 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:23.097Z,1527628523.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:23.098Z,1527628523.098 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:23.488Z,1527628523.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:23.494Z,1527628523.494 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:23.878Z,1527628523.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:23.879Z,1527628523.879 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:24.286Z,1527628524.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:24.287Z,1527628524.287 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:24.421Z,1527628524.421 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T21:15:24.421Z,1527628524.421 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:24.553Z,1527628524.553 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-05-29T21:15:24.553Z,1527628524.553 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:24.554Z,1527628524.554 [CommandLine](INFO): Join timeout helper Thread ID is 3522
2018-05-29T21:15:24.554Z,1527628524.554 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-05-29T21:15:24.554Z,1527628524.554 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:24.555Z,1527628524.555 [NavChartDb](INFO): Join timeout helper Thread ID is 3523
2018-05-29T21:15:24.698Z,1527628524.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:24.700Z,1527628524.700 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.076Z,1527628525.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.085Z,1527628525.085 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.479Z,1527628525.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.480Z,1527628525.480 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.883Z,1527628525.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.884Z,1527628525.884 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:25.977Z,1527628525.977 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T21:15:25.978Z,1527628525.978 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:25.993Z,1527628525.993 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-05-29T21:15:25.993Z,1527628525.993 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:25.994Z,1527628525.994 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3524
2018-05-29T21:15:26.245Z,1527628526.245 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-05-29T21:15:26.246Z,1527628526.246 [PNI_TCM] Data Fault, FailCount= 5
2018-05-29T21:15:26.246Z,1527628526.246 [PNI_TCM](ERROR): Data Fault
2018-05-29T21:15:26.269Z,1527628526.269 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T21:15:26.343Z,1527628526.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:26.344Z,1527628526.344 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:26.421Z,1527628526.421 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-05-29T21:15:26.422Z,1527628526.422 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-05-29T21:15:26.483Z,1527628526.483 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-29T21:15:26.483Z,1527628526.483 [CTD_Seabird](INFO): Powering down
2018-05-29T21:15:26.484Z,1527628526.484 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.485Z,1527628526.485 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-05-29T21:15:26.485Z,1527628526.485 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.486Z,1527628526.486 [Radio_Surface](INFO): Join timeout helper Thread ID is 3526
2018-05-29T21:15:26.638Z,1527628526.638 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T21:15:26.638Z,1527628526.638 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.645Z,1527628526.645 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-05-29T21:15:26.645Z,1527628526.645 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.646Z,1527628526.646 [logger](INFO): Join timeout helper Thread ID is 3527
2018-05-29T21:15:26.654Z,1527628526.654 [PNI_TCM](INFO): Powering down
2018-05-29T21:15:26.681Z,1527628526.681 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T21:15:26.681Z,1527628526.681 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.686Z,1527628526.686 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-05-29T21:15:26.686Z,1527628526.686 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.686Z,1527628526.686 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-05-29T21:15:26.687Z,1527628526.687 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:26.687Z,1527628526.687 [controlThread](INFO): Join timeout helper Thread ID is 3528
2018-05-29T21:15:26.750Z,1527628526.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:26.751Z,1527628526.751 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-29T21:15:26.761Z,1527628526.761 [CBIT](INFO): Critical error at 20180529T211526
2018-05-29T21:15:26.765Z,1527628526.765 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-29T21:15:26.765Z,1527628526.765 [controlThread](DEBUG): Uninitializing ControlThread
2018-05-29T21:15:26.765Z,1527628526.765 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-05-29T21:15:26.886Z,1527628526.886 [NAL9602](INFO): Powering down
2018-05-29T21:15:26.886Z,1527628526.886 [PNI_TCM](INFO): Powering down
2018-05-29T21:15:26.890Z,1527628526.890 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-29T21:15:26.891Z,1527628526.891 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-05-29T21:15:26.892Z,1527628526.892 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-05-29T21:15:26.893Z,1527628526.893 [MissionManager](INFO): Uninitializing Mission Default
2018-05-29T21:15:26.896Z,1527628526.896 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-29T21:15:26.898Z,1527628526.898 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-05-29T21:15:26.898Z,1527628526.898 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-05-29T21:15:26.898Z,1527628526.898 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-05-29T21:15:26.899Z,1527628526.899 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-05-29T21:15:26.899Z,1527628526.899 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-29T21:15:26.899Z,1527628526.899 [BuoyancyServo](INFO): Powering down
2018-05-29T21:15:26.913Z,1527628526.913 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-05-29T21:15:26.913Z,1527628526.913 [ElevatorServo](INFO): Powering down
2018-05-29T21:15:26.947Z,1527628526.947 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-05-29T21:15:26.947Z,1527628526.947 [MassServo](INFO): Powering down
2018-05-29T21:15:26.948Z,1527628526.948 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-05-29T21:15:26.948Z,1527628526.948 [RudderServo](INFO): Powering down
2018-05-29T21:15:26.950Z,1527628526.950 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-29T21:15:26.950Z,1527628526.950 [ThrusterServo](INFO): Powering down
2018-05-29T21:15:26.951Z,1527628526.951 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-05-29T21:15:26.951Z,1527628526.951 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-05-29T21:15:26.951Z,1527628526.951 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-05-29T21:15:26.952Z,1527628526.952 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:27.127Z,1527628527.127 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:27.205Z,1527628527.205 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:27.390Z,1527628527.390 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-29T21:15:27.494Z,1527628527.494 [logger ThreadHandler](INFO): Thread cancelled.