2017-10-30T22:37:58.778Z,1509403078.778 [Supervisor](DEBUG): Initializing supervisor.
2017-10-30T22:37:58.781Z,1509403078.781 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-10-30T22:37:58.782Z,1509403078.782 [SyncHandler](INFO): Protected caller Thread ID is 2443
2017-10-30T22:37:58.782Z,1509403078.782 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-10-30T22:37:58.783Z,1509403078.783 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-10-30T22:37:58.783Z,1509403078.783 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2444
2017-10-30T22:37:58.786Z,1509403078.786 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-10-30T22:37:58.796Z,1509403078.796 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-10-30T22:37:58.797Z,1509403078.797 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-10-30T22:37:58.798Z,1509403078.798 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2445
2017-10-30T22:37:58.799Z,1509403078.799 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-10-30T22:37:58.799Z,1509403078.799 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-10-30T22:37:58.800Z,1509403078.800 [logger ThreadHandler](INFO): Protected caller Thread ID is 2446
2017-10-30T22:37:58.802Z,1509403078.802 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-10-30T22:37:58.802Z,1509403078.802 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-10-30T22:37:58.806Z,1509403078.806 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-10-30T22:37:58.900Z,1509403078.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-10-30T22:37:58.902Z,1509403078.902 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-10-30T22:37:58.999Z,1509403078.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-10-30T22:37:59.001Z,1509403079.001 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-10-30T22:37:59.178Z,1509403079.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-10-30T22:37:59.179Z,1509403079.179 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-10-30T22:37:59.426Z,1509403079.426 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-10-30T22:37:59.428Z,1509403079.428 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-10-30T22:37:59.732Z,1509403079.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-10-30T22:37:59.733Z,1509403079.733 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-10-30T22:38:00.154Z,1509403080.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-10-30T22:38:00.154Z,1509403080.154 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-10-30T22:38:00.469Z,1509403080.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-10-30T22:38:00.470Z,1509403080.470 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-10-30T22:38:00.864Z,1509403080.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-10-30T22:38:00.865Z,1509403080.865 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-10-30T22:38:00.945Z,1509403080.945 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-10-30T22:38:01.219Z,1509403081.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-10-30T22:38:01.220Z,1509403081.220 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-10-30T22:38:01.431Z,1509403081.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-10-30T22:38:01.432Z,1509403081.432 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-10-30T22:38:01.625Z,1509403081.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-10-30T22:38:01.625Z,1509403081.625 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-10-30T22:38:01.813Z,1509403081.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-10-30T22:38:01.814Z,1509403081.814 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-10-30T22:38:01.908Z,1509403081.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-10-30T22:38:01.908Z,1509403081.908 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-10-30T22:38:02.041Z,1509403082.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-10-30T22:38:02.043Z,1509403082.043 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-10-30T22:38:02.045Z,1509403082.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-10-30T22:38:02.266Z,1509403082.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-10-30T22:38:02.266Z,1509403082.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-10-30T22:38:02.343Z,1509403082.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-10-30T22:38:02.478Z,1509403082.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-10-30T22:38:02.589Z,1509403082.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-10-30T22:38:03.112Z,1509403083.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-10-30T22:38:03.244Z,1509403083.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-10-30T22:38:03.399Z,1509403083.399 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-10-30T22:38:03.495Z,1509403083.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-10-30T22:38:03.585Z,1509403083.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-10-30T22:38:03.680Z,1509403083.680 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-10-30T22:38:03.763Z,1509403083.763 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2017-10-30T22:38:03.763Z,1509403083.763 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-10-30T22:38:03.766Z,1509403083.766 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-10-30T22:38:03.890Z,1509403083.890 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-10-30T22:38:03.891Z,1509403083.891 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-10-30T22:38:03.914Z,1509403083.914 [Module Loader](CRITICAL): Could not find element LcmListener.loadAtStartup
2017-10-30T22:38:03.914Z,1509403083.914 [Module Loader](CRITICAL): Could not find element LcmPublisher.loadAtStartup
2017-10-30T22:38:03.915Z,1509403083.915 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-10-30T22:38:03.915Z,1509403083.915 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-10-30T22:38:03.951Z,1509403083.951 [DepthRateCalculator] Loaded
2017-10-30T22:38:03.951Z,1509403083.951 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-10-30T22:38:03.957Z,1509403083.957 [PitchRateCalculator] Loaded
2017-10-30T22:38:03.957Z,1509403083.957 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-10-30T22:38:03.973Z,1509403083.973 [SpeedCalculator] Loaded
2017-10-30T22:38:03.973Z,1509403083.973 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-10-30T22:38:03.993Z,1509403083.993 [TempGradientCalculator] Loaded
2017-10-30T22:38:03.994Z,1509403083.994 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-10-30T22:38:04.008Z,1509403084.008 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-10-30T22:38:04.009Z,1509403084.009 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-10-30T22:38:04.014Z,1509403084.014 [YawRateCalculator] Loaded
2017-10-30T22:38:04.014Z,1509403084.014 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-10-30T22:38:04.015Z,1509403084.015 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-10-30T22:38:04.015Z,1509403084.015 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-10-30T22:38:04.052Z,1509403084.052 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-10-30T22:38:04.052Z,1509403084.052 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-10-30T22:38:04.499Z,1509403084.499 [AcousticModem_Benthos_ATM900] Loaded
2017-10-30T22:38:04.499Z,1509403084.499 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2017-10-30T22:38:04.636Z,1509403084.636 [DataOverHttps] Loaded
2017-10-30T22:38:04.637Z,1509403084.637 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-10-30T22:38:04.650Z,1509403084.650 [Depth_Keller] Loaded
2017-10-30T22:38:04.650Z,1509403084.650 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-10-30T22:38:04.655Z,1509403084.655 [DropWeight] Loaded
2017-10-30T22:38:04.655Z,1509403084.655 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-10-30T22:38:04.750Z,1509403084.750 [NAL9602] Loaded
2017-10-30T22:38:04.750Z,1509403084.750 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-10-30T22:38:04.766Z,1509403084.766 [Onboard] Loaded
2017-10-30T22:38:04.766Z,1509403084.766 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-10-30T22:38:04.776Z,1509403084.776 [Radio_Surface] Loaded
2017-10-30T22:38:04.776Z,1509403084.776 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-10-30T22:38:04.777Z,1509403084.777 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0
2017-10-30T22:38:04.778Z,1509403084.778 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2526
2017-10-30T22:38:04.823Z,1509403084.823 [PNI_TCM] Loaded
2017-10-30T22:38:04.823Z,1509403084.823 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-10-30T22:38:04.881Z,1509403084.881 [Rowe_600LCM] Loaded
2017-10-30T22:38:04.882Z,1509403084.882 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-10-30T22:38:04.883Z,1509403084.883 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0
2017-10-30T22:38:04.883Z,1509403084.883 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 2527
2017-10-30T22:38:06.572Z,1509403086.572 [BPC1] Loaded
2017-10-30T22:38:06.572Z,1509403086.572 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-10-30T22:38:06.572Z,1509403086.572 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-10-30T22:38:06.574Z,1509403086.574 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-10-30T22:38:06.726Z,1509403086.726 [SBIT](DEBUG): Construct Startup Built In Test.
2017-10-30T22:38:06.752Z,1509403086.752 [SBIT] Loaded
2017-10-30T22:38:06.752Z,1509403086.752 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-10-30T22:38:06.753Z,1509403086.753 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-10-30T22:38:06.764Z,1509403086.764 [IBIT] Loaded
2017-10-30T22:38:06.764Z,1509403086.764 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-10-30T22:38:06.767Z,1509403086.767 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-10-30T22:38:06.911Z,1509403086.911 [CBIT] Loaded
2017-10-30T22:38:06.911Z,1509403086.911 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-10-30T22:38:06.912Z,1509403086.912 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-10-30T22:38:06.912Z,1509403086.912 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-10-30T22:38:07.084Z,1509403087.084 [Aanderaa_O2] Loaded
2017-10-30T22:38:07.084Z,1509403087.084 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-10-30T22:38:07.160Z,1509403087.160 [CTD_NeilBrown] Loaded
2017-10-30T22:38:07.161Z,1509403087.161 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-10-30T22:38:07.162Z,1509403087.162 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408944E0
2017-10-30T22:38:07.162Z,1509403087.162 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2528
2017-10-30T22:38:07.178Z,1509403087.178 [ESPComponent] Loaded
2017-10-30T22:38:07.178Z,1509403087.178 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-10-30T22:38:07.192Z,1509403087.192 [PAR_Licor] Loaded
2017-10-30T22:38:07.192Z,1509403087.192 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-10-30T22:38:07.239Z,1509403087.239 [WetLabsBB2FL] Loaded
2017-10-30T22:38:07.239Z,1509403087.239 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-10-30T22:38:07.240Z,1509403087.240 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C44E0
2017-10-30T22:38:07.241Z,1509403087.241 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2529
2017-10-30T22:38:07.241Z,1509403087.241 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-10-30T22:38:07.242Z,1509403087.242 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-10-30T22:38:07.511Z,1509403087.511 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-10-30T22:38:07.512Z,1509403087.512 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-10-30T22:38:07.599Z,1509403087.599 [VerticalControl](DEBUG): Construct VerticalControl.
2017-10-30T22:38:07.694Z,1509403087.694 [VerticalControl] Loaded
2017-10-30T22:38:07.694Z,1509403087.694 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-10-30T22:38:07.695Z,1509403087.695 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-10-30T22:38:07.753Z,1509403087.753 [HorizontalControl] Loaded
2017-10-30T22:38:07.753Z,1509403087.753 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-10-30T22:38:07.754Z,1509403087.754 [SpeedControl](DEBUG): Construct SpeedControl.
2017-10-30T22:38:07.759Z,1509403087.759 [SpeedControl] Loaded
2017-10-30T22:38:07.760Z,1509403087.760 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-10-30T22:38:07.761Z,1509403087.761 [LoopControl](DEBUG): Construct LoopControl.
2017-10-30T22:38:07.761Z,1509403087.761 [LoopControl] Loaded
2017-10-30T22:38:07.761Z,1509403087.761 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-10-30T22:38:07.762Z,1509403087.762 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-10-30T22:38:07.762Z,1509403087.762 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-10-30T22:38:08.160Z,1509403088.160 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-10-30T22:38:08.160Z,1509403088.160 [StratificationFrontDetector](DEBUG): (re)initializing
2017-10-30T22:38:08.160Z,1509403088.160 [StratificationFrontDetector] Loaded
2017-10-30T22:38:08.160Z,1509403088.160 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-10-30T22:38:08.161Z,1509403088.161 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-10-30T22:38:08.162Z,1509403088.162 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-10-30T22:38:08.277Z,1509403088.277 [BuoyancyServo] Loaded
2017-10-30T22:38:08.277Z,1509403088.277 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-10-30T22:38:08.288Z,1509403088.288 [ElevatorServo] Loaded
2017-10-30T22:38:08.288Z,1509403088.288 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-10-30T22:38:08.299Z,1509403088.299 [MassServo] Loaded
2017-10-30T22:38:08.299Z,1509403088.299 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-10-30T22:38:08.310Z,1509403088.310 [RudderServo] Loaded
2017-10-30T22:38:08.310Z,1509403088.310 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-10-30T22:38:08.321Z,1509403088.321 [ThrusterServo] Loaded
2017-10-30T22:38:08.321Z,1509403088.321 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-10-30T22:38:08.321Z,1509403088.321 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-10-30T22:38:08.323Z,1509403088.323 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-10-30T22:38:08.433Z,1509403088.433 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-10-30T22:38:08.433Z,1509403088.433 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-10-30T22:38:08.486Z,1509403088.486 [DeadReckonUsingSpeedCalculator] Loaded
2017-10-30T22:38:08.486Z,1509403088.486 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-10-30T22:38:08.502Z,1509403088.502 [NavChart] Loaded
2017-10-30T22:38:08.502Z,1509403088.502 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-10-30T22:38:08.506Z,1509403088.506 [UniversalFixResidualReporter] Loaded
2017-10-30T22:38:08.506Z,1509403088.506 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-10-30T22:38:08.507Z,1509403088.507 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-10-30T22:38:08.510Z,1509403088.510 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-10-30T22:38:08.511Z,1509403088.511 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-10-30T22:38:08.518Z,1509403088.518 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-10-30T22:38:08.519Z,1509403088.519 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2017-10-30T22:38:08.519Z,1509403088.519 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2530
2017-10-30T22:38:08.524Z,1509403088.524 [Supervisor](INFO): Main Thread ID is 2442
2017-10-30T22:38:08.524Z,1509403088.524 [Supervisor](DEBUG): Running supervisor.
2017-10-30T22:38:08.525Z,1509403088.525 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2531
2017-10-30T22:38:08.527Z,1509403088.527 [controlThread ThreadHandler](INFO): Handler Thread ID is 2532
2017-10-30T22:38:08.528Z,1509403088.528 [controlThread](DEBUG): Initializing ControlThread
2017-10-30T22:38:08.529Z,1509403088.529 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-30T22:38:08.529Z,1509403088.529 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-10-30T22:38:08.529Z,1509403088.529 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-10-30T22:38:08.530Z,1509403088.530 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-10-30T22:38:08.530Z,1509403088.530 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-10-30T22:38:08.531Z,1509403088.531 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-10-30T22:38:08.537Z,1509403088.537 [SBIT](INFO): Initialize SBIT Component.
2017-10-30T22:38:08.537Z,1509403088.537 [SBIT](IMPORTANT): git: 2017-10-16-91-g1cda494
2017-10-30T22:38:08.537Z,1509403088.537 [SBIT](INFO): git hash: 1cda4942dadce3e3c6eb9510eb040eaf1a2b49d6
2017-10-30T22:38:08.538Z,1509403088.538 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-10-30T22:38:08.539Z,1509403088.539 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #17 PREEMPT Fri Oct 6 17:22:04 PDT 2017
2017-10-30T22:38:08.540Z,1509403088.540 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-10-30T22:38:08.540Z,1509403088.540 [IBIT](INFO): Initialize IBIT Component.
2017-10-30T22:38:08.541Z,1509403088.541 [CBIT](DEBUG): Initialize CBIT Component.
2017-10-30T22:38:08.541Z,1509403088.541 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-10-30T22:38:08.542Z,1509403088.542 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-10-30T22:38:08.542Z,1509403088.542 [logger ThreadHandler](INFO): Handler Thread ID is 2533
2017-10-30T22:38:08.562Z,1509403088.562 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2534
2017-10-30T22:38:08.568Z,1509403088.568 [Radio_Surface](INFO): Powering up
2017-10-30T22:38:08.574Z,1509403088.574 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 2535
2017-10-30T22:38:08.575Z,1509403088.575 [Rowe_600LCM](INFO): Initializing
2017-10-30T22:38:08.575Z,1509403088.575 [Rowe_600LCM](INFO): Checking LCM
2017-10-30T22:38:08.630Z,1509403088.630 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-10-30T22:38:08.632Z,1509403088.632 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-10-30T22:38:08.633Z,1509403088.633 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-10-30T22:38:08.633Z,1509403088.633 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-10-30T22:38:08.680Z,1509403088.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-30T22:38:08.681Z,1509403088.681 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:38:08.681Z,1509403088.681 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:38:08.682Z,1509403088.682 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-30T22:38:08.682Z,1509403088.682 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:38:08.682Z,1509403088.682 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:38:08.683Z,1509403088.683 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-10-30T22:38:08.683Z,1509403088.683 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-10-30T22:38:08.684Z,1509403088.684 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-10-30T22:38:08.685Z,1509403088.685 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2536
2017-10-30T22:38:08.686Z,1509403088.686 [CTD_NeilBrown](INFO): Powering down
2017-10-30T22:38:08.699Z,1509403088.699 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2537
2017-10-30T22:38:08.716Z,1509403088.716 [WetLabsBB2FL](INFO): Powering down
2017-10-30T22:38:08.734Z,1509403088.734 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2538
2017-10-30T22:38:08.855Z,1509403088.855 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-30T22:38:08.860Z,1509403088.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-10-30T22:38:08.860Z,1509403088.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-10-30T22:38:08.860Z,1509403088.860 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-10-30T22:38:08.860Z,1509403088.860 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-10-30T22:38:08.861Z,1509403088.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-10-30T22:38:08.861Z,1509403088.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-10-30T22:38:08.861Z,1509403088.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-10-30T22:38:08.861Z,1509403088.861 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-10-30T22:38:08.861Z,1509403088.861 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-10-30T22:38:08.862Z,1509403088.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-10-30T22:38:08.862Z,1509403088.862 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-10-30T22:38:08.862Z,1509403088.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-10-30T22:38:08.862Z,1509403088.862 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-10-30T22:38:08.862Z,1509403088.862 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-10-30T22:38:08.863Z,1509403088.863 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-10-30T22:38:08.863Z,1509403088.863 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-10-30T22:38:08.954Z,1509403088.954 [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>


2017-10-30T22:38:08.955Z,1509403088.955 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-10-30T22:38:09.063Z,1509403089.063 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-10-30T22:38:09.064Z,1509403089.064 [Default:A.Wait](DEBUG): Construct Wait.
2017-10-30T22:38:09.070Z,1509403089.070 [Rowe_600LCM](INFO): LCM OK
2017-10-30T22:38:09.070Z,1509403089.070 [Rowe_600LCM](INFO): Powering up
2017-10-30T22:38:09.074Z,1509403089.074 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-30T22:38:09.096Z,1509403089.096 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-10-30T22:38:09.122Z,1509403089.122 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-10-30T22:38:09.128Z,1509403089.128 [Default:E.Execute](DEBUG): Construct Execute.
2017-10-30T22:38:09.158Z,1509403089.158 [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>


2017-10-30T22:38:09.177Z,1509403089.177 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-10-30T22:38:09.210Z,1509403089.210 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:38:09.210Z,1509403089.210 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:38:09.236Z,1509403089.236 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-10-30T22:38:09.573Z,1509403089.573 [ESPComponent](INFO): powering down ESP
2017-10-30T22:38:10.064Z,1509403090.064 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:10.155Z,1509403090.155 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:10.266Z,1509403090.266 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-10-30T22:38:10.274Z,1509403090.274 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-10-30T22:38:10.289Z,1509403090.289 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-10-30T22:38:10.294Z,1509403090.294 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-10-30T22:38:10.308Z,1509403090.308 [MassServo](DEBUG): Initializing EZServoServo.
2017-10-30T22:38:10.314Z,1509403090.314 [MassServo](DEBUG): Initializing MassServo.
2017-10-30T22:38:10.336Z,1509403090.336 [RudderServo](DEBUG): Initializing EZServoServo.
2017-10-30T22:38:10.342Z,1509403090.342 [RudderServo](DEBUG): Initializing RudderServo.
2017-10-30T22:38:10.348Z,1509403090.348 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-10-30T22:38:10.354Z,1509403090.354 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-10-30T22:38:10.371Z,1509403090.371 [CommandLine](FAULT): Scheduling is paused
2017-10-30T22:38:10.371Z,1509403090.371 [CBIT](INFO): Critical error at 20171030T223803
2017-10-30T22:38:10.371Z,1509403090.371 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-10-30T22:38:10.580Z,1509403090.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:10.581Z,1509403090.581 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:13.012Z,1509403093.012 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-30T22:38:13.141Z,1509403093.141 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-30T22:38:13.268Z,1509403093.268 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-30T22:38:13.269Z,1509403093.269 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-30T22:38:13.269Z,1509403093.269 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-30T22:38:20.093Z,1509403100.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:20.102Z,1509403100.102 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:20.475Z,1509403100.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:20.476Z,1509403100.476 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:20.886Z,1509403100.886 [Aanderaa_O2](INFO): Powering down
2017-10-30T22:38:20.990Z,1509403100.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:20.991Z,1509403100.991 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:21.214Z,1509403101.214 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003226
2017-10-30T22:38:21.254Z,1509403101.254 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:21.255Z,1509403101.255 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:21.662Z,1509403101.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:21.663Z,1509403101.663 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:22.062Z,1509403102.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:22.070Z,1509403102.070 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-30T22:38:25.241Z,1509403105.241 [BPC1](ERROR): Failed to parse bank B battery data
2017-10-30T22:38:25.241Z,1509403105.241 [BPC1] Data Fault, FailCount= 1
2017-10-30T22:38:25.241Z,1509403105.241 [BPC1](ERROR): Data Fault
2017-10-30T22:38:25.323Z,1509403105.323 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:38:26.590Z,1509403106.590 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:38:26.590Z,1509403106.590 [BPC1] No Fault, FailCount= 1
2017-10-30T22:38:29.609Z,1509403109.609 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:38:29.609Z,1509403109.609 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2017-10-30T22:38:29.609Z,1509403109.609 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:38:29.726Z,1509403109.726 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:38:30.009Z,1509403110.009 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:38:30.934Z,1509403110.934 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:38:30.934Z,1509403110.934 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2017-10-30T22:38:33.209Z,1509403113.209 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:38:33.209Z,1509403113.209 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:38:34.831Z,1509403114.831 [NAL9602](INFO): Powering up NAL9602
2017-10-30T22:38:46.015Z,1509403126.015 [NAL9602](INFO): NAL9602 initialized
2017-10-30T22:38:47.275Z,1509403127.275 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:38:47.275Z,1509403127.275 [BPC1] Data Fault, FailCount= 2
2017-10-30T22:38:47.275Z,1509403127.275 [BPC1](ERROR): Data Fault
2017-10-30T22:38:47.371Z,1509403127.371 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:38:48.547Z,1509403128.547 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:38:48.547Z,1509403128.547 [BPC1] No Fault, FailCount= 2
2017-10-30T22:38:53.609Z,1509403133.609 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:38:53.609Z,1509403133.609 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2017-10-30T22:38:53.609Z,1509403133.609 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:38:53.742Z,1509403133.742 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:38:54.009Z,1509403134.009 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:38:54.930Z,1509403134.930 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:38:54.930Z,1509403134.930 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2017-10-30T22:38:57.209Z,1509403137.209 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:38:57.209Z,1509403137.209 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:39:02.439Z,1509403142.439 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:39:02.439Z,1509403142.439 [BPC1] Data Fault, FailCount= 3
2017-10-30T22:39:02.439Z,1509403142.439 [BPC1](ERROR): Data Fault
2017-10-30T22:39:02.502Z,1509403142.502 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:39:03.700Z,1509403143.700 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:39:03.700Z,1509403143.700 [BPC1] No Fault, FailCount= 3
2017-10-30T22:39:13.501Z,1509403153.501 [SBIT](IMPORTANT): Beginning Startup BIT
2017-10-30T22:39:13.509Z,1509403153.509 [CBIT](IMPORTANT): Beginning ground fault scan
2017-10-30T22:39:17.505Z,1509403157.505 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:39:17.505Z,1509403157.505 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2017-10-30T22:39:17.505Z,1509403157.505 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:39:17.734Z,1509403157.734 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:39:17.901Z,1509403157.901 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:39:18.737Z,1509403158.737 [BPC1](ERROR): Failed to parse bank B battery data
2017-10-30T22:39:18.737Z,1509403158.737 [BPC1] Data Fault, FailCount= 4
2017-10-30T22:39:18.737Z,1509403158.737 [BPC1](ERROR): Data Fault
2017-10-30T22:39:18.845Z,1509403158.845 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:39:18.845Z,1509403158.845 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2017-10-30T22:39:18.864Z,1509403158.864 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:39:20.035Z,1509403160.035 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:39:20.035Z,1509403160.035 [BPC1] No Fault, FailCount= 4
2017-10-30T22:39:21.101Z,1509403161.101 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:39:21.101Z,1509403161.101 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:39:24.361Z,1509403164.361 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008562
CHAN A1 (24V): 0.006901
CHAN A2 (12V): -0.005569
CHAN A3 (5V): -0.002283
CHAN B0 (3.3V): -0.001664
CHAN B1 (3.15aV): -0.001473
CHAN B2 (3.15bV): -0.001249
CHAN B3 (GND): 0.000462
OPEN: 0.004441
Full Scale Calc: 4.765 mA, -1.589 mA
2017-10-30T22:39:41.036Z,1509403181.036 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:39:41.036Z,1509403181.036 [BPC1] Data Fault, FailCount= 5
2017-10-30T22:39:41.036Z,1509403181.036 [BPC1](ERROR): Data Fault
2017-10-30T22:39:41.088Z,1509403181.088 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:39:41.388Z,1509403181.388 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:39:41.388Z,1509403181.388 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2017-10-30T22:39:41.388Z,1509403181.388 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:39:41.493Z,1509403181.493 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:39:41.781Z,1509403181.781 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:39:42.239Z,1509403182.239 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:39:42.239Z,1509403182.239 [BPC1] No Fault, FailCount= 5
2017-10-30T22:39:42.692Z,1509403182.692 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:39:42.692Z,1509403182.692 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2017-10-30T22:39:44.981Z,1509403184.981 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:39:44.981Z,1509403184.981 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:39:55.813Z,1509403195.813 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:39:55.813Z,1509403195.813 [BPC1] Data Fault, FailCount= 6
2017-10-30T22:39:55.813Z,1509403195.813 [BPC1](ERROR): Data Fault
2017-10-30T22:39:56.318Z,1509403196.318 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:39:57.314Z,1509403197.314 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:39:57.315Z,1509403197.315 [BPC1] No Fault, FailCount= 6
2017-10-30T22:40:05.125Z,1509403205.125 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:40:05.125Z,1509403205.125 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2017-10-30T22:40:05.125Z,1509403205.125 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:40:05.202Z,1509403205.202 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:40:05.525Z,1509403205.525 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:40:06.397Z,1509403206.397 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:40:06.397Z,1509403206.397 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2017-10-30T22:40:07.594Z,1509403207.594 [SBIT](IMPORTANT): SBIT PASSED
2017-10-30T22:40:07.986Z,1509403207.986 [MissionManager](IMPORTANT): Started mission Startup
2017-10-30T22:40:07.987Z,1509403207.987 [Startup] Running Loop=1
2017-10-30T22:40:07.987Z,1509403207.987 [Startup](DEBUG): Aggregate::initialize Startup
2017-10-30T22:40:07.987Z,1509403207.987 [Startup:A.GoToSurface] Running Loop=1
2017-10-30T22:40:07.987Z,1509403207.987 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-10-30T22:40:07.988Z,1509403207.988 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-10-30T22:40:07.988Z,1509403207.988 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-10-30T22:40:07.988Z,1509403207.988 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-10-30T22:40:07.989Z,1509403207.989 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-10-30T22:40:07.989Z,1509403207.989 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-10-30T22:40:07.996Z,1509403207.996 [Startup:StartupSatComms] Running Loop=1
2017-10-30T22:40:07.996Z,1509403207.996 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-10-30T22:40:07.996Z,1509403207.996 [Startup:StartupSatComms:A] Running Loop=1
2017-10-30T22:40:08.388Z,1509403208.388 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-10-30T22:40:08.725Z,1509403208.725 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:40:08.725Z,1509403208.725 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:40:09.423Z,1509403209.423 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-30T22:40:09.424Z,1509403209.424 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-10-30T22:40:09.424Z,1509403209.424 [Rowe_600LCM](ERROR): Communications Fault
2017-10-30T22:40:09.564Z,1509403209.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-10-30T22:40:09.606Z,1509403209.606 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-30T22:40:09.826Z,1509403209.826 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-30T22:40:09.923Z,1509403209.923 [Rowe_600LCM](INFO): Powering down
2017-10-30T22:40:09.968Z,1509403209.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-10-30T22:40:10.394Z,1509403210.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-30T22:40:13.489Z,1509403213.489 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:40:13.489Z,1509403213.489 [BPC1] Data Fault, FailCount= 7
2017-10-30T22:40:13.489Z,1509403213.489 [BPC1](ERROR): Data Fault
2017-10-30T22:40:13.502Z,1509403213.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2017-10-30T22:40:13.516Z,1509403213.516 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-30T22:40:13.516Z,1509403213.516 [Rowe_600LCM] No Fault, FailCount= 1
2017-10-30T22:40:13.516Z,1509403213.516 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:40:13.558Z,1509403213.558 [Rowe_600LCM](INFO): Initializing
2017-10-30T22:40:13.558Z,1509403213.558 [Rowe_600LCM](INFO): Checking LCM
2017-10-30T22:40:13.558Z,1509403213.558 [Rowe_600LCM](INFO): LCM OK
2017-10-30T22:40:13.559Z,1509403213.559 [Rowe_600LCM](INFO): Powering up
2017-10-30T22:40:13.665Z,1509403213.665 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2017-10-30T22:40:13.999Z,1509403213.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-30T22:40:14.380Z,1509403214.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-30T22:40:14.418Z,1509403214.418 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:40:14.418Z,1509403214.418 [BPC1] No Fault, FailCount= 7
2017-10-30T22:40:14.814Z,1509403214.814 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-30T22:40:15.173Z,1509403215.173 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-30T22:40:15.566Z,1509403215.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-10-30T22:40:15.969Z,1509403215.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-10-30T22:40:16.372Z,1509403216.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-10-30T22:40:16.767Z,1509403216.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-10-30T22:40:17.176Z,1509403217.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-10-30T22:40:17.572Z,1509403217.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-10-30T22:40:17.701Z,1509403217.701 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-30T22:40:17.760Z,1509403217.760 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-30T22:40:17.762Z,1509403217.762 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-30T22:40:17.763Z,1509403217.763 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-30T22:40:17.764Z,1509403217.764 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-30T22:40:18.018Z,1509403218.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-10-30T22:40:29.125Z,1509403229.125 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:40:29.125Z,1509403229.125 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2017-10-30T22:40:29.125Z,1509403229.125 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:40:29.221Z,1509403229.221 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:40:29.525Z,1509403229.525 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:40:30.409Z,1509403230.409 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:40:30.409Z,1509403230.409 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2017-10-30T22:40:32.725Z,1509403232.725 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:40:32.725Z,1509403232.725 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:40:35.171Z,1509403235.171 [BPC1](ERROR): Failed to parse bank B battery data
2017-10-30T22:40:35.171Z,1509403235.171 [BPC1] Data Fault, FailCount= 8
2017-10-30T22:40:35.171Z,1509403235.171 [BPC1](ERROR): Data Fault
2017-10-30T22:40:35.217Z,1509403235.217 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:40:36.461Z,1509403236.461 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:40:36.461Z,1509403236.461 [BPC1] No Fault, FailCount= 8
2017-10-30T22:40:53.125Z,1509403253.125 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:40:53.125Z,1509403253.125 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2017-10-30T22:40:53.125Z,1509403253.125 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:40:53.214Z,1509403253.214 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:40:53.525Z,1509403253.525 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:40:54.383Z,1509403254.383 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:40:54.384Z,1509403254.384 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2017-10-30T22:40:55.967Z,1509403255.967 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:40:55.967Z,1509403255.967 [BPC1] Data Fault, FailCount= 9
2017-10-30T22:40:55.967Z,1509403255.967 [BPC1](ERROR): Data Fault
2017-10-30T22:40:56.013Z,1509403256.013 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:40:56.725Z,1509403256.725 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:40:56.725Z,1509403256.725 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:40:57.182Z,1509403257.182 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:40:57.182Z,1509403257.182 [BPC1] No Fault, FailCount= 9
2017-10-30T22:41:08.379Z,1509403268.379 [Startup:StartupSatComms:A](INFO): Timed out from 2017-10-30T22:40:07.0Z
2017-10-30T22:41:08.379Z,1509403268.379 [Startup:StartupSatComms:A] Stopped
2017-10-30T22:41:08.379Z,1509403268.379 [Startup:StartupSatComms:B] Running Loop=1
2017-10-30T22:41:08.771Z,1509403268.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-30T22:41:08.771Z,1509403268.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-10-30T22:41:08.771Z,1509403268.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-10-30T22:41:08.773Z,1509403268.773 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-30T22:41:08.773Z,1509403268.773 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-10-30T22:41:08.773Z,1509403268.773 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-10-30T22:41:08.776Z,1509403268.776 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-10-30T22:41:08.788Z,1509403268.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-10-30T22:41:08.788Z,1509403268.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-10-30T22:41:09.646Z,1509403269.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-10-30T22:41:09.646Z,1509403269.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-10-30T22:41:09.647Z,1509403269.647 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-10-30T22:41:09.647Z,1509403269.647 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-10-30T22:41:09.748Z,1509403269.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-30T22:41:09.748Z,1509403269.748 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:41:09.748Z,1509403269.748 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:41:09.749Z,1509403269.749 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-30T22:41:09.749Z,1509403269.749 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:41:09.762Z,1509403269.762 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:41:12.916Z,1509403272.916 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:41:12.916Z,1509403272.916 [BPC1] Data Fault, FailCount= 10
2017-10-30T22:41:12.916Z,1509403272.916 [BPC1](ERROR): Data Fault
2017-10-30T22:41:12.937Z,1509403272.937 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:41:12.938Z,1509403272.938 [CBIT](CRITICAL): Data Fault in component: BPC1
2017-10-30T22:41:12.978Z,1509403272.978 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171030T154030/Courier0128.lzma
2017-10-30T22:41:13.100Z,1509403273.100 [CBIT](INFO): Critical error at 20171030T224112
2017-10-30T22:41:13.747Z,1509403273.747 [DataOverHttps](INFO): Moved sent file to Logs/20171030T154030/Courier0128.lzma.bak
2017-10-30T22:41:13.747Z,1509403273.747 [DataOverHttps](INFO): SBD MOMSN=5177291
2017-10-30T22:41:17.826Z,1509403277.826 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:41:17.826Z,1509403277.826 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2017-10-30T22:41:17.826Z,1509403277.826 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:41:17.961Z,1509403277.961 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:41:17.962Z,1509403277.962 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:41:18.229Z,1509403278.229 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:41:22.775Z,1509403282.775 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171030T213726/Courier0000.lzma
2017-10-30T22:41:23.574Z,1509403283.574 [DataOverHttps](INFO): Moved sent file to Logs/20171030T213726/Courier0000.lzma.bak
2017-10-30T22:41:23.575Z,1509403283.575 [DataOverHttps](INFO): SBD MOMSN=5177293
2017-10-30T22:41:23.620Z,1509403283.620 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:24.217Z,1509403284.217 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:24.403Z,1509403284.403 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:28.471Z,1509403288.471 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:28.861Z,1509403288.861 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:29.261Z,1509403289.261 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:32.538Z,1509403292.538 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:32.583Z,1509403292.583 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20171030T223758/Courier0000.lzma
2017-10-30T22:41:32.674Z,1509403292.674 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:33.020Z,1509403293.020 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:33.382Z,1509403293.382 [DataOverHttps](INFO): Moved sent file to Logs/20171030T223758/Courier0000.lzma.bak
2017-10-30T22:41:33.383Z,1509403293.383 [DataOverHttps](INFO): SBD MOMSN=5177296
2017-10-30T22:41:33.430Z,1509403293.430 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:34.319Z,1509403294.319 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:34.429Z,1509403294.429 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:41:43.111Z,1509403303.111 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171030T154030/Express0129.lzma
2017-10-30T22:41:43.903Z,1509403303.903 [DataOverHttps](INFO): Moved sent file to Logs/20171030T154030/Express0129.lzma.bak
2017-10-30T22:41:43.903Z,1509403303.903 [DataOverHttps](INFO): SBD MOMSN=5177301
2017-10-30T22:41:53.165Z,1509403313.165 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171030T213726/Express0001.lzma
2017-10-30T22:41:53.967Z,1509403313.967 [DataOverHttps](INFO): Moved sent file to Logs/20171030T213726/Express0001.lzma.bak
2017-10-30T22:41:53.967Z,1509403313.967 [DataOverHttps](INFO): SBD MOMSN=5177303
2017-10-30T22:42:03.017Z,1509403323.017 [DataOverHttps](INFO): Sending 801 bytes from file Logs/20171030T223758/Express0001.lzma
2017-10-30T22:42:03.834Z,1509403323.834 [DataOverHttps](INFO): Moved sent file to Logs/20171030T223758/Express0001.lzma.bak
2017-10-30T22:42:03.835Z,1509403323.835 [DataOverHttps](INFO): SBD MOMSN=5177305
2017-10-30T22:42:04.615Z,1509403324.615 [Startup:StartupSatComms:B] Stopped
2017-10-30T22:42:04.615Z,1509403324.615 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-10-30T22:42:04.615Z,1509403324.615 [Startup:StartupSatComms] Stopped
2017-10-30T22:42:04.615Z,1509403324.615 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-10-30T22:42:04.616Z,1509403324.616 [Startup](INFO): Completed Startup
2017-10-30T22:42:04.616Z,1509403324.616 [MissionManager](INFO): Startup is completed.
2017-10-30T22:42:04.616Z,1509403324.616 [MissionManager](INFO): Uninitializing Mission Startup
2017-10-30T22:42:04.616Z,1509403324.616 [Startup] Stopped
2017-10-30T22:42:04.617Z,1509403324.617 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-10-30T22:42:04.617Z,1509403324.617 [Startup:A.GoToSurface] Stopped
2017-10-30T22:42:04.617Z,1509403324.617 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-10-30T22:42:04.748Z,1509403324.748 [MissionManager](IMPORTANT): Started mission Default
2017-10-30T22:42:04.748Z,1509403324.748 [Default] Running Loop=1
2017-10-30T22:42:04.749Z,1509403324.749 [Default](DEBUG): Aggregate::initialize Default
2017-10-30T22:42:04.749Z,1509403324.749 [Default:B.GoToSurface] Running Loop=1
2017-10-30T22:42:04.749Z,1509403324.749 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-10-30T22:42:04.749Z,1509403324.749 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-10-30T22:42:04.749Z,1509403324.749 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-10-30T22:42:04.750Z,1509403324.750 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-10-30T22:42:04.750Z,1509403324.750 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-10-30T22:42:04.750Z,1509403324.750 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-10-30T22:42:04.751Z,1509403324.751 [Default:A.Wait] Running Loop=1
2017-10-30T22:42:04.751Z,1509403324.751 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-10-30T22:42:14.014Z,1509403334.014 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-30T22:42:14.014Z,1509403334.014 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-10-30T22:42:14.014Z,1509403334.014 [Rowe_600LCM](ERROR): Communications Fault
2017-10-30T22:42:14.035Z,1509403334.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-10-30T22:42:14.069Z,1509403334.069 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-30T22:42:14.418Z,1509403334.418 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-30T22:42:14.531Z,1509403334.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2017-10-30T22:42:14.607Z,1509403334.607 [Rowe_600LCM](INFO): Powering down
2017-10-30T22:42:14.839Z,1509403334.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-30T22:42:15.231Z,1509403335.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-10-30T22:42:15.635Z,1509403335.635 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-10-30T22:42:16.031Z,1509403336.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-10-30T22:42:16.044Z,1509403336.044 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-30T22:42:16.044Z,1509403336.044 [Rowe_600LCM] No Fault, FailCount= 2
2017-10-30T22:42:16.222Z,1509403336.222 [Rowe_600LCM](INFO): Initializing
2017-10-30T22:42:16.222Z,1509403336.222 [Rowe_600LCM](INFO): Checking LCM
2017-10-30T22:42:16.222Z,1509403336.222 [Rowe_600LCM](INFO): LCM OK
2017-10-30T22:42:16.223Z,1509403336.223 [Rowe_600LCM](INFO): Powering up
2017-10-30T22:42:16.435Z,1509403336.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-10-30T22:42:16.834Z,1509403336.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-10-30T22:42:17.232Z,1509403337.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-10-30T22:42:17.699Z,1509403337.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-10-30T22:42:18.144Z,1509403338.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2017-10-30T22:42:18.179Z,1509403338.179 [Default:A.Wait](INFO): Done Waiting.
2017-10-30T22:42:18.254Z,1509403338.254 [Default:A.Wait] Stopped
2017-10-30T22:42:18.254Z,1509403338.254 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-10-30T22:42:18.439Z,1509403338.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-30T22:42:18.442Z,1509403338.442 [Default:CheckIn] Running Loop=1
2017-10-30T22:42:18.442Z,1509403338.442 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-10-30T22:42:18.442Z,1509403338.442 [Default:CheckIn:Read_GPS] Running Loop=1
2017-10-30T22:42:18.844Z,1509403338.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-30T22:42:18.848Z,1509403338.848 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-10-30T22:42:19.240Z,1509403339.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-30T22:42:19.632Z,1509403339.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-30T22:42:20.037Z,1509403340.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-10-30T22:42:20.346Z,1509403340.346 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-30T22:42:20.454Z,1509403340.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-10-30T22:42:20.487Z,1509403340.487 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-30T22:42:20.488Z,1509403340.488 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-30T22:42:20.488Z,1509403340.488 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-30T22:42:20.489Z,1509403340.489 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-30T22:42:20.841Z,1509403340.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-10-30T22:43:01.604Z,1509403381.604 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:02.008Z,1509403382.008 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:02.404Z,1509403382.404 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:02.804Z,1509403382.804 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:03.204Z,1509403383.204 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:03.608Z,1509403383.608 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:04.004Z,1509403384.004 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:04.429Z,1509403384.429 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:04.827Z,1509403384.827 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-10-30T22:43:49.315Z,1509403429.315 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-10-30T22:44:09.934Z,1509403449.934 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-30T22:44:09.934Z,1509403449.934 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-10-30T22:44:09.934Z,1509403449.934 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-10-30T22:44:09.936Z,1509403449.936 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-30T22:44:09.936Z,1509403449.936 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-10-30T22:44:09.936Z,1509403449.936 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-10-30T22:44:09.981Z,1509403449.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-10-30T22:44:09.981Z,1509403449.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-10-30T22:44:10.295Z,1509403450.295 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-10-30T22:44:10.295Z,1509403450.295 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-10-30T22:44:10.296Z,1509403450.296 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-10-30T22:44:10.296Z,1509403450.296 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-10-30T22:44:10.688Z,1509403450.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-30T22:44:10.688Z,1509403450.688 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:44:10.688Z,1509403450.688 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:44:10.689Z,1509403450.689 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-30T22:44:10.689Z,1509403450.689 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:44:10.689Z,1509403450.689 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:44:16.411Z,1509403456.411 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-30T22:44:16.411Z,1509403456.411 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-10-30T22:44:16.411Z,1509403456.411 [Rowe_600LCM](ERROR): Communications Fault
2017-10-30T22:44:16.692Z,1509403456.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-10-30T22:44:16.731Z,1509403456.731 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-30T22:44:16.814Z,1509403456.814 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-30T22:44:16.895Z,1509403456.895 [Rowe_600LCM](INFO): Powering down
2017-10-30T22:44:17.118Z,1509403457.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-10-30T22:44:17.491Z,1509403457.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-30T22:44:17.878Z,1509403457.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-10-30T22:44:18.282Z,1509403458.282 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-10-30T22:44:18.296Z,1509403458.296 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-30T22:44:18.296Z,1509403458.296 [Rowe_600LCM] No Fault, FailCount= 3
2017-10-30T22:44:18.510Z,1509403458.510 [Rowe_600LCM](INFO): Initializing
2017-10-30T22:44:18.511Z,1509403458.511 [Rowe_600LCM](INFO): Checking LCM
2017-10-30T22:44:18.511Z,1509403458.511 [Rowe_600LCM](INFO): LCM OK
2017-10-30T22:44:18.511Z,1509403458.511 [Rowe_600LCM](INFO): Powering up
2017-10-30T22:44:18.689Z,1509403458.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-10-30T22:44:19.084Z,1509403459.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-10-30T22:44:19.491Z,1509403459.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-10-30T22:44:19.890Z,1509403459.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-10-30T22:44:20.292Z,1509403460.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-10-30T22:44:20.682Z,1509403460.682 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-10-30T22:44:21.087Z,1509403461.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-30T22:44:21.481Z,1509403461.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-30T22:44:21.882Z,1509403461.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-30T22:44:22.281Z,1509403462.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-30T22:44:22.629Z,1509403462.629 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-30T22:44:22.744Z,1509403462.744 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-30T22:44:22.745Z,1509403462.745 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-30T22:44:22.746Z,1509403462.746 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-30T22:44:22.746Z,1509403462.746 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-30T22:44:22.791Z,1509403462.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-10-30T22:44:23.093Z,1509403463.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-10-30T22:46:13.229Z,1509403573.229 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:46:13.230Z,1509403573.230 [BPC1] No Fault, FailCount= 10
2017-10-30T22:46:18.026Z,1509403578.026 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:46:18.026Z,1509403578.026 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8
2017-10-30T22:46:18.345Z,1509403578.345 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:46:18.345Z,1509403578.345 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:46:18.943Z,1509403578.943 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-30T22:46:18.943Z,1509403578.943 [Rowe_600LCM] Communications Fault, FailCount= 4
2017-10-30T22:46:18.943Z,1509403578.943 [Rowe_600LCM](ERROR): Communications Fault
2017-10-30T22:46:19.204Z,1509403579.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-10-30T22:46:19.235Z,1509403579.235 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-30T22:46:19.346Z,1509403579.346 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-30T22:46:19.433Z,1509403579.433 [Rowe_600LCM](INFO): Powering down
2017-10-30T22:46:19.598Z,1509403579.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-10-30T22:46:19.990Z,1509403579.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-30T22:46:20.395Z,1509403580.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-10-30T22:46:20.799Z,1509403580.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-10-30T22:46:20.829Z,1509403580.829 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-30T22:46:20.829Z,1509403580.829 [Rowe_600LCM] No Fault, FailCount= 4
2017-10-30T22:46:21.050Z,1509403581.050 [Rowe_600LCM](INFO): Initializing
2017-10-30T22:46:21.050Z,1509403581.050 [Rowe_600LCM](INFO): Checking LCM
2017-10-30T22:46:21.050Z,1509403581.050 [Rowe_600LCM](INFO): LCM OK
2017-10-30T22:46:21.051Z,1509403581.051 [Rowe_600LCM](INFO): Powering up
2017-10-30T22:46:21.199Z,1509403581.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-10-30T22:46:21.591Z,1509403581.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-10-30T22:46:21.992Z,1509403581.992 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-10-30T22:46:22.398Z,1509403582.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-10-30T22:46:22.822Z,1509403582.822 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-10-30T22:46:23.223Z,1509403583.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-10-30T22:46:23.591Z,1509403583.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-30T22:46:23.996Z,1509403583.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-30T22:46:24.444Z,1509403584.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-30T22:46:24.804Z,1509403584.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-30T22:46:25.235Z,1509403585.235 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-30T22:46:25.283Z,1509403585.283 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-10-30T22:46:25.348Z,1509403585.348 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-30T22:46:25.349Z,1509403585.349 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-30T22:46:25.350Z,1509403585.350 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-30T22:46:25.351Z,1509403585.351 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-30T22:46:25.627Z,1509403585.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-10-30T22:46:29.977Z,1509403589.977 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:46:29.977Z,1509403589.977 [BPC1] Data Fault, FailCount= 1
2017-10-30T22:46:29.994Z,1509403589.994 [BPC1](ERROR): Data Fault
2017-10-30T22:46:30.047Z,1509403590.047 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:46:31.216Z,1509403591.216 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:46:31.216Z,1509403591.216 [BPC1] No Fault, FailCount= 1
2017-10-30T22:46:38.745Z,1509403598.745 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:46:38.745Z,1509403598.745 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2017-10-30T22:46:38.745Z,1509403598.745 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:46:38.844Z,1509403598.844 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:46:39.145Z,1509403599.145 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:46:40.052Z,1509403600.052 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:46:40.052Z,1509403600.052 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2017-10-30T22:46:42.345Z,1509403602.345 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:46:42.345Z,1509403602.345 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:46:51.585Z,1509403611.585 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:46:51.586Z,1509403611.586 [BPC1] Data Fault, FailCount= 2
2017-10-30T22:46:51.586Z,1509403611.586 [BPC1](ERROR): Data Fault
2017-10-30T22:46:51.607Z,1509403611.607 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:46:52.816Z,1509403612.816 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:46:52.816Z,1509403612.816 [BPC1] No Fault, FailCount= 2
2017-10-30T22:47:02.745Z,1509403622.745 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:47:02.745Z,1509403622.745 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2017-10-30T22:47:02.745Z,1509403622.745 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:47:02.918Z,1509403622.918 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:47:03.145Z,1509403623.145 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:47:04.016Z,1509403624.016 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:47:04.017Z,1509403624.017 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2017-10-30T22:47:08.783Z,1509403628.783 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:47:08.783Z,1509403628.783 [BPC1] Data Fault, FailCount= 3
2017-10-30T22:47:08.783Z,1509403628.783 [BPC1](ERROR): Data Fault
2017-10-30T22:47:08.843Z,1509403628.843 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:47:08.853Z,1509403628.853 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:47:08.853Z,1509403628.853 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:47:09.740Z,1509403629.740 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:47:09.740Z,1509403629.740 [BPC1] No Fault, FailCount= 3
2017-10-30T22:47:10.897Z,1509403630.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-30T22:47:10.897Z,1509403630.897 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2017-10-30T22:47:10.897Z,1509403630.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-10-30T22:47:10.899Z,1509403630.899 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-30T22:47:10.899Z,1509403630.899 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2017-10-30T22:47:10.899Z,1509403630.899 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-10-30T22:47:10.944Z,1509403630.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-10-30T22:47:10.944Z,1509403630.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-10-30T22:47:11.336Z,1509403631.336 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-10-30T22:47:11.336Z,1509403631.336 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2017-10-30T22:47:11.337Z,1509403631.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-10-30T22:47:11.337Z,1509403631.337 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2017-10-30T22:47:11.708Z,1509403631.708 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-30T22:47:11.708Z,1509403631.708 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:47:11.709Z,1509403631.709 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:47:11.709Z,1509403631.709 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-30T22:47:11.709Z,1509403631.709 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-30T22:47:11.710Z,1509403631.710 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-30T22:47:18.543Z,1509403638.543 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-10-30T22:42:18.4Z
2017-10-30T22:47:18.543Z,1509403638.543 [Default:CheckIn:Read_GPS] Stopped
2017-10-30T22:47:18.543Z,1509403638.543 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-10-30T22:47:18.965Z,1509403638.965 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-10-30T22:47:22.970Z,1509403642.970 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20171030T223758/Courier0004.lzma
2017-10-30T22:47:23.763Z,1509403643.763 [DataOverHttps](INFO): Moved sent file to Logs/20171030T223758/Courier0004.lzma.bak
2017-10-30T22:47:23.763Z,1509403643.763 [DataOverHttps](INFO): SBD MOMSN=5177325
2017-10-30T22:47:24.552Z,1509403644.552 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:47:24.552Z,1509403644.552 [BPC1] Data Fault, FailCount= 4
2017-10-30T22:47:24.552Z,1509403644.552 [BPC1](ERROR): Data Fault
2017-10-30T22:47:24.614Z,1509403644.614 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:47:28.347Z,1509403648.347 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:47:28.347Z,1509403648.347 [BPC1] No Fault, FailCount= 4
2017-10-30T22:47:29.049Z,1509403649.049 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:47:29.049Z,1509403649.049 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2017-10-30T22:47:29.049Z,1509403649.049 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:47:29.145Z,1509403649.145 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:47:29.449Z,1509403649.449 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:47:33.207Z,1509403653.207 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:47:33.207Z,1509403653.207 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2017-10-30T22:47:33.212Z,1509403653.212 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:47:33.212Z,1509403653.212 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:47:33.245Z,1509403653.245 [DataOverHttps](INFO): Sending 368 bytes from file Logs/20171030T223758/Express0005.lzma
2017-10-30T22:47:34.015Z,1509403654.015 [DataOverHttps](INFO): Moved sent file to Logs/20171030T223758/Express0005.lzma.bak
2017-10-30T22:47:34.015Z,1509403654.015 [DataOverHttps](INFO): SBD MOMSN=5177328
2017-10-30T22:47:34.827Z,1509403654.827 [Default:CheckIn:Read_Iridium] Stopped
2017-10-30T22:47:34.827Z,1509403654.827 [Default:CheckIn:C.Wait] Running Loop=1
2017-10-30T22:47:34.827Z,1509403654.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-10-30T22:47:50.226Z,1509403670.226 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:47:50.226Z,1509403670.226 [BPC1] Data Fault, FailCount= 5
2017-10-30T22:47:50.226Z,1509403670.226 [BPC1](ERROR): Data Fault
2017-10-30T22:47:50.293Z,1509403670.293 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:47:51.479Z,1509403671.479 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:47:51.479Z,1509403671.479 [BPC1] No Fault, FailCount= 5
2017-10-30T22:47:53.383Z,1509403673.383 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:47:53.383Z,1509403673.383 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2017-10-30T22:47:53.383Z,1509403673.383 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:47:53.486Z,1509403673.486 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:47:53.777Z,1509403673.777 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:47:54.644Z,1509403674.644 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:47:54.644Z,1509403674.644 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2017-10-30T22:47:56.977Z,1509403676.977 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:47:56.977Z,1509403676.977 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:48:05.028Z,1509403685.028 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:48:05.028Z,1509403685.028 [BPC1] Data Fault, FailCount= 6
2017-10-30T22:48:05.028Z,1509403685.028 [BPC1](ERROR): Data Fault
2017-10-30T22:48:05.050Z,1509403685.050 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:48:06.238Z,1509403686.238 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:48:06.239Z,1509403686.239 [BPC1] No Fault, FailCount= 6
2017-10-30T22:48:08.641Z,1509403688.641 [CBIT](DEBUG): EFC running - data check-sum false
2017-10-30T22:48:17.377Z,1509403697.377 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:48:17.377Z,1509403697.377 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2017-10-30T22:48:17.377Z,1509403697.377 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:48:17.465Z,1509403697.465 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:48:17.777Z,1509403697.777 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:48:18.649Z,1509403698.649 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:48:18.649Z,1509403698.649 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2017-10-30T22:48:19.830Z,1509403699.830 [BPC1](ERROR): Failed to parse bank B battery data
2017-10-30T22:48:19.830Z,1509403699.830 [BPC1] Data Fault, FailCount= 7
2017-10-30T22:48:19.830Z,1509403699.830 [BPC1](ERROR): Data Fault
2017-10-30T22:48:19.858Z,1509403699.858 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:48:20.977Z,1509403700.977 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:48:20.977Z,1509403700.977 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:48:21.056Z,1509403701.056 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:48:21.057Z,1509403701.057 [BPC1] No Fault, FailCount= 7
2017-10-30T22:48:21.436Z,1509403701.436 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-30T22:48:21.436Z,1509403701.436 [Rowe_600LCM] Communications Fault, FailCount= 5
2017-10-30T22:48:21.436Z,1509403701.436 [Rowe_600LCM](ERROR): Communications Fault
2017-10-30T22:48:21.531Z,1509403701.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2017-10-30T22:48:21.545Z,1509403701.545 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-30T22:48:21.545Z,1509403701.545 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2017-10-30T22:48:21.817Z,1509403701.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-10-30T22:48:21.838Z,1509403701.838 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-30T22:48:21.962Z,1509403701.962 [Rowe_600LCM](INFO): Powering down
2017-10-30T22:48:22.241Z,1509403702.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-30T22:48:22.644Z,1509403702.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-10-30T22:48:23.045Z,1509403703.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-10-30T22:48:23.447Z,1509403703.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-10-30T22:48:23.821Z,1509403703.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-10-30T22:48:24.219Z,1509403704.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-10-30T22:48:24.621Z,1509403704.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-10-30T22:48:25.021Z,1509403705.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-10-30T22:48:25.446Z,1509403705.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-10-30T22:48:25.847Z,1509403705.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-30T22:48:26.247Z,1509403706.247 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-30T22:48:26.619Z,1509403706.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-30T22:48:27.021Z,1509403707.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-30T22:48:27.420Z,1509403707.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-10-30T22:48:27.820Z,1509403707.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-10-30T22:48:28.221Z,1509403708.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-10-30T22:48:28.647Z,1509403708.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-10-30T22:48:29.044Z,1509403709.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-10-30T22:48:29.447Z,1509403709.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-10-30T22:48:29.856Z,1509403709.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-10-30T22:48:30.224Z,1509403710.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-10-30T22:48:30.621Z,1509403710.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-10-30T22:48:31.021Z,1509403711.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-10-30T22:48:31.446Z,1509403711.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-10-30T22:48:31.846Z,1509403711.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-10-30T22:48:32.227Z,1509403712.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-10-30T22:48:32.618Z,1509403712.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-10-30T22:48:33.019Z,1509403713.019 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-10-30T22:48:33.419Z,1509403713.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-10-30T22:48:33.818Z,1509403713.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-10-30T22:48:34.224Z,1509403714.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-10-30T22:48:34.647Z,1509403714.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-10-30T22:48:35.046Z,1509403715.046 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-10-30T22:48:35.452Z,1509403715.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-10-30T22:48:38.546Z,1509403718.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2017-10-30T22:48:38.668Z,1509403718.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-10-30T22:48:39.022Z,1509403719.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-10-30T22:48:39.423Z,1509403719.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-10-30T22:48:39.828Z,1509403719.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-10-30T22:48:40.220Z,1509403720.220 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-10-30T22:48:40.611Z,1509403720.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-10-30T22:48:41.011Z,1509403721.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2017-10-30T22:48:41.365Z,1509403721.365 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:48:41.365Z,1509403721.365 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2017-10-30T22:48:41.365Z,1509403721.365 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:48:41.449Z,1509403721.449 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:48:41.765Z,1509403721.765 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:48:41.930Z,1509403721.930 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:48:41.931Z,1509403721.931 [BPC1] Data Fault, FailCount= 8
2017-10-30T22:48:41.931Z,1509403721.931 [BPC1](ERROR): Data Fault
2017-10-30T22:48:41.976Z,1509403721.976 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:48:42.722Z,1509403722.722 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:48:42.723Z,1509403722.723 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2017-10-30T22:48:43.028Z,1509403723.028 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:48:43.029Z,1509403723.029 [BPC1] No Fault, FailCount= 8
2017-10-30T22:48:44.965Z,1509403724.965 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:48:44.965Z,1509403724.965 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:48:47.371Z,1509403727.371 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-10-30T22:48:47.372Z,1509403727.372 [NAL9602] Data Fault, FailCount= 1
2017-10-30T22:48:47.372Z,1509403727.372 [NAL9602](ERROR): Data Fault
2017-10-30T22:48:47.459Z,1509403727.459 [CBIT](ERROR): Data Fault in component: NAL9602
2017-10-30T22:48:47.771Z,1509403727.771 [NAL9602](INFO): Powering down
2017-10-30T22:48:48.626Z,1509403728.626 [CBIT](INFO): Clearing failed state for component NAL9602
2017-10-30T22:48:48.626Z,1509403728.626 [NAL9602] No Fault, FailCount= 1
2017-10-30T22:48:56.601Z,1509403736.601 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:48:56.601Z,1509403736.601 [BPC1] Data Fault, FailCount= 9
2017-10-30T22:48:56.601Z,1509403736.601 [BPC1](ERROR): Data Fault
2017-10-30T22:48:56.623Z,1509403736.623 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:48:57.822Z,1509403737.822 [CBIT](INFO): Clearing failed state for component BPC1
2017-10-30T22:48:57.822Z,1509403737.822 [BPC1] No Fault, FailCount= 9
2017-10-30T22:49:05.365Z,1509403745.365 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-10-30T22:49:05.365Z,1509403745.365 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2017-10-30T22:49:05.365Z,1509403745.365 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-10-30T22:49:05.456Z,1509403745.456 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-10-30T22:49:05.765Z,1509403745.765 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:49:06.658Z,1509403746.658 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-10-30T22:49:06.658Z,1509403746.658 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2017-10-30T22:49:08.965Z,1509403748.965 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-10-30T22:49:08.965Z,1509403748.965 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-10-30T22:49:12.201Z,1509403752.201 [BPC1](ERROR): Failed to parse bank A battery data
2017-10-30T22:49:12.201Z,1509403752.201 [BPC1] Data Fault, FailCount= 10
2017-10-30T22:49:12.201Z,1509403752.201 [BPC1](ERROR): Data Fault
2017-10-30T22:49:12.223Z,1509403752.223 [CBIT](ERROR): Data Fault in component: BPC1
2017-10-30T22:49:12.224Z,1509403752.224 [CBIT](CRITICAL): Data Fault in component: BPC1
2017-10-30T22:49:12.627Z,1509403752.627 [CBIT](INFO): Critical error at 20171030T224912
2017-10-30T22:49:16.148Z,1509403756.148 [CommandLine](IMPORTANT): got command restart system
2017-10-30T22:49:18.172Z,1509403758.172 [NAL9602](INFO): Powering up NAL9602
2017-10-30T22:49:18.301Z,1509403758.301 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:18.302Z,1509403758.302 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.414Z,1509403758.414 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-10-30T22:49:18.414Z,1509403758.414 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.415Z,1509403758.415 [CommandLine](INFO): Join timeout helper Thread ID is 2602
2017-10-30T22:49:18.415Z,1509403758.415 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-10-30T22:49:18.415Z,1509403758.415 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.416Z,1509403758.416 [NavChartDb](INFO): Join timeout helper Thread ID is 2603
2017-10-30T22:49:18.694Z,1509403758.694 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:18.694Z,1509403758.694 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.702Z,1509403758.702 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-10-30T22:49:18.702Z,1509403758.702 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.702Z,1509403758.702 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2604
2017-10-30T22:49:18.734Z,1509403758.734 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:18.734Z,1509403758.734 [WetLabsBB2FL](INFO): Powering down
2017-10-30T22:49:18.735Z,1509403758.735 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.746Z,1509403758.746 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-10-30T22:49:18.746Z,1509403758.746 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.746Z,1509403758.746 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 2605
2017-10-30T22:49:18.938Z,1509403758.938 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:18.938Z,1509403758.938 [CTD_NeilBrown](INFO): Powering down
2017-10-30T22:49:18.939Z,1509403758.939 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.954Z,1509403758.954 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-10-30T22:49:18.954Z,1509403758.954 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:18.954Z,1509403758.954 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 2606
2017-10-30T22:49:19.350Z,1509403759.350 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:19.350Z,1509403759.350 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-30T22:49:19.528Z,1509403759.528 [Rowe_600LCM](INFO): Powering down
2017-10-30T22:49:19.529Z,1509403759.529 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.547Z,1509403759.547 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-10-30T22:49:19.547Z,1509403759.547 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.547Z,1509403759.547 [Radio_Surface](INFO): Join timeout helper Thread ID is 2608
2017-10-30T22:49:19.690Z,1509403759.690 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:19.691Z,1509403759.691 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.701Z,1509403759.701 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-10-30T22:49:19.701Z,1509403759.701 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.702Z,1509403759.702 [logger](INFO): Join timeout helper Thread ID is 2609
2017-10-30T22:49:19.702Z,1509403759.702 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:19.702Z,1509403759.702 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.703Z,1509403759.703 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-10-30T22:49:19.704Z,1509403759.704 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.704Z,1509403759.704 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-10-30T22:49:19.704Z,1509403759.704 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:19.704Z,1509403759.704 [controlThread](INFO): Join timeout helper Thread ID is 2610
2017-10-30T22:49:19.762Z,1509403759.762 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-30T22:49:19.762Z,1509403759.762 [controlThread](DEBUG): Uninitializing ControlThread
2017-10-30T22:49:19.763Z,1509403759.763 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-10-30T22:49:19.883Z,1509403759.883 [NAL9602](INFO): Powering down
2017-10-30T22:49:19.884Z,1509403759.884 [PNI_TCM](INFO): Powering down
2017-10-30T22:49:19.970Z,1509403759.970 [Aanderaa_O2](INFO): Powering down
2017-10-30T22:49:19.973Z,1509403759.973 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-30T22:49:19.975Z,1509403759.975 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-10-30T22:49:19.975Z,1509403759.975 [MissionManager](INFO): Uninitializing Mission Default
2017-10-30T22:49:19.975Z,1509403759.975 [Default] Stopped
2017-10-30T22:49:19.976Z,1509403759.976 [Default](DEBUG): Aggregate::uninitialize Default
2017-10-30T22:49:19.976Z,1509403759.976 [Default:B.GoToSurface] Stopped
2017-10-30T22:49:19.976Z,1509403759.976 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-10-30T22:49:19.976Z,1509403759.976 [Default:CheckIn] Stopped
2017-10-30T22:49:19.976Z,1509403759.976 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-10-30T22:49:19.976Z,1509403759.976 [Default:CheckIn:C.Wait] Stopped
2017-10-30T22:49:19.976Z,1509403759.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-10-30T22:49:19.979Z,1509403759.979 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-10-30T22:49:19.980Z,1509403759.980 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-10-30T22:49:19.980Z,1509403759.980 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-10-30T22:49:19.980Z,1509403759.980 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-10-30T22:49:19.981Z,1509403759.981 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-10-30T22:49:19.981Z,1509403759.981 [BuoyancyServo](INFO): Powering down
2017-10-30T22:49:19.994Z,1509403759.994 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-10-30T22:49:19.994Z,1509403759.994 [ElevatorServo](INFO): Powering down
2017-10-30T22:49:19.995Z,1509403759.995 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-10-30T22:49:19.995Z,1509403759.995 [MassServo](INFO): Powering down
2017-10-30T22:49:19.996Z,1509403759.996 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-10-30T22:49:19.996Z,1509403759.996 [RudderServo](INFO): Powering down
2017-10-30T22:49:19.997Z,1509403759.997 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-10-30T22:49:19.997Z,1509403759.997 [ThrusterServo](INFO): Powering down
2017-10-30T22:49:19.998Z,1509403759.998 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-10-30T22:49:19.999Z,1509403759.999 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-10-30T22:49:19.999Z,1509403759.999 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-10-30T22:49:19.000Z,1509403760.000 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:20.079Z,1509403760.079 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:20.085Z,1509403760.085 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:20.120Z,1509403760.120 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:20.125Z,1509403760.125 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:20.169Z,1509403760.169 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-30T22:49:20.217Z,1509403760.217 [logger ThreadHandler](INFO): Thread cancelled.