2018-02-07T01:12:28.778Z,1517965948.778 [Supervisor](DEBUG): Initializing supervisor.
2018-02-07T01:12:28.781Z,1517965948.781 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-02-07T01:12:28.782Z,1517965948.782 [SyncHandler](INFO): Protected caller Thread ID is 3851
2018-02-07T01:12:28.782Z,1517965948.782 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-02-07T01:12:28.783Z,1517965948.783 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-02-07T01:12:28.783Z,1517965948.783 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3852
2018-02-07T01:12:28.786Z,1517965948.786 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-02-07T01:12:28.799Z,1517965948.799 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-02-07T01:12:28.800Z,1517965948.800 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-02-07T01:12:28.800Z,1517965948.800 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3853
2018-02-07T01:12:28.801Z,1517965948.801 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-02-07T01:12:28.802Z,1517965948.802 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-02-07T01:12:28.802Z,1517965948.802 [logger ThreadHandler](INFO): Protected caller Thread ID is 3854
2018-02-07T01:12:28.804Z,1517965948.804 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-02-07T01:12:28.805Z,1517965948.805 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-02-07T01:12:28.806Z,1517965948.806 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-02-07T01:12:28.909Z,1517965948.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-02-07T01:12:28.910Z,1517965948.910 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-02-07T01:12:29.014Z,1517965949.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-02-07T01:12:29.015Z,1517965949.015 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-02-07T01:12:29.229Z,1517965949.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-02-07T01:12:29.230Z,1517965949.230 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-02-07T01:12:29.549Z,1517965949.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-02-07T01:12:29.550Z,1517965949.550 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-02-07T01:12:29.894Z,1517965949.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-02-07T01:12:29.895Z,1517965949.895 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-02-07T01:12:30.378Z,1517965950.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-02-07T01:12:30.378Z,1517965950.378 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-02-07T01:12:30.732Z,1517965950.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-02-07T01:12:30.733Z,1517965950.733 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-02-07T01:12:31.189Z,1517965951.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-02-07T01:12:31.190Z,1517965951.190 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-02-07T01:12:31.273Z,1517965951.273 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-02-07T01:12:31.583Z,1517965951.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-02-07T01:12:31.584Z,1517965951.584 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-02-07T01:12:31.732Z,1517965951.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-02-07T01:12:31.732Z,1517965951.732 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-02-07T01:12:31.944Z,1517965951.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-02-07T01:12:31.945Z,1517965951.945 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-02-07T01:12:32.158Z,1517965952.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-02-07T01:12:32.159Z,1517965952.159 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-02-07T01:12:32.260Z,1517965952.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-02-07T01:12:32.261Z,1517965952.261 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-02-07T01:12:32.404Z,1517965952.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-02-07T01:12:32.406Z,1517965952.406 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-02-07T01:12:32.407Z,1517965952.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-02-07T01:12:32.641Z,1517965952.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-02-07T01:12:32.642Z,1517965952.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-02-07T01:12:32.720Z,1517965952.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-02-07T01:12:32.868Z,1517965952.868 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-02-07T01:12:32.963Z,1517965952.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-02-07T01:12:33.050Z,1517965953.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-02-07T01:12:33.191Z,1517965953.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-02-07T01:12:33.364Z,1517965953.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-02-07T01:12:33.463Z,1517965953.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-02-07T01:12:33.557Z,1517965953.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-02-07T01:12:33.657Z,1517965953.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-02-07T01:12:33.755Z,1517965953.755 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-02-07T01:12:33.755Z,1517965953.755 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-02-07T01:12:33.769Z,1517965953.769 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-02-07T01:12:33.950Z,1517965953.950 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-02-07T01:12:33.950Z,1517965953.950 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-02-07T01:12:33.964Z,1517965953.964 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-02-07T01:12:33.964Z,1517965953.964 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-02-07T01:12:34.005Z,1517965954.005 [DepthRateCalculator] Loaded
2018-02-07T01:12:34.005Z,1517965954.005 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-02-07T01:12:34.011Z,1517965954.011 [PitchRateCalculator] Loaded
2018-02-07T01:12:34.012Z,1517965954.012 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-02-07T01:12:34.043Z,1517965954.043 [SpeedCalculator] Loaded
2018-02-07T01:12:34.043Z,1517965954.043 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-02-07T01:12:34.164Z,1517965954.164 [TempGradientCalculator] Loaded
2018-02-07T01:12:34.164Z,1517965954.164 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-02-07T01:12:34.190Z,1517965954.190 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-02-07T01:12:34.191Z,1517965954.191 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-02-07T01:12:34.196Z,1517965954.196 [YawRateCalculator] Loaded
2018-02-07T01:12:34.197Z,1517965954.197 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-02-07T01:12:34.236Z,1517965954.236 [ElevatorOffsetCalculator] Loaded
2018-02-07T01:12:34.237Z,1517965954.237 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-02-07T01:12:34.237Z,1517965954.237 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-02-07T01:12:34.238Z,1517965954.238 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-02-07T01:12:34.315Z,1517965954.315 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-02-07T01:12:34.316Z,1517965954.316 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-02-07T01:12:34.652Z,1517965954.652 [AcousticModem_Benthos_ATM900] Loaded
2018-02-07T01:12:34.652Z,1517965954.652 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-02-07T01:12:34.729Z,1517965954.729 [DataOverHttps] Loaded
2018-02-07T01:12:34.729Z,1517965954.729 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-02-07T01:12:34.743Z,1517965954.743 [Depth_Keller] Loaded
2018-02-07T01:12:34.743Z,1517965954.743 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-02-07T01:12:34.747Z,1517965954.747 [DropWeight] Loaded
2018-02-07T01:12:34.748Z,1517965954.748 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-02-07T01:12:34.845Z,1517965954.845 [NAL9602] Loaded
2018-02-07T01:12:34.845Z,1517965954.845 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-02-07T01:12:34.861Z,1517965954.861 [Onboard] Loaded
2018-02-07T01:12:34.861Z,1517965954.861 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-02-07T01:12:34.871Z,1517965954.871 [Radio_Surface] Loaded
2018-02-07T01:12:34.872Z,1517965954.872 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-02-07T01:12:34.873Z,1517965954.873 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0
2018-02-07T01:12:34.873Z,1517965954.873 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3933
2018-02-07T01:12:34.920Z,1517965954.920 [PNI_TCM] Loaded
2018-02-07T01:12:34.920Z,1517965954.920 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-02-07T01:12:36.657Z,1517965956.657 [BPC1] Loaded
2018-02-07T01:12:36.657Z,1517965956.657 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-02-07T01:12:36.658Z,1517965956.658 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-02-07T01:12:36.658Z,1517965956.658 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-02-07T01:12:36.786Z,1517965956.786 [SBIT](DEBUG): Construct Startup Built In Test.
2018-02-07T01:12:36.809Z,1517965956.809 [SBIT] Loaded
2018-02-07T01:12:36.809Z,1517965956.809 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-02-07T01:12:36.810Z,1517965956.810 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-02-07T01:12:36.821Z,1517965956.821 [IBIT] Loaded
2018-02-07T01:12:36.821Z,1517965956.821 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-02-07T01:12:36.824Z,1517965956.824 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-02-07T01:12:36.972Z,1517965956.972 [CBIT] Loaded
2018-02-07T01:12:36.973Z,1517965956.973 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-02-07T01:12:36.973Z,1517965956.973 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-02-07T01:12:36.974Z,1517965956.974 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-02-07T01:12:37.119Z,1517965957.119 [Aanderaa_O2] Loaded
2018-02-07T01:12:37.119Z,1517965957.119 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-02-07T01:12:37.167Z,1517965957.167 [CTD_Seabird] Loaded
2018-02-07T01:12:37.167Z,1517965957.167 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-02-07T01:12:37.168Z,1517965957.168 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408654E0
2018-02-07T01:12:37.168Z,1517965957.168 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3934
2018-02-07T01:12:37.183Z,1517965957.183 [PAR_Licor] Loaded
2018-02-07T01:12:37.183Z,1517965957.183 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-02-07T01:12:37.232Z,1517965957.232 [WetLabsBB2FL] Loaded
2018-02-07T01:12:37.232Z,1517965957.232 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-02-07T01:12:37.233Z,1517965957.233 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408954E0
2018-02-07T01:12:37.233Z,1517965957.233 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3935
2018-02-07T01:12:37.234Z,1517965957.234 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-02-07T01:12:37.235Z,1517965957.235 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-02-07T01:12:37.614Z,1517965957.614 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-02-07T01:12:37.649Z,1517965957.649 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-02-07T01:12:37.716Z,1517965957.716 [VerticalControl](DEBUG): Construct VerticalControl.
2018-02-07T01:12:37.812Z,1517965957.812 [VerticalControl] Loaded
2018-02-07T01:12:37.812Z,1517965957.812 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-02-07T01:12:37.813Z,1517965957.813 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-02-07T01:12:37.874Z,1517965957.874 [HorizontalControl] Loaded
2018-02-07T01:12:37.874Z,1517965957.874 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-02-07T01:12:37.875Z,1517965957.875 [SpeedControl](DEBUG): Construct SpeedControl.
2018-02-07T01:12:37.880Z,1517965957.880 [SpeedControl] Loaded
2018-02-07T01:12:37.880Z,1517965957.880 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-02-07T01:12:37.881Z,1517965957.881 [LoopControl](DEBUG): Construct LoopControl.
2018-02-07T01:12:37.882Z,1517965957.882 [LoopControl] Loaded
2018-02-07T01:12:37.882Z,1517965957.882 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-02-07T01:12:37.882Z,1517965957.882 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-02-07T01:12:37.883Z,1517965957.883 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-02-07T01:12:37.964Z,1517965957.964 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-02-07T01:12:37.964Z,1517965957.964 [StratificationFrontDetector](DEBUG): (re)initializing
2018-02-07T01:12:37.965Z,1517965957.965 [StratificationFrontDetector] Loaded
2018-02-07T01:12:37.965Z,1517965957.965 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-02-07T01:12:37.966Z,1517965957.966 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-02-07T01:12:37.966Z,1517965957.966 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-02-07T01:12:38.062Z,1517965958.062 [BuoyancyServo] Loaded
2018-02-07T01:12:38.062Z,1517965958.062 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-02-07T01:12:38.074Z,1517965958.074 [ElevatorServo] Loaded
2018-02-07T01:12:38.074Z,1517965958.074 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-02-07T01:12:38.085Z,1517965958.085 [MassServo] Loaded
2018-02-07T01:12:38.085Z,1517965958.085 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-02-07T01:12:38.096Z,1517965958.096 [RudderServo] Loaded
2018-02-07T01:12:38.097Z,1517965958.097 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-02-07T01:12:38.108Z,1517965958.108 [ThrusterServo] Loaded
2018-02-07T01:12:38.108Z,1517965958.108 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-02-07T01:12:38.108Z,1517965958.108 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-02-07T01:12:38.109Z,1517965958.109 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-02-07T01:12:38.205Z,1517965958.205 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-02-07T01:12:38.206Z,1517965958.206 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-02-07T01:12:38.260Z,1517965958.260 [DeadReckonUsingSpeedCalculator] Loaded
2018-02-07T01:12:38.261Z,1517965958.261 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-02-07T01:12:38.277Z,1517965958.277 [NavChart] Loaded
2018-02-07T01:12:38.278Z,1517965958.278 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-02-07T01:12:38.282Z,1517965958.282 [UniversalFixResidualReporter] Loaded
2018-02-07T01:12:38.282Z,1517965958.282 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-02-07T01:12:38.282Z,1517965958.282 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-02-07T01:12:38.286Z,1517965958.286 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-02-07T01:12:38.287Z,1517965958.287 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-02-07T01:12:38.294Z,1517965958.294 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-02-07T01:12:38.295Z,1517965958.295 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2018-02-07T01:12:38.295Z,1517965958.295 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3936
2018-02-07T01:12:38.300Z,1517965958.300 [Supervisor](INFO): Main Thread ID is 795
2018-02-07T01:12:38.300Z,1517965958.300 [Supervisor](DEBUG): Running supervisor.
2018-02-07T01:12:38.301Z,1517965958.301 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3937
2018-02-07T01:12:38.304Z,1517965958.304 [controlThread ThreadHandler](INFO): Handler Thread ID is 3938
2018-02-07T01:12:38.304Z,1517965958.304 [controlThread](DEBUG): Initializing ControlThread
2018-02-07T01:12:38.305Z,1517965958.305 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-07T01:12:38.305Z,1517965958.305 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-02-07T01:12:38.306Z,1517965958.306 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-02-07T01:12:38.306Z,1517965958.306 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-02-07T01:12:38.306Z,1517965958.306 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-02-07T01:12:38.307Z,1517965958.307 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-02-07T01:12:38.307Z,1517965958.307 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-02-07T01:12:38.315Z,1517965958.315 [SBIT](INFO): Initialize SBIT Component.
2018-02-07T01:12:38.316Z,1517965958.316 [SBIT](IMPORTANT): git: 2018-01-24-20-g7b7df60
2018-02-07T01:12:38.316Z,1517965958.316 [SBIT](INFO): git hash: 7b7df607dd4c62d1014105a1bb40f7706504dcf4
2018-02-07T01:12:38.316Z,1517965958.316 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-02-07T01:12:38.317Z,1517965958.317 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2018-02-07T01:12:38.318Z,1517965958.318 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-02-07T01:12:38.319Z,1517965958.319 [IBIT](INFO): Initialize IBIT Component.
2018-02-07T01:12:38.320Z,1517965958.320 [CBIT](DEBUG): Initialize CBIT Component.
2018-02-07T01:12:38.320Z,1517965958.320 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-02-07T01:12:38.321Z,1517965958.321 [logger ThreadHandler](INFO): Handler Thread ID is 3939
2018-02-07T01:12:38.341Z,1517965958.341 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3940
2018-02-07T01:12:38.347Z,1517965958.347 [Radio_Surface](INFO): Powering up
2018-02-07T01:12:38.353Z,1517965958.353 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3941
2018-02-07T01:12:38.354Z,1517965958.354 [CTD_Seabird](INFO): Initializing
2018-02-07T01:12:38.354Z,1517965958.354 [CTD_Seabird](INFO): Checking LCM
2018-02-07T01:12:38.363Z,1517965958.363 [CTD_Seabird](INFO): LCM OK
2018-02-07T01:12:38.364Z,1517965958.364 [CTD_Seabird](INFO): Powering up
2018-02-07T01:12:38.369Z,1517965958.369 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3942
2018-02-07T01:12:38.371Z,1517965958.371 [WetLabsBB2FL](INFO): Powering down
2018-02-07T01:12:38.397Z,1517965958.397 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3943
2018-02-07T01:12:38.401Z,1517965958.401 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-02-07T01:12:38.401Z,1517965958.401 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-02-07T01:12:38.402Z,1517965958.402 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-02-07T01:12:38.402Z,1517965958.402 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-02-07T01:12:38.402Z,1517965958.402 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-02-07T01:12:38.402Z,1517965958.402 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-02-07T01:12:38.402Z,1517965958.402 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-02-07T01:12:38.402Z,1517965958.402 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-02-07T01:12:38.403Z,1517965958.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-02-07T01:12:38.403Z,1517965958.403 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-02-07T01:12:38.403Z,1517965958.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-02-07T01:12:38.403Z,1517965958.403 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-02-07T01:12:38.403Z,1517965958.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-02-07T01:12:38.404Z,1517965958.404 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-02-07T01:12:38.404Z,1517965958.404 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-02-07T01:12:38.404Z,1517965958.404 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-02-07T01:12:38.429Z,1517965958.429 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-02-07T01:12:38.431Z,1517965958.431 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-02-07T01:12:38.431Z,1517965958.431 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-02-07T01:12:38.432Z,1517965958.432 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-02-07T01:12:38.434Z,1517965958.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-07T01:12:38.434Z,1517965958.434 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-07T01:12:38.435Z,1517965958.435 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-07T01:12:38.435Z,1517965958.435 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-07T01:12:38.436Z,1517965958.436 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-07T01:12:38.436Z,1517965958.436 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-07T01:12:38.436Z,1517965958.436 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-02-07T01:12:38.437Z,1517965958.437 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-02-07T01:12:38.438Z,1517965958.438 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-02-07T01:12:38.457Z,1517965958.457 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-07T01:12:38.487Z,1517965958.487 [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-02-07T01:12:38.488Z,1517965958.488 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-02-07T01:12:38.557Z,1517965958.557 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-02-07T01:12:38.558Z,1517965958.558 [Default:A.Wait](DEBUG): Construct Wait.
2018-02-07T01:12:38.560Z,1517965958.560 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-02-07T01:12:38.601Z,1517965958.601 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-02-07T01:12:38.604Z,1517965958.604 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-02-07T01:12:38.630Z,1517965958.630 [Default:E.Execute](DEBUG): Construct Execute.
2018-02-07T01:12:38.633Z,1517965958.633 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <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-02-07T01:12:38.645Z,1517965958.645 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,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-02-07T01:12:38.657Z,1517965958.657 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-02-07T01:12:38.657Z,1517965958.657 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-02-07T01:12:38.684Z,1517965958.684 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-02-07T01:12:38.910Z,1517965958.910 [PNI_TCM](INFO): Start
2018-02-07T01:12:39.174Z,1517965959.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:39.201Z,1517965959.201 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:39.293Z,1517965959.293 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-07T01:12:39.301Z,1517965959.301 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-07T01:12:39.323Z,1517965959.323 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-02-07T01:12:39.329Z,1517965959.329 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-02-07T01:12:39.343Z,1517965959.343 [MassServo](DEBUG): Initializing EZServoServo.
2018-02-07T01:12:39.345Z,1517965959.345 [MassServo](DEBUG): Initializing MassServo.
2018-02-07T01:12:39.367Z,1517965959.367 [RudderServo](DEBUG): Initializing EZServoServo.
2018-02-07T01:12:39.373Z,1517965959.373 [RudderServo](DEBUG): Initializing RudderServo.
2018-02-07T01:12:39.378Z,1517965959.378 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-02-07T01:12:39.385Z,1517965959.385 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-02-07T01:12:39.401Z,1517965959.401 [CommandLine](FAULT): Scheduling is paused
2018-02-07T01:12:39.401Z,1517965959.401 [CBIT](INFO): Critical error at 20180207T011238
2018-02-07T01:12:39.402Z,1517965959.402 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-02-07T01:12:39.522Z,1517965959.522 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-02-07T01:12:39.522Z,1517965959.522 [DropWeight] Hardware Fault, FailCount= 1
2018-02-07T01:12:39.522Z,1517965959.522 [DropWeight](ERROR): Hardware Fault
2018-02-07T01:12:39.549Z,1517965959.549 [PNI_TCM](INFO): Starting
2018-02-07T01:12:39.597Z,1517965959.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:39.598Z,1517965959.598 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:39.622Z,1517965959.622 [CBIT](INFO): Critical error at 20180207T011239
2018-02-07T01:12:39.624Z,1517965959.624 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-02-07T01:12:39.625Z,1517965959.625 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-02-07T01:12:39.882Z,1517965959.882 [PNI_TCM](INFO): Starting
2018-02-07T01:12:39.910Z,1517965959.910 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:39.911Z,1517965959.911 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:40.241Z,1517965960.241 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-02-07T01:12:40.241Z,1517965960.241 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-02-07T01:12:40.241Z,1517965960.241 [BuoyancyServo] Communications Fault, FailCount= 1
2018-02-07T01:12:40.241Z,1517965960.241 [BuoyancyServo](ERROR): Communications Fault
2018-02-07T01:12:40.471Z,1517965960.471 [CBIT](INFO): Critical error at 20180207T011239
2018-02-07T01:12:40.473Z,1517965960.473 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-02-07T01:12:40.503Z,1517965960.503 [PNI_TCM](INFO): Starting
2018-02-07T01:12:40.520Z,1517965960.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:40.529Z,1517965960.529 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:40.542Z,1517965960.542 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-07T01:12:40.543Z,1517965960.543 [BuoyancyServo](INFO): Powering down
2018-02-07T01:12:40.902Z,1517965960.902 [PNI_TCM](INFO): Starting
2018-02-07T01:12:40.944Z,1517965960.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:40.945Z,1517965960.945 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:41.302Z,1517965961.302 [PNI_TCM](INFO): Starting
2018-02-07T01:12:41.333Z,1517965961.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:41.334Z,1517965961.334 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:41.702Z,1517965961.702 [PNI_TCM](INFO): Starting
2018-02-07T01:12:41.732Z,1517965961.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:41.741Z,1517965961.741 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:42.102Z,1517965962.102 [PNI_TCM](INFO): Starting
2018-02-07T01:12:42.233Z,1517965962.233 [Aanderaa_O2](INFO): Powering down
2018-02-07T01:12:42.332Z,1517965962.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:42.341Z,1517965962.341 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-02-07T01:12:42.511Z,1517965962.511 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:42.659Z,1517965962.659 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-02-07T01:12:42.659Z,1517965962.659 [BuoyancyServo] No Fault, FailCount= 1
2018-02-07T01:12:42.908Z,1517965962.908 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:43.014Z,1517965963.014 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-02-07T01:12:43.134Z,1517965963.134 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-02-07T01:12:43.339Z,1517965963.339 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:43.870Z,1517965963.870 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:44.243Z,1517965964.243 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:44.377Z,1517965964.377 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-07T01:12:44.460Z,1517965964.460 [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-02-07T01:12:44.506Z,1517965964.506 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-02-07T01:12:44.660Z,1517965964.660 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:44.923Z,1517965964.923 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-07T01:12:44.923Z,1517965964.923 [CTD_Seabird](INFO): Powering down
2018-02-07T01:12:45.036Z,1517965965.036 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:45.437Z,1517965965.437 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:45.837Z,1517965965.837 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:46.208Z,1517965966.208 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-02-07T01:12:46.238Z,1517965966.238 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:46.662Z,1517965966.662 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:47.037Z,1517965967.037 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:47.410Z,1517965967.410 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-02-07T01:12:47.410Z,1517965967.410 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-02-07T01:12:47.411Z,1517965967.411 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-02-07T01:12:47.411Z,1517965967.411 [AcousticModem_Benthos_ATM900](DEBUG): Feb 28 2000 15:40:25 
2018-02-07T01:12:47.438Z,1517965967.438 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:47.809Z,1517965967.809 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-02-07T01:12:47.838Z,1517965967.838 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:48.249Z,1517965968.249 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:48.608Z,1517965968.608 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-02-07T01:12:48.636Z,1517965968.636 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:49.008Z,1517965969.008 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-02-07T01:12:49.009Z,1517965969.009 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-02-07T01:12:49.009Z,1517965969.009 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-02-07T01:12:49.049Z,1517965969.049 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:49.408Z,1517965969.408 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-02-07T01:12:49.453Z,1517965969.453 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:49.808Z,1517965969.808 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-02-07T01:12:49.809Z,1517965969.809 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-02-07T01:12:49.836Z,1517965969.836 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:50.209Z,1517965970.209 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-02-07T01:12:50.238Z,1517965970.238 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:50.637Z,1517965970.637 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:51.045Z,1517965971.045 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:51.450Z,1517965971.450 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:51.838Z,1517965971.838 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:52.249Z,1517965972.249 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:52.638Z,1517965972.638 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:53.070Z,1517965973.070 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:53.437Z,1517965973.437 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:53.837Z,1517965973.837 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:54.237Z,1517965974.237 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:54.637Z,1517965974.637 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:55.037Z,1517965975.037 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:55.437Z,1517965975.437 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:55.837Z,1517965975.837 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:56.241Z,1517965976.241 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:56.637Z,1517965976.637 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:57.037Z,1517965977.037 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:57.439Z,1517965977.439 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:57.836Z,1517965977.836 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:58.237Z,1517965978.237 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:58.639Z,1517965978.639 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:59.042Z,1517965979.042 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:59.438Z,1517965979.438 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:12:59.837Z,1517965979.837 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:00.237Z,1517965980.237 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:00.638Z,1517965980.638 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:01.039Z,1517965981.039 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:01.436Z,1517965981.436 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:01.837Z,1517965981.837 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:02.237Z,1517965982.237 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:02.637Z,1517965982.637 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:03.038Z,1517965983.038 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:03.437Z,1517965983.437 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:03.848Z,1517965983.848 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:04.255Z,1517965984.255 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:04.641Z,1517965984.641 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:05.014Z,1517965985.014 [NAL9602](INFO): Powering up NAL9602
2018-02-07T01:13:05.038Z,1517965985.038 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:05.438Z,1517965985.438 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:05.841Z,1517965985.841 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:06.245Z,1517965986.245 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:06.654Z,1517965986.654 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:07.039Z,1517965987.039 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:07.444Z,1517965987.444 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:07.836Z,1517965987.836 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:08.237Z,1517965988.237 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:08.640Z,1517965988.640 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:09.040Z,1517965989.040 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:09.437Z,1517965989.437 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:09.852Z,1517965989.852 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:10.237Z,1517965990.237 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:10.646Z,1517965990.646 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:11.045Z,1517965991.045 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:11.445Z,1517965991.445 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:11.845Z,1517965991.845 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:12.250Z,1517965992.250 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:12.641Z,1517965992.641 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:13.037Z,1517965993.037 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:13.436Z,1517965993.436 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:13.840Z,1517965993.840 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:14.250Z,1517965994.250 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:14.637Z,1517965994.637 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:15.049Z,1517965995.049 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:15.456Z,1517965995.456 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:15.826Z,1517965995.826 [NAL9602](INFO): NAL9602 initialized
2018-02-07T01:13:15.850Z,1517965995.850 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:16.255Z,1517965996.255 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:16.653Z,1517965996.653 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:17.064Z,1517965997.064 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:17.476Z,1517965997.476 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:17.837Z,1517965997.837 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:18.236Z,1517965998.236 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:18.645Z,1517965998.645 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:19.069Z,1517965999.069 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:19.449Z,1517965999.449 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:19.846Z,1517965999.846 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:20.249Z,1517966000.249 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:20.657Z,1517966000.657 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:21.051Z,1517966001.051 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:21.444Z,1517966001.444 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:21.849Z,1517966001.849 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:22.250Z,1517966002.250 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:22.638Z,1517966002.638 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:23.037Z,1517966003.037 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:23.446Z,1517966003.446 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:23.856Z,1517966003.856 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:24.247Z,1517966004.247 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:24.646Z,1517966004.646 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:25.063Z,1517966005.063 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:25.455Z,1517966005.455 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:25.842Z,1517966005.842 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:26.241Z,1517966006.241 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:26.659Z,1517966006.659 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:27.041Z,1517966007.041 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:27.448Z,1517966007.448 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:27.848Z,1517966007.848 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:28.257Z,1517966008.257 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:28.649Z,1517966008.649 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:29.040Z,1517966009.040 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:29.464Z,1517966009.464 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:29.860Z,1517966009.860 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:30.243Z,1517966010.243 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:30.647Z,1517966010.647 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:31.045Z,1517966011.045 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:31.119Z,1517966011.119 [SBIT](IMPORTANT): Beginning Startup BIT
2018-02-07T01:13:31.131Z,1517966011.131 [CBIT](IMPORTANT): Beginning ground fault scan
2018-02-07T01:13:31.848Z,1517966011.848 [PNI_TCM](ERROR): CRC does not match. Expected:0x46093 got:0x49853
2018-02-07T01:13:32.258Z,1517966012.258 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:32.649Z,1517966012.649 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:33.609Z,1517966013.609 [PNI_TCM](ERROR): CRC does not match. Expected:0x4059 got:0x33294
2018-02-07T01:13:34.013Z,1517966014.013 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:34.398Z,1517966014.398 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:34.797Z,1517966014.797 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:35.201Z,1517966015.201 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:35.602Z,1517966015.602 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:35.993Z,1517966015.993 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:36.389Z,1517966016.389 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:36.793Z,1517966016.793 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:37.194Z,1517966017.194 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:37.596Z,1517966017.596 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:38.007Z,1517966018.007 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:38.393Z,1517966018.393 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:38.793Z,1517966018.793 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:39.193Z,1517966019.193 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:39.597Z,1517966019.597 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:39.997Z,1517966019.997 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:40.401Z,1517966020.401 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:40.797Z,1517966020.797 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:41.197Z,1517966021.197 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:41.602Z,1517966021.602 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:41.998Z,1517966021.998 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:42.082Z,1517966022.082 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008002
CHAN A1 (24V): -0.011268
CHAN A2 (12V): -0.005487
CHAN A3 (5V): -0.002649
CHAN B0 (3.3V): -0.001113
CHAN B1 (3.15aV): -0.000949
CHAN B2 (3.15bV): -0.001602
CHAN B3 (GND): 0.000109
OPEN: 0.004314
Full Scale Calc: 4.765 mA, -1.589 mA
2018-02-07T01:13:42.412Z,1517966022.412 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:42.800Z,1517966022.800 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:43.192Z,1517966023.192 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:43.596Z,1517966023.596 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:43.997Z,1517966023.997 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:44.403Z,1517966024.403 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:44.798Z,1517966024.798 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:45.197Z,1517966025.197 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:45.599Z,1517966025.599 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:46.000Z,1517966026.000 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:46.397Z,1517966026.397 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:47.011Z,1517966027.011 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:47.393Z,1517966027.393 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:47.794Z,1517966027.794 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:48.193Z,1517966028.193 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:48.593Z,1517966028.593 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:48.989Z,1517966028.989 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:49.392Z,1517966029.392 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:49.789Z,1517966029.789 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:50.189Z,1517966030.189 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:50.593Z,1517966030.593 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:50.993Z,1517966030.993 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:51.397Z,1517966031.397 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:51.797Z,1517966031.797 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:52.197Z,1517966032.197 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:52.599Z,1517966032.599 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:52.996Z,1517966032.996 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:53.399Z,1517966033.399 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:53.806Z,1517966033.806 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:54.199Z,1517966034.199 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:54.597Z,1517966034.597 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:55.001Z,1517966035.001 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:55.398Z,1517966035.398 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:55.800Z,1517966035.800 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:56.193Z,1517966036.193 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:56.593Z,1517966036.593 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:56.993Z,1517966036.993 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:57.389Z,1517966037.389 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:57.789Z,1517966037.789 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:58.193Z,1517966038.193 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:58.593Z,1517966038.593 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:58.993Z,1517966038.993 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:59.397Z,1517966039.397 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:13:59.802Z,1517966039.802 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:00.197Z,1517966040.197 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:00.600Z,1517966040.600 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:00.996Z,1517966040.996 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:01.400Z,1517966041.400 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:01.803Z,1517966041.803 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:02.198Z,1517966042.198 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:02.599Z,1517966042.599 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:03.003Z,1517966043.003 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:03.398Z,1517966043.398 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:03.800Z,1517966043.800 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:04.202Z,1517966044.202 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:04.598Z,1517966044.598 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:04.992Z,1517966044.992 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:05.392Z,1517966045.392 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:05.789Z,1517966045.789 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:06.198Z,1517966046.198 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:06.572Z,1517966046.572 [NAL9602](INFO): SBD MO Status=2, MOMSN=21449, MT Status=2, MTMSN=0
2018-02-07T01:14:06.572Z,1517966046.572 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-07T01:14:06.617Z,1517966046.617 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:07.012Z,1517966047.012 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:07.397Z,1517966047.397 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:07.797Z,1517966047.797 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:08.193Z,1517966048.193 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:08.597Z,1517966048.597 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:08.998Z,1517966048.998 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:09.395Z,1517966049.395 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:09.792Z,1517966049.792 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:10.192Z,1517966050.192 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:10.596Z,1517966050.596 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:11.012Z,1517966051.012 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:11.408Z,1517966051.408 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:11.789Z,1517966051.789 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:12.197Z,1517966052.197 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:12.596Z,1517966052.596 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:12.598Z,1517966052.598 [BPC1](ERROR): Failed to receive battery data
2018-02-07T01:14:12.598Z,1517966052.598 [BPC1] Communications Fault, FailCount= 1
2018-02-07T01:14:12.598Z,1517966052.598 [BPC1](ERROR): Communications Fault
2018-02-07T01:14:12.619Z,1517966052.619 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-07T01:14:12.988Z,1517966052.988 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:13.401Z,1517966053.401 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:13.928Z,1517966053.928 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:14.059Z,1517966054.059 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-07T01:14:14.059Z,1517966054.059 [BPC1] No Fault, FailCount= 1
2018-02-07T01:14:14.315Z,1517966054.315 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:15.117Z,1517966055.117 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:15.517Z,1517966055.517 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:15.914Z,1517966055.914 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:16.712Z,1517966056.712 [PNI_TCM](ERROR): CRC does not match. Expected:0x5924 got:0x7618
2018-02-07T01:14:17.118Z,1517966057.118 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:17.512Z,1517966057.512 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:18.313Z,1517966058.313 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:18.711Z,1517966058.711 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:19.112Z,1517966059.112 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:19.913Z,1517966059.913 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:20.313Z,1517966060.313 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:20.712Z,1517966060.712 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:21.510Z,1517966061.510 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:21.903Z,1517966061.903 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:22.312Z,1517966062.312 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:23.109Z,1517966063.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:23.508Z,1517966063.508 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:23.908Z,1517966063.908 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:24.701Z,1517966064.701 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:25.106Z,1517966065.106 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:25.154Z,1517966065.154 [SBIT](IMPORTANT): SBIT PASSED
2018-02-07T01:14:25.505Z,1517966065.505 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:25.553Z,1517966065.553 [MissionManager](IMPORTANT): Started mission Startup
2018-02-07T01:14:25.554Z,1517966065.554 [Startup] Running Loop=1
2018-02-07T01:14:25.554Z,1517966065.554 [Startup](DEBUG): Aggregate::initialize Startup
2018-02-07T01:14:25.554Z,1517966065.554 [Startup:A.GoToSurface] Running Loop=1
2018-02-07T01:14:25.554Z,1517966065.554 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-02-07T01:14:25.555Z,1517966065.555 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-02-07T01:14:25.555Z,1517966065.555 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-02-07T01:14:25.555Z,1517966065.555 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-02-07T01:14:25.556Z,1517966065.556 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2018-02-07T01:14:25.556Z,1517966065.556 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-02-07T01:14:25.563Z,1517966065.563 [Startup:StartupSatComms] Running Loop=1
2018-02-07T01:14:25.563Z,1517966065.563 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-02-07T01:14:25.563Z,1517966065.563 [Startup:StartupSatComms:A] Running Loop=1
2018-02-07T01:14:25.943Z,1517966065.943 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-02-07T01:14:26.309Z,1517966066.309 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:26.717Z,1517966066.717 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:27.088Z,1517966067.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=21449, MT Status=2, MTMSN=0
2018-02-07T01:14:27.088Z,1517966067.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-02-07T01:14:27.113Z,1517966067.113 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:27.906Z,1517966067.906 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:28.308Z,1517966068.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:28.715Z,1517966068.715 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:29.511Z,1517966069.511 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:29.916Z,1517966069.916 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:30.312Z,1517966070.312 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:31.112Z,1517966071.112 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:31.522Z,1517966071.522 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:31.922Z,1517966071.922 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:32.710Z,1517966072.710 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:33.109Z,1517966073.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:33.510Z,1517966073.510 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:33.901Z,1517966073.901 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:34.309Z,1517966074.309 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:34.720Z,1517966074.720 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:35.124Z,1517966075.124 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:35.913Z,1517966075.913 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:36.301Z,1517966076.301 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:36.701Z,1517966076.701 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:37.101Z,1517966077.101 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:37.523Z,1517966077.523 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:37.901Z,1517966077.901 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:38.305Z,1517966078.305 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:38.701Z,1517966078.701 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:39.101Z,1517966079.101 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:39.506Z,1517966079.506 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:39.901Z,1517966079.901 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:40.305Z,1517966080.305 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:40.704Z,1517966080.704 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:41.107Z,1517966081.107 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:41.510Z,1517966081.510 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:41.911Z,1517966081.911 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:42.308Z,1517966082.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:42.708Z,1517966082.708 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:43.118Z,1517966083.118 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:43.517Z,1517966083.517 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:43.909Z,1517966083.909 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:44.316Z,1517966084.316 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:44.708Z,1517966084.708 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:45.109Z,1517966085.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:45.503Z,1517966085.503 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:45.907Z,1517966085.907 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:46.312Z,1517966086.312 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:46.716Z,1517966086.716 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:47.108Z,1517966087.108 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:47.520Z,1517966087.520 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:47.922Z,1517966087.922 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:48.301Z,1517966088.301 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:48.721Z,1517966088.721 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:49.126Z,1517966089.126 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:49.512Z,1517966089.512 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:49.514Z,1517966089.514 [BPC1](ERROR): Failed to receive battery data
2018-02-07T01:14:49.514Z,1517966089.514 [BPC1] Communications Fault, FailCount= 2
2018-02-07T01:14:49.514Z,1517966089.514 [BPC1](ERROR): Communications Fault
2018-02-07T01:14:49.545Z,1517966089.545 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-07T01:14:49.909Z,1517966089.909 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:50.313Z,1517966090.313 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:50.715Z,1517966090.715 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:50.743Z,1517966090.743 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-07T01:14:50.743Z,1517966090.743 [BPC1] No Fault, FailCount= 2
2018-02-07T01:14:51.103Z,1517966091.103 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:51.502Z,1517966091.502 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:51.902Z,1517966091.902 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:52.302Z,1517966092.302 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:52.702Z,1517966092.702 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:53.106Z,1517966093.106 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:53.501Z,1517966093.501 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:53.906Z,1517966093.906 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:54.307Z,1517966094.307 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:54.708Z,1517966094.708 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:55.109Z,1517966095.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:55.507Z,1517966095.507 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:55.911Z,1517966095.911 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:56.308Z,1517966096.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:56.708Z,1517966096.708 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:57.123Z,1517966097.123 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:57.520Z,1517966097.520 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:57.910Z,1517966097.910 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:58.309Z,1517966098.309 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:58.702Z,1517966098.702 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:59.101Z,1517966099.101 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:59.501Z,1517966099.501 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:14:59.905Z,1517966099.905 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:00.301Z,1517966100.301 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:00.702Z,1517966100.702 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:01.105Z,1517966101.105 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:01.501Z,1517966101.501 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:01.906Z,1517966101.906 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:02.309Z,1517966102.309 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:02.709Z,1517966102.709 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:03.109Z,1517966103.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:03.506Z,1517966103.506 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:03.914Z,1517966103.914 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:04.308Z,1517966104.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:04.713Z,1517966104.713 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:05.109Z,1517966105.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:05.510Z,1517966105.510 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:05.923Z,1517966105.923 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:06.327Z,1517966106.327 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:06.702Z,1517966106.702 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:07.102Z,1517966107.102 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:07.506Z,1517966107.506 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:07.902Z,1517966107.902 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:08.307Z,1517966108.307 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:08.706Z,1517966108.706 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:09.110Z,1517966109.110 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:09.510Z,1517966109.510 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:09.916Z,1517966109.916 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:10.325Z,1517966110.325 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:10.723Z,1517966110.723 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:11.113Z,1517966111.113 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:11.509Z,1517966111.509 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:11.908Z,1517966111.908 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:12.308Z,1517966112.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:12.709Z,1517966112.709 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:13.110Z,1517966113.110 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:13.500Z,1517966113.500 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:13.901Z,1517966113.901 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:14.313Z,1517966114.313 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:14.720Z,1517966114.720 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:15.112Z,1517966115.112 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:15.515Z,1517966115.515 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:15.916Z,1517966115.916 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:16.301Z,1517966116.301 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:16.705Z,1517966116.705 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:17.105Z,1517966117.105 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:17.505Z,1517966117.505 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:17.908Z,1517966117.908 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:18.309Z,1517966118.309 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:18.714Z,1517966118.714 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:19.108Z,1517966119.108 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:19.513Z,1517966119.513 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:19.916Z,1517966119.916 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:20.317Z,1517966120.317 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:20.718Z,1517966120.718 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:21.108Z,1517966121.108 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:21.507Z,1517966121.507 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:21.904Z,1517966121.904 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:22.310Z,1517966122.310 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:22.712Z,1517966122.712 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:23.112Z,1517966123.112 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:23.518Z,1517966123.518 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:23.912Z,1517966123.912 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:24.320Z,1517966124.320 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:24.719Z,1517966124.719 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:25.109Z,1517966125.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:25.509Z,1517966125.509 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:25.901Z,1517966125.901 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:25.937Z,1517966125.937 [Startup:StartupSatComms:A](INFO): Timed out from 2018-02-07T01:14:25.6Z
2018-02-07T01:15:25.938Z,1517966125.938 [Startup:StartupSatComms:A] Stopped
2018-02-07T01:15:25.938Z,1517966125.938 [Startup:StartupSatComms:B] Running Loop=1
2018-02-07T01:15:26.305Z,1517966126.305 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:26.342Z,1517966126.342 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-02-07T01:15:26.705Z,1517966126.705 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:27.101Z,1517966127.101 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:27.506Z,1517966127.506 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:27.901Z,1517966127.901 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:28.301Z,1517966128.301 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:28.719Z,1517966128.719 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:29.120Z,1517966129.120 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:29.511Z,1517966129.511 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:29.910Z,1517966129.910 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:30.310Z,1517966130.310 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:30.708Z,1517966130.708 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:31.108Z,1517966131.108 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:31.509Z,1517966131.509 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:31.910Z,1517966131.910 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:32.310Z,1517966132.310 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:32.712Z,1517966132.712 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:33.109Z,1517966133.109 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:33.504Z,1517966133.504 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:33.904Z,1517966133.904 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:34.308Z,1517966134.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:34.708Z,1517966134.708 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:35.116Z,1517966135.116 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:35.513Z,1517966135.513 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:35.911Z,1517966135.911 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:36.316Z,1517966136.316 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:36.710Z,1517966136.710 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:37.122Z,1517966137.122 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:37.518Z,1517966137.518 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:37.909Z,1517966137.909 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:38.308Z,1517966138.308 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:38.711Z,1517966138.711 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:38.721Z,1517966138.721 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-07T01:15:38.721Z,1517966138.721 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-02-07T01:15:38.722Z,1517966138.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-02-07T01:15:38.723Z,1517966138.723 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-02-07T01:15:38.723Z,1517966138.723 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-02-07T01:15:38.723Z,1517966138.723 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-02-07T01:15:38.735Z,1517966138.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-02-07T01:15:38.736Z,1517966138.736 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-02-07T01:15:39.110Z,1517966139.110 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:39.145Z,1517966139.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-02-07T01:15:39.146Z,1517966139.146 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-02-07T01:15:39.146Z,1517966139.146 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-02-07T01:15:39.146Z,1517966139.146 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-02-07T01:15:39.514Z,1517966139.514 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:39.523Z,1517966139.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-02-07T01:15:39.523Z,1517966139.523 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-02-07T01:15:39.523Z,1517966139.523 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-02-07T01:15:39.524Z,1517966139.524 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-02-07T01:15:39.524Z,1517966139.524 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-02-07T01:15:39.524Z,1517966139.524 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-02-07T01:15:39.925Z,1517966139.925 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:39.940Z,1517966139.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-02-07T01:15:43.032Z,1517966143.032 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:43.050Z,1517966143.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2018-02-07T01:15:43.224Z,1517966143.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-02-07T01:15:43.533Z,1517966143.533 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:43.548Z,1517966143.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-02-07T01:15:43.901Z,1517966143.901 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003730
2018-02-07T01:15:43.945Z,1517966143.945 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:43.954Z,1517966143.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-02-07T01:15:44.942Z,1517966144.942 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:44.956Z,1517966144.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.2 s old, using for 20.0 s.
2018-02-07T01:15:45.081Z,1517966145.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-02-07T01:15:48.155Z,1517966148.155 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:48.164Z,1517966148.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s.
2018-02-07T01:15:48.234Z,1517966148.234 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180207T010314/Courier0008.lzma
2018-02-07T01:15:48.311Z,1517966148.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-02-07T01:15:48.652Z,1517966148.652 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:48.668Z,1517966148.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2018-02-07T01:15:49.022Z,1517966149.022 [DataOverHttps](INFO): Moved sent file to Logs/20180207T010314/Courier0008.lzma.bak
2018-02-07T01:15:49.022Z,1517966149.022 [DataOverHttps](INFO): SBD MOMSN=7830413
2018-02-07T01:15:49.074Z,1517966149.074 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:49.107Z,1517966149.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-02-07T01:15:50.120Z,1517966150.120 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:50.151Z,1517966150.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-02-07T01:15:50.297Z,1517966150.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-02-07T01:15:53.776Z,1517966153.776 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:53.805Z,1517966153.805 [BPC1](ERROR): Failed to receive battery data
2018-02-07T01:15:53.805Z,1517966153.805 [BPC1] Communications Fault, FailCount= 1
2018-02-07T01:15:53.805Z,1517966153.805 [BPC1](ERROR): Communications Fault
2018-02-07T01:15:53.822Z,1517966153.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2018-02-07T01:15:53.895Z,1517966153.895 [CBIT](ERROR): Communications Fault in component: BPC1
2018-02-07T01:15:54.024Z,1517966154.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-02-07T01:15:54.357Z,1517966154.357 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:54.431Z,1517966154.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-02-07T01:15:54.755Z,1517966154.755 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:54.793Z,1517966154.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-02-07T01:15:54.830Z,1517966154.830 [CBIT](INFO): Clearing failed state for component BPC1
2018-02-07T01:15:54.830Z,1517966154.830 [BPC1] No Fault, FailCount= 1
2018-02-07T01:15:55.142Z,1517966155.142 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:55.256Z,1517966155.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-02-07T01:15:55.547Z,1517966155.547 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:15:55.631Z,1517966155.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2018-02-07T01:16:01.845Z,1517966161.845 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:16:01.882Z,1517966161.882 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20180207T011228/Courier0000.lzma
2018-02-07T01:16:02.326Z,1517966162.326 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:16:02.682Z,1517966162.682 [DataOverHttps](INFO): Moved sent file to Logs/20180207T011228/Courier0000.lzma.bak
2018-02-07T01:16:02.682Z,1517966162.682 [DataOverHttps](INFO): SBD MOMSN=7830415
2018-02-07T01:16:02.734Z,1517966162.734 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:16:04.304Z,1517966164.304 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:16:04.463Z,1517966164.463 [CommandLine](IMPORTANT): got command restart application
2018-02-07T01:16:05.468Z,1517966165.468 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:05.468Z,1517966165.468 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:05.625Z,1517966165.625 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-02-07T01:16:05.625Z,1517966165.625 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:05.626Z,1517966165.626 [CommandLine](INFO): Join timeout helper Thread ID is 3972
2018-02-07T01:16:05.629Z,1517966165.629 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-02-07T01:16:05.629Z,1517966165.629 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:05.632Z,1517966165.632 [NavChartDb](INFO): Join timeout helper Thread ID is 3973
2018-02-07T01:16:05.865Z,1517966165.865 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:05.865Z,1517966165.865 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:05.877Z,1517966165.877 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-02-07T01:16:05.877Z,1517966165.877 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:05.877Z,1517966165.877 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3974
2018-02-07T01:16:06.097Z,1517966166.097 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:06.097Z,1517966166.097 [WetLabsBB2FL](INFO): Powering down
2018-02-07T01:16:06.098Z,1517966166.098 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.109Z,1517966166.109 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-02-07T01:16:06.109Z,1517966166.109 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.109Z,1517966166.109 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3975
2018-02-07T01:16:06.253Z,1517966166.253 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:06.402Z,1517966166.402 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-02-07T01:16:06.402Z,1517966166.402 [CTD_Seabird](INFO): Powering down
2018-02-07T01:16:06.403Z,1517966166.403 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.421Z,1517966166.421 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-02-07T01:16:06.421Z,1517966166.421 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.421Z,1517966166.421 [Radio_Surface](INFO): Join timeout helper Thread ID is 3977
2018-02-07T01:16:06.529Z,1517966166.529 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:06.529Z,1517966166.529 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.534Z,1517966166.534 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-02-07T01:16:06.534Z,1517966166.534 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.534Z,1517966166.534 [logger](INFO): Join timeout helper Thread ID is 3978
2018-02-07T01:16:06.561Z,1517966166.561 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:06.561Z,1517966166.561 [logger ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.570Z,1517966166.570 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-02-07T01:16:06.570Z,1517966166.570 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.570Z,1517966166.570 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-02-07T01:16:06.570Z,1517966166.570 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:06.570Z,1517966166.570 [controlThread](INFO): Join timeout helper Thread ID is 3979
2018-02-07T01:16:08.022Z,1517966168.022 [PNI_TCM](ERROR): Received 0x
2018-02-07T01:16:08.049Z,1517966168.049 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-02-07T01:16:08.049Z,1517966168.049 [controlThread](DEBUG): Uninitializing ControlThread
2018-02-07T01:16:08.050Z,1517966168.050 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-02-07T01:16:08.172Z,1517966168.172 [NAL9602](INFO): Powering down
2018-02-07T01:16:08.174Z,1517966168.174 [PNI_TCM](INFO): uninitialize
2018-02-07T01:16:08.174Z,1517966168.174 [PNI_TCM](INFO): Powering down
2018-02-07T01:16:08.262Z,1517966168.262 [Aanderaa_O2](INFO): Powering down
2018-02-07T01:16:08.263Z,1517966168.263 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-02-07T01:16:08.264Z,1517966168.264 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-02-07T01:16:08.265Z,1517966168.265 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-02-07T01:16:08.266Z,1517966168.266 [MissionManager](INFO): Uninitializing Mission Default
2018-02-07T01:16:08.268Z,1517966168.268 [MissionManager](INFO): Uninitializing Mission Startup
2018-02-07T01:16:08.269Z,1517966168.269 [Startup] Stopped
2018-02-07T01:16:08.269Z,1517966168.269 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-02-07T01:16:08.269Z,1517966168.269 [Startup:A.GoToSurface] Stopped
2018-02-07T01:16:08.269Z,1517966168.269 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-02-07T01:16:08.269Z,1517966168.269 [Startup:StartupSatComms] Stopped
2018-02-07T01:16:08.269Z,1517966168.269 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-02-07T01:16:08.270Z,1517966168.270 [Startup:StartupSatComms:B] Stopped
2018-02-07T01:16:08.271Z,1517966168.271 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-02-07T01:16:08.272Z,1517966168.272 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-02-07T01:16:08.272Z,1517966168.272 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-02-07T01:16:08.272Z,1517966168.272 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-02-07T01:16:08.273Z,1517966168.273 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-02-07T01:16:08.273Z,1517966168.273 [BuoyancyServo](INFO): Powering down
2018-02-07T01:16:08.285Z,1517966168.285 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-02-07T01:16:08.285Z,1517966168.285 [ElevatorServo](INFO): Powering down
2018-02-07T01:16:08.286Z,1517966168.286 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-02-07T01:16:08.286Z,1517966168.286 [MassServo](INFO): Powering down
2018-02-07T01:16:08.287Z,1517966168.287 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-02-07T01:16:08.287Z,1517966168.287 [RudderServo](INFO): Powering down
2018-02-07T01:16:08.288Z,1517966168.288 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-02-07T01:16:08.289Z,1517966168.289 [ThrusterServo](INFO): Powering down
2018-02-07T01:16:08.290Z,1517966168.290 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-02-07T01:16:08.290Z,1517966168.290 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-02-07T01:16:08.290Z,1517966168.290 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-02-07T01:16:08.292Z,1517966168.292 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:08.460Z,1517966168.460 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:08.830Z,1517966168.830 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:08.842Z,1517966168.842 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:09.047Z,1517966169.047 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-02-07T01:16:09.247Z,1517966169.247 [logger ThreadHandler](INFO): Thread cancelled.