2017-12-13T17:16:03.818Z,1513185363.818 [Supervisor](DEBUG): Initializing supervisor.
2017-12-13T17:16:03.820Z,1513185363.820 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-12-13T17:16:03.821Z,1513185363.821 [SyncHandler](INFO): Protected caller Thread ID is 803
2017-12-13T17:16:03.822Z,1513185363.822 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-12-13T17:16:03.822Z,1513185363.822 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-12-13T17:16:03.823Z,1513185363.823 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804
2017-12-13T17:16:03.825Z,1513185363.825 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-12-13T17:16:03.836Z,1513185363.836 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-12-13T17:16:03.837Z,1513185363.837 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-12-13T17:16:03.838Z,1513185363.838 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805
2017-12-13T17:16:03.838Z,1513185363.838 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-12-13T17:16:03.839Z,1513185363.839 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-12-13T17:16:03.840Z,1513185363.840 [logger ThreadHandler](INFO): Protected caller Thread ID is 806
2017-12-13T17:16:03.842Z,1513185363.842 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-12-13T17:16:03.842Z,1513185363.842 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-12-13T17:16:03.845Z,1513185363.845 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-12-13T17:16:03.940Z,1513185363.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-12-13T17:16:03.942Z,1513185363.942 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-12-13T17:16:04.040Z,1513185364.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-12-13T17:16:04.042Z,1513185364.042 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-12-13T17:16:04.220Z,1513185364.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-12-13T17:16:04.221Z,1513185364.221 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-12-13T17:16:04.505Z,1513185364.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-12-13T17:16:04.507Z,1513185364.507 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-12-13T17:16:04.819Z,1513185364.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-12-13T17:16:04.820Z,1513185364.820 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-12-13T17:16:05.245Z,1513185365.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-12-13T17:16:05.246Z,1513185365.246 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-12-13T17:16:05.563Z,1513185365.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-12-13T17:16:05.565Z,1513185365.565 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-12-13T17:16:05.972Z,1513185365.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-12-13T17:16:05.974Z,1513185365.974 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-12-13T17:16:06.055Z,1513185366.055 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-12-13T17:16:06.333Z,1513185366.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-12-13T17:16:06.334Z,1513185366.334 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-12-13T17:16:06.606Z,1513185366.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-12-13T17:16:06.607Z,1513185366.607 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-12-13T17:16:07.349Z,1513185367.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-12-13T17:16:07.351Z,1513185367.351 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-12-13T17:16:07.541Z,1513185367.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-12-13T17:16:07.542Z,1513185367.542 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-12-13T17:16:07.638Z,1513185367.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-12-13T17:16:07.639Z,1513185367.639 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-12-13T17:16:07.773Z,1513185367.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-12-13T17:16:07.775Z,1513185367.775 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-12-13T17:16:07.778Z,1513185367.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-12-13T17:16:07.000Z,1513185368.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-12-13T17:16:08.001Z,1513185368.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-12-13T17:16:08.078Z,1513185368.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-12-13T17:16:08.214Z,1513185368.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-12-13T17:16:08.306Z,1513185368.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-12-13T17:16:08.390Z,1513185368.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-12-13T17:16:08.521Z,1513185368.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-12-13T17:16:08.678Z,1513185368.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-12-13T17:16:08.773Z,1513185368.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-12-13T17:16:08.864Z,1513185368.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-12-13T17:16:08.959Z,1513185368.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-12-13T17:16:09.040Z,1513185369.040 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-12-13T17:16:09.056Z,1513185369.056 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-12-13T17:16:09.181Z,1513185369.181 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-12-13T17:16:09.182Z,1513185369.182 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-12-13T17:16:09.205Z,1513185369.205 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-12-13T17:16:09.207Z,1513185369.207 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-12-13T17:16:09.263Z,1513185369.263 [DepthRateCalculator] Loaded
2017-12-13T17:16:09.263Z,1513185369.263 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-12-13T17:16:09.268Z,1513185369.268 [PitchRateCalculator] Loaded
2017-12-13T17:16:09.269Z,1513185369.269 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-12-13T17:16:09.284Z,1513185369.284 [SpeedCalculator] Loaded
2017-12-13T17:16:09.284Z,1513185369.284 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-12-13T17:16:09.305Z,1513185369.305 [TempGradientCalculator] Loaded
2017-12-13T17:16:09.305Z,1513185369.305 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-12-13T17:16:09.320Z,1513185369.320 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-12-13T17:16:09.320Z,1513185369.320 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-12-13T17:16:09.325Z,1513185369.325 [YawRateCalculator] Loaded
2017-12-13T17:16:09.326Z,1513185369.326 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-12-13T17:16:09.364Z,1513185369.364 [ElevatorOffsetCalculator] Loaded
2017-12-13T17:16:09.364Z,1513185369.364 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2017-12-13T17:16:09.365Z,1513185369.365 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-12-13T17:16:09.365Z,1513185369.365 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-12-13T17:16:09.402Z,1513185369.402 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-12-13T17:16:09.403Z,1513185369.403 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-12-13T17:16:09.906Z,1513185369.906 [AcousticModem_Benthos_ATM900] Loaded
2017-12-13T17:16:09.906Z,1513185369.906 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2017-12-13T17:16:10.043Z,1513185370.043 [DataOverHttps] Loaded
2017-12-13T17:16:10.043Z,1513185370.043 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-12-13T17:16:10.057Z,1513185370.057 [Depth_Keller] Loaded
2017-12-13T17:16:10.057Z,1513185370.057 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-12-13T17:16:10.062Z,1513185370.062 [DropWeight] Loaded
2017-12-13T17:16:10.062Z,1513185370.062 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-12-13T17:16:10.155Z,1513185370.155 [NAL9602] Loaded
2017-12-13T17:16:10.155Z,1513185370.155 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-12-13T17:16:10.171Z,1513185370.171 [Onboard] Loaded
2017-12-13T17:16:10.172Z,1513185370.172 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-12-13T17:16:10.182Z,1513185370.182 [Radio_Surface] Loaded
2017-12-13T17:16:10.182Z,1513185370.182 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-12-13T17:16:10.183Z,1513185370.183 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0
2017-12-13T17:16:10.183Z,1513185370.183 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 886
2017-12-13T17:16:10.334Z,1513185370.334 [PNI_TCM] Loaded
2017-12-13T17:16:10.334Z,1513185370.334 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-12-13T17:16:12.018Z,1513185372.018 [BPC1] Loaded
2017-12-13T17:16:12.018Z,1513185372.018 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-12-13T17:16:12.018Z,1513185372.018 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-12-13T17:16:12.020Z,1513185372.020 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-12-13T17:16:12.742Z,1513185372.742 [SBIT](DEBUG): Construct Startup Built In Test.
2017-12-13T17:16:12.820Z,1513185372.820 [SBIT] Loaded
2017-12-13T17:16:12.833Z,1513185372.833 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-12-13T17:16:12.834Z,1513185372.834 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-12-13T17:16:12.855Z,1513185372.855 [IBIT] Loaded
2017-12-13T17:16:12.855Z,1513185372.855 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-12-13T17:16:12.858Z,1513185372.858 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-12-13T17:16:13.006Z,1513185373.006 [CBIT] Loaded
2017-12-13T17:16:13.006Z,1513185373.006 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-12-13T17:16:13.007Z,1513185373.007 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-12-13T17:16:13.007Z,1513185373.007 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-12-13T17:16:13.190Z,1513185373.190 [Aanderaa_O2] Loaded
2017-12-13T17:16:13.190Z,1513185373.190 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-12-13T17:16:13.223Z,1513185373.223 [CTD_Seabird] Loaded
2017-12-13T17:16:13.223Z,1513185373.223 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-12-13T17:16:13.224Z,1513185373.224 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4086A4E0
2017-12-13T17:16:13.225Z,1513185373.225 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887
2017-12-13T17:16:13.240Z,1513185373.240 [ESPComponent] Loaded
2017-12-13T17:16:13.240Z,1513185373.240 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-12-13T17:16:13.254Z,1513185373.254 [PAR_Licor] Loaded
2017-12-13T17:16:13.254Z,1513185373.254 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-12-13T17:16:13.301Z,1513185373.301 [WetLabsBB2FL] Loaded
2017-12-13T17:16:13.302Z,1513185373.302 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-12-13T17:16:13.303Z,1513185373.303 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4089A4E0
2017-12-13T17:16:13.303Z,1513185373.303 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888
2017-12-13T17:16:13.345Z,1513185373.345 [WetLabsSeaOWL_UV_A] Loaded
2017-12-13T17:16:13.345Z,1513185373.345 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2017-12-13T17:16:13.346Z,1513185373.346 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408CA4E0
2017-12-13T17:16:13.347Z,1513185373.347 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 889
2017-12-13T17:16:13.347Z,1513185373.347 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-12-13T17:16:13.348Z,1513185373.348 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-12-13T17:16:13.613Z,1513185373.613 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-12-13T17:16:13.614Z,1513185373.614 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-12-13T17:16:13.699Z,1513185373.699 [VerticalControl](DEBUG): Construct VerticalControl.
2017-12-13T17:16:13.790Z,1513185373.790 [VerticalControl] Loaded
2017-12-13T17:16:13.791Z,1513185373.791 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-12-13T17:16:13.792Z,1513185373.792 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-12-13T17:16:13.850Z,1513185373.850 [HorizontalControl] Loaded
2017-12-13T17:16:13.851Z,1513185373.851 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-12-13T17:16:13.851Z,1513185373.851 [SpeedControl](DEBUG): Construct SpeedControl.
2017-12-13T17:16:13.856Z,1513185373.856 [SpeedControl] Loaded
2017-12-13T17:16:13.857Z,1513185373.857 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-12-13T17:16:13.857Z,1513185373.857 [LoopControl](DEBUG): Construct LoopControl.
2017-12-13T17:16:13.858Z,1513185373.858 [LoopControl] Loaded
2017-12-13T17:16:13.858Z,1513185373.858 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-12-13T17:16:13.859Z,1513185373.859 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-12-13T17:16:13.859Z,1513185373.859 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-12-13T17:16:13.976Z,1513185373.976 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-12-13T17:16:13.976Z,1513185373.976 [StratificationFrontDetector](DEBUG): (re)initializing
2017-12-13T17:16:13.976Z,1513185373.976 [StratificationFrontDetector] Loaded
2017-12-13T17:16:13.976Z,1513185373.976 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-12-13T17:16:13.977Z,1513185373.977 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-12-13T17:16:13.978Z,1513185373.978 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-12-13T17:16:14.095Z,1513185374.095 [BuoyancyServo] Loaded
2017-12-13T17:16:14.095Z,1513185374.095 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-12-13T17:16:14.106Z,1513185374.106 [ElevatorServo] Loaded
2017-12-13T17:16:14.106Z,1513185374.106 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-12-13T17:16:14.117Z,1513185374.117 [MassServo] Loaded
2017-12-13T17:16:14.117Z,1513185374.117 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-12-13T17:16:14.128Z,1513185374.128 [RudderServo] Loaded
2017-12-13T17:16:14.128Z,1513185374.128 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-12-13T17:16:14.139Z,1513185374.139 [ThrusterServo] Loaded
2017-12-13T17:16:14.139Z,1513185374.139 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-12-13T17:16:14.140Z,1513185374.140 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-12-13T17:16:14.140Z,1513185374.140 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-12-13T17:16:14.251Z,1513185374.251 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-12-13T17:16:14.252Z,1513185374.252 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-12-13T17:16:14.305Z,1513185374.305 [DeadReckonUsingSpeedCalculator] Loaded
2017-12-13T17:16:14.305Z,1513185374.305 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-12-13T17:16:14.322Z,1513185374.322 [NavChart] Loaded
2017-12-13T17:16:14.322Z,1513185374.322 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-12-13T17:16:14.326Z,1513185374.326 [UniversalFixResidualReporter] Loaded
2017-12-13T17:16:14.326Z,1513185374.326 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-12-13T17:16:14.327Z,1513185374.327 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-12-13T17:16:14.330Z,1513185374.330 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-12-13T17:16:14.331Z,1513185374.331 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-12-13T17:16:14.338Z,1513185374.338 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-12-13T17:16:14.339Z,1513185374.339 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1C4E0
2017-12-13T17:16:14.339Z,1513185374.339 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890
2017-12-13T17:16:14.343Z,1513185374.343 [Supervisor](INFO): Main Thread ID is 795
2017-12-13T17:16:14.343Z,1513185374.343 [Supervisor](DEBUG): Running supervisor.
2017-12-13T17:16:14.344Z,1513185374.344 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891
2017-12-13T17:16:14.347Z,1513185374.347 [controlThread ThreadHandler](INFO): Handler Thread ID is 892
2017-12-13T17:16:14.347Z,1513185374.347 [controlThread](DEBUG): Initializing ControlThread
2017-12-13T17:16:14.348Z,1513185374.348 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-13T17:16:14.348Z,1513185374.348 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-12-13T17:16:14.349Z,1513185374.349 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-12-13T17:16:14.349Z,1513185374.349 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-12-13T17:16:14.350Z,1513185374.350 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-12-13T17:16:14.350Z,1513185374.350 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-12-13T17:16:14.351Z,1513185374.351 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2017-12-13T17:16:14.356Z,1513185374.356 [SBIT](INFO): Initialize SBIT Component.
2017-12-13T17:16:14.356Z,1513185374.356 [SBIT](IMPORTANT): git: 2017-12-12
2017-12-13T17:16:14.356Z,1513185374.356 [SBIT](INFO): git hash: 18638d31f033e3fa0cb90b26256f5481d37f00cc
2017-12-13T17:16:14.356Z,1513185374.356 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-12-13T17:16:14.358Z,1513185374.358 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2017-12-13T17:16:14.359Z,1513185374.359 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-12-13T17:16:14.359Z,1513185374.359 [IBIT](INFO): Initialize IBIT Component.
2017-12-13T17:16:14.360Z,1513185374.360 [CBIT](DEBUG): Initialize CBIT Component.
2017-12-13T17:16:14.360Z,1513185374.360 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-12-13T17:16:14.361Z,1513185374.361 [logger ThreadHandler](INFO): Handler Thread ID is 893
2017-12-13T17:16:14.381Z,1513185374.381 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894
2017-12-13T17:16:14.387Z,1513185374.387 [Radio_Surface](INFO): Powering up
2017-12-13T17:16:14.393Z,1513185374.393 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 895
2017-12-13T17:16:14.394Z,1513185374.394 [CTD_Seabird](INFO): Initializing
2017-12-13T17:16:14.430Z,1513185374.430 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 897
2017-12-13T17:16:14.431Z,1513185374.431 [WetLabsBB2FL](INFO): Powering down
2017-12-13T17:16:14.445Z,1513185374.445 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-12-13T17:16:14.448Z,1513185374.448 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-12-13T17:16:14.449Z,1513185374.449 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-12-13T17:16:14.449Z,1513185374.449 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-12-13T17:16:14.451Z,1513185374.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-12-13T17:16:14.452Z,1513185374.452 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:16:14.452Z,1513185374.452 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:16:14.461Z,1513185374.461 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-13T17:16:14.462Z,1513185374.462 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:16:14.462Z,1513185374.462 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:16:14.462Z,1513185374.462 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-12-13T17:16:14.463Z,1513185374.463 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-12-13T17:16:14.464Z,1513185374.464 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-12-13T17:16:14.485Z,1513185374.485 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 898
2017-12-13T17:16:14.501Z,1513185374.501 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T17:16:14.517Z,1513185374.517 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 899
2017-12-13T17:16:14.526Z,1513185374.526 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-12-13T17:16:14.527Z,1513185374.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-12-13T17:16:14.527Z,1513185374.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-12-13T17:16:14.527Z,1513185374.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-12-13T17:16:14.527Z,1513185374.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-12-13T17:16:14.527Z,1513185374.527 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-12-13T17:16:14.528Z,1513185374.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-12-13T17:16:14.528Z,1513185374.528 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-12-13T17:16:14.528Z,1513185374.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-12-13T17:16:14.528Z,1513185374.528 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-12-13T17:16:14.528Z,1513185374.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-12-13T17:16:14.529Z,1513185374.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-12-13T17:16:14.529Z,1513185374.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-12-13T17:16:14.529Z,1513185374.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-12-13T17:16:14.529Z,1513185374.529 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-12-13T17:16:14.529Z,1513185374.529 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-12-13T17:16:14.565Z,1513185374.565 [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-12-13T17:16:14.566Z,1513185374.566 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-12-13T17:16:14.639Z,1513185374.639 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-12-13T17:16:14.640Z,1513185374.640 [Default:A.Wait](DEBUG): Construct Wait.
2017-12-13T17:16:14.670Z,1513185374.670 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T17:16:14.701Z,1513185374.701 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-12-13T17:16:14.704Z,1513185374.704 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-12-13T17:16:14.727Z,1513185374.727 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T17:16:14.727Z,1513185374.727 [CTD_Seabird](INFO): Powering down
2017-12-13T17:16:14.749Z,1513185374.749 [Default:E.Execute](DEBUG): Construct Execute.
2017-12-13T17:16:14.769Z,1513185374.769 [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-12-13T17:16:14.776Z,1513185374.776 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-12-13T17:16:14.783Z,1513185374.783 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-12-13T17:16:14.784Z,1513185374.784 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-12-13T17:16:14.808Z,1513185374.808 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-12-13T17:16:15.448Z,1513185375.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:15.552Z,1513185375.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:15.661Z,1513185375.661 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:16:15.669Z,1513185375.669 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-13T17:16:15.684Z,1513185375.684 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:16:15.689Z,1513185375.689 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-12-13T17:16:15.710Z,1513185375.710 [MassServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:16:15.717Z,1513185375.717 [MassServo](DEBUG): Initializing MassServo.
2017-12-13T17:16:15.730Z,1513185375.730 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:16:15.738Z,1513185375.738 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-13T17:16:15.751Z,1513185375.751 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:16:15.757Z,1513185375.757 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-12-13T17:16:15.980Z,1513185375.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:15.981Z,1513185375.981 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:16.290Z,1513185376.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:16.291Z,1513185376.291 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:16.621Z,1513185376.621 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-12-13T17:16:16.621Z,1513185376.621 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-12-13T17:16:16.621Z,1513185376.621 [BuoyancyServo] Communications Fault, FailCount= 1
2017-12-13T17:16:16.621Z,1513185376.621 [BuoyancyServo](ERROR): Communications Fault
2017-12-13T17:16:16.828Z,1513185376.828 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-12-13T17:16:16.896Z,1513185376.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:16.897Z,1513185376.897 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:16.918Z,1513185376.918 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-13T17:16:16.918Z,1513185376.918 [BuoyancyServo](INFO): Powering down
2017-12-13T17:16:17.285Z,1513185377.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:17.286Z,1513185377.286 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:17.722Z,1513185377.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:17.723Z,1513185377.723 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:18.085Z,1513185378.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:18.087Z,1513185378.087 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:18.597Z,1513185378.597 [Aanderaa_O2](INFO): Powering down
2017-12-13T17:16:18.708Z,1513185378.708 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:18.709Z,1513185378.709 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T17:16:19.089Z,1513185379.089 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-12-13T17:16:19.089Z,1513185379.089 [BuoyancyServo] No Fault, FailCount= 1
2017-12-13T17:16:19.350Z,1513185379.350 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:16:19.470Z,1513185379.470 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-13T17:16:22.436Z,1513185382.436 [AcousticModem_Benthos_ATM900](DEBUG): 
2017-12-13T17:16:23.637Z,1513185383.637 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2017-12-13T17:16:23.638Z,1513185383.638 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2017-12-13T17:16:23.639Z,1513185383.639 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2017-12-13T17:16:23.639Z,1513185383.639 [AcousticModem_Benthos_ATM900](DEBUG): Feb  6 2000 23:37:56 
2017-12-13T17:16:24.037Z,1513185384.037 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2017-12-13T17:16:24.836Z,1513185384.836 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2017-12-13T17:16:25.236Z,1513185385.236 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2017-12-13T17:16:25.237Z,1513185385.237 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2017-12-13T17:16:25.237Z,1513185385.237 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2017-12-13T17:16:25.636Z,1513185385.636 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2017-12-13T17:16:26.036Z,1513185386.036 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2017-12-13T17:16:26.037Z,1513185386.037 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2017-12-13T17:16:26.437Z,1513185386.437 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2017-12-13T17:16:40.443Z,1513185400.443 [NAL9602](INFO): Powering up NAL9602
2017-12-13T17:16:51.263Z,1513185411.263 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:16:57.906Z,1513185417.906 [CommandLine](IMPORTANT): got command configSet list
2017-12-13T17:16:57.906Z,1513185417.906 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2017-12-13T17:16:57.907Z,1513185417.907 [CommandLine](IMPORTANT): RudderServo.offsetAngle=1 degree;

2017-12-13T17:16:57.908Z,1513185417.908 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=330 milliliter;

2017-12-13T17:16:57.908Z,1513185417.908 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=41 millimeter;

2017-12-13T17:17:17.767Z,1513185437.767 [SBIT](IMPORTANT): Beginning Startup BIT
2017-12-13T17:17:17.771Z,1513185437.771 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T17:17:28.777Z,1513185448.777 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008221
CHAN A1 (24V): -0.013158
CHAN A2 (12V): -0.004913
CHAN A3 (5V): -0.002772
CHAN B0 (3.3V): -0.001246
CHAN B1 (3.15aV): -0.001590
CHAN B2 (3.15bV): -0.001296
CHAN B3 (GND): 0.000179
OPEN: 0.004162
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T17:18:03.279Z,1513185483.279 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:18:03.279Z,1513185483.279 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:18:03.279Z,1513185483.279 [BPC1](ERROR): Communications Fault
2017-12-13T17:18:03.417Z,1513185483.417 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:18:04.605Z,1513185484.605 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:18:04.605Z,1513185484.605 [BPC1] No Fault, FailCount= 1
2017-12-13T17:18:11.762Z,1513185491.762 [SBIT](IMPORTANT): SBIT PASSED
2017-12-13T17:18:12.092Z,1513185492.092 [MissionManager](IMPORTANT): Started mission Startup
2017-12-13T17:18:12.092Z,1513185492.092 [Startup] Running Loop=1
2017-12-13T17:18:12.092Z,1513185492.092 [Startup](DEBUG): Aggregate::initialize Startup
2017-12-13T17:18:12.092Z,1513185492.092 [Startup:A.GoToSurface] Running Loop=1
2017-12-13T17:18:12.092Z,1513185492.092 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T17:18:12.094Z,1513185492.094 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T17:18:12.094Z,1513185492.094 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T17:18:12.095Z,1513185492.095 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T17:18:12.095Z,1513185492.095 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T17:18:12.095Z,1513185492.095 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T17:18:12.101Z,1513185492.101 [Startup:StartupSatComms] Running Loop=1
2017-12-13T17:18:12.102Z,1513185492.102 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-12-13T17:18:12.102Z,1513185492.102 [Startup:StartupSatComms:A] Running Loop=1
2017-12-13T17:18:12.508Z,1513185492.508 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-12-13T17:18:25.275Z,1513185505.275 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:18:25.275Z,1513185505.275 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:18:25.275Z,1513185505.275 [BPC1](ERROR): Communications Fault
2017-12-13T17:18:25.397Z,1513185505.397 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:18:26.561Z,1513185506.561 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:18:26.561Z,1513185506.561 [BPC1] No Fault, FailCount= 2
2017-12-13T17:18:44.585Z,1513185524.585 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003731
2017-12-13T17:19:06.232Z,1513185546.232 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:19:06.233Z,1513185546.233 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:19:06.233Z,1513185546.233 [BPC1](ERROR): Communications Fault
2017-12-13T17:19:06.320Z,1513185546.320 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:19:07.516Z,1513185547.516 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:19:07.516Z,1513185547.516 [BPC1] No Fault, FailCount= 1
2017-12-13T17:19:12.235Z,1513185552.235 [Startup:StartupSatComms:A](INFO): Timed out from 2017-12-13T17:18:12.1Z
2017-12-13T17:19:12.235Z,1513185552.235 [Startup:StartupSatComms:A] Stopped
2017-12-13T17:19:12.235Z,1513185552.235 [Startup:StartupSatComms:B] Running Loop=1
2017-12-13T17:19:12.638Z,1513185552.638 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-13T17:19:16.634Z,1513185556.634 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:19:16.634Z,1513185556.634 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-12-13T17:19:16.634Z,1513185556.634 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-12-13T17:19:16.636Z,1513185556.636 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T17:19:16.636Z,1513185556.636 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-12-13T17:19:16.636Z,1513185556.636 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-12-13T17:19:16.712Z,1513185556.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-12-13T17:19:16.712Z,1513185556.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-12-13T17:19:16.723Z,1513185556.723 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T162306/Courier0024.lzma
2017-12-13T17:19:16.876Z,1513185556.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-12-13T17:19:16.876Z,1513185556.876 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-12-13T17:19:16.876Z,1513185556.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-12-13T17:19:16.877Z,1513185556.877 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-12-13T17:19:17.171Z,1513185557.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-12-13T17:19:17.176Z,1513185557.176 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:19:17.176Z,1513185557.176 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:19:17.177Z,1513185557.177 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-13T17:19:17.177Z,1513185557.177 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T17:19:17.177Z,1513185557.177 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T17:19:17.521Z,1513185557.521 [DataOverHttps](INFO): Moved sent file to Logs/20171213T162306/Courier0024.lzma.bak
2017-12-13T17:19:17.521Z,1513185557.521 [DataOverHttps](INFO): SBD MOMSN=5435081
2017-12-13T17:19:17.585Z,1513185557.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.0 s old, using for 20.0 s.
2017-12-13T17:19:18.030Z,1513185558.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2017-12-13T17:19:18.379Z,1513185558.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2017-12-13T17:19:21.590Z,1513185561.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s.
2017-12-13T17:19:21.745Z,1513185561.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2017-12-13T17:19:22.139Z,1513185562.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2017-12-13T17:19:22.578Z,1513185562.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2017-12-13T17:19:22.935Z,1513185562.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2017-12-13T17:19:23.374Z,1513185563.374 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2017-12-13T17:19:26.485Z,1513185566.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2017-12-13T17:19:26.573Z,1513185566.573 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20171213T171603/Courier0000.lzma
2017-12-13T17:19:26.651Z,1513185566.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-12-13T17:19:27.034Z,1513185567.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-12-13T17:19:27.373Z,1513185567.373 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0000.lzma.bak
2017-12-13T17:19:27.373Z,1513185567.373 [DataOverHttps](INFO): SBD MOMSN=5435083
2017-12-13T17:19:27.449Z,1513185567.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-12-13T17:19:27.836Z,1513185567.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-12-13T17:19:28.230Z,1513185568.230 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-12-13T17:19:31.431Z,1513185571.431 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-12-13T17:19:31.678Z,1513185571.678 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2017-12-13T17:19:32.050Z,1513185572.050 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2017-12-13T17:19:32.442Z,1513185572.442 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2017-12-13T17:19:32.840Z,1513185572.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2017-12-13T17:19:33.239Z,1513185573.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2017-12-13T17:19:36.427Z,1513185576.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2017-12-13T17:19:36.522Z,1513185576.522 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20171213T162306/Express0025.lzma
2017-12-13T17:19:36.583Z,1513185576.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2017-12-13T17:19:37.321Z,1513185577.321 [DataOverHttps](INFO): Moved sent file to Logs/20171213T162306/Express0025.lzma.bak
2017-12-13T17:19:37.321Z,1513185577.321 [DataOverHttps](INFO): SBD MOMSN=5435086
2017-12-13T17:19:46.651Z,1513185586.651 [DataOverHttps](INFO): Sending 812 bytes from file Logs/20171213T171603/Express0001.lzma
2017-12-13T17:19:47.449Z,1513185587.449 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0001.lzma.bak
2017-12-13T17:19:47.449Z,1513185587.449 [DataOverHttps](INFO): SBD MOMSN=5435090
2017-12-13T17:19:47.909Z,1513185587.909 [Startup:StartupSatComms:B] Stopped
2017-12-13T17:19:47.909Z,1513185587.909 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-12-13T17:19:47.909Z,1513185587.909 [Startup:StartupSatComms] Stopped
2017-12-13T17:19:47.909Z,1513185587.909 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-12-13T17:19:47.910Z,1513185587.910 [Startup](INFO): Completed Startup
2017-12-13T17:19:47.910Z,1513185587.910 [MissionManager](INFO): Startup is completed.
2017-12-13T17:19:47.910Z,1513185587.910 [MissionManager](INFO): Uninitializing Mission Startup
2017-12-13T17:19:47.910Z,1513185587.910 [Startup] Stopped
2017-12-13T17:19:47.910Z,1513185587.910 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-12-13T17:19:47.910Z,1513185587.910 [Startup:A.GoToSurface] Stopped
2017-12-13T17:19:47.910Z,1513185587.910 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T17:19:48.325Z,1513185588.325 [MissionManager](IMPORTANT): Started mission Default
2017-12-13T17:19:48.325Z,1513185588.325 [Default] Running Loop=1
2017-12-13T17:19:48.325Z,1513185588.325 [Default](DEBUG): Aggregate::initialize Default
2017-12-13T17:19:48.325Z,1513185588.325 [Default:B.GoToSurface] Running Loop=1
2017-12-13T17:19:48.325Z,1513185588.325 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T17:19:48.325Z,1513185588.325 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T17:19:48.326Z,1513185588.326 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T17:19:48.326Z,1513185588.326 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T17:19:48.326Z,1513185588.326 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T17:19:48.327Z,1513185588.327 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T17:19:48.327Z,1513185588.327 [Default:A.Wait] Running Loop=1
2017-12-13T17:19:48.327Z,1513185588.327 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:20:01.625Z,1513185601.625 [Default:A.Wait](INFO): Done Waiting.
2017-12-13T17:20:01.625Z,1513185601.625 [Default:A.Wait] Stopped
2017-12-13T17:20:01.625Z,1513185601.625 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:20:02.005Z,1513185602.005 [Default:CheckIn] Running Loop=1
2017-12-13T17:20:02.005Z,1513185602.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:20:02.005Z,1513185602.005 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:20:02.413Z,1513185602.413 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-12-13T17:20:08.375Z,1513185608.375 [NAL9602](INFO): SBD MO Status=2, MOMSN=19290, MT Status=2, MTMSN=0
2017-12-13T17:20:08.375Z,1513185608.375 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:20:58.505Z,1513185658.505 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:20:58.505Z,1513185658.505 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:20:58.505Z,1513185658.505 [BPC1](ERROR): Communications Fault
2017-12-13T17:20:58.624Z,1513185658.624 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:20:59.820Z,1513185659.820 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:20:59.820Z,1513185659.820 [BPC1] No Fault, FailCount= 1
2017-12-13T17:21:45.634Z,1513185705.634 [CommandLine](IMPORTANT): got command burn 5.000000
2017-12-13T17:21:50.648Z,1513185710.648 [CommandLine](IMPORTANT): Deactivating dropweight wire
2017-12-13T17:21:52.218Z,1513185712.218 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:21:52.218Z,1513185712.218 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:21:52.219Z,1513185712.219 [BPC1](ERROR): Communications Fault
2017-12-13T17:21:52.348Z,1513185712.348 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:21:53.516Z,1513185713.516 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:21:53.516Z,1513185713.516 [BPC1] No Fault, FailCount= 1
2017-12-13T17:21:54.197Z,1513185714.197 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-12-13T17:21:55.403Z,1513185715.403 [NAL9602](INFO): GPS fix at 20171213T172155: (36.802901, -121.788026)
2017-12-13T17:21:55.443Z,1513185715.443 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:21:55.443Z,1513185715.443 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T17:21:55.867Z,1513185715.867 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-13T17:22:01.773Z,1513185721.773 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20171213T171603/Courier0004.lzma
2017-12-13T17:22:02.573Z,1513185722.573 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0004.lzma.bak
2017-12-13T17:22:02.573Z,1513185722.573 [DataOverHttps](INFO): SBD MOMSN=5435118
2017-12-13T17:22:13.989Z,1513185733.989 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20171213T171603/Express0005.lzma
2017-12-13T17:22:14.789Z,1513185734.789 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0005.lzma.bak
2017-12-13T17:22:14.789Z,1513185734.789 [DataOverHttps](INFO): SBD MOMSN=5435121
2017-12-13T17:22:15.269Z,1513185735.269 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T17:22:15.269Z,1513185735.269 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T17:22:15.269Z,1513185735.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:22:31.813Z,1513185751.813 [NAL9602](INFO): Powering down
2017-12-13T17:22:43.008Z,1513185763.008 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:43.407Z,1513185763.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:43.807Z,1513185763.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:44.207Z,1513185764.207 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:44.607Z,1513185764.607 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:45.010Z,1513185765.010 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:45.407Z,1513185765.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:45.807Z,1513185765.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:46.207Z,1513185766.207 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:46.607Z,1513185766.607 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:47.007Z,1513185767.007 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:47.407Z,1513185767.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:47.807Z,1513185767.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:48.207Z,1513185768.207 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:48.607Z,1513185768.607 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:48.651Z,1513185768.651 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:22:48.651Z,1513185768.651 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:22:48.651Z,1513185768.651 [BPC1](ERROR): Communications Fault
2017-12-13T17:22:48.764Z,1513185768.764 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:22:49.007Z,1513185769.007 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:49.407Z,1513185769.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:49.807Z,1513185769.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:49.964Z,1513185769.964 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:22:49.964Z,1513185769.964 [BPC1] No Fault, FailCount= 1
2017-12-13T17:22:50.207Z,1513185770.207 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:50.607Z,1513185770.607 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:51.007Z,1513185771.007 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:51.407Z,1513185771.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:51.807Z,1513185771.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:52.208Z,1513185772.208 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:52.607Z,1513185772.607 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:53.007Z,1513185773.007 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:53.407Z,1513185773.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:53.807Z,1513185773.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:54.207Z,1513185774.207 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:54.607Z,1513185774.607 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:55.007Z,1513185775.007 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:55.407Z,1513185775.407 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:22:55.807Z,1513185775.807 [AcousticModem_Benthos_ATM900](DEBUG): LCB error: Software Overcurrent.
2017-12-13T17:23:10.646Z,1513185790.646 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:23:10.646Z,1513185790.646 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:23:10.646Z,1513185790.646 [BPC1](ERROR): Communications Fault
2017-12-13T17:23:10.736Z,1513185790.736 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:23:11.940Z,1513185791.940 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:23:11.940Z,1513185791.940 [BPC1] No Fault, FailCount= 2
2017-12-13T17:23:53.410Z,1513185833.410 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:23:53.410Z,1513185833.410 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:23:53.410Z,1513185833.410 [BPC1](ERROR): Communications Fault
2017-12-13T17:23:53.505Z,1513185833.505 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:23:54.705Z,1513185834.705 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:23:54.705Z,1513185834.705 [BPC1] No Fault, FailCount= 1
2017-12-13T17:24:45.935Z,1513185885.935 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:24:45.935Z,1513185885.935 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:24:45.935Z,1513185885.935 [BPC1](ERROR): Communications Fault
2017-12-13T17:24:46.026Z,1513185886.026 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:24:47.228Z,1513185887.228 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:24:47.228Z,1513185887.228 [BPC1] No Fault, FailCount= 1
2017-12-13T17:25:07.940Z,1513185907.940 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:25:07.940Z,1513185907.940 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:25:07.940Z,1513185907.940 [BPC1](ERROR): Communications Fault
2017-12-13T17:25:08.048Z,1513185908.048 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:25:09.254Z,1513185909.254 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:25:09.254Z,1513185909.254 [BPC1] No Fault, FailCount= 2
2017-12-13T17:25:48.883Z,1513185948.883 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:25:48.883Z,1513185948.883 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:25:48.883Z,1513185948.883 [BPC1](ERROR): Communications Fault
2017-12-13T17:25:48.972Z,1513185948.972 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:25:50.172Z,1513185950.172 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:25:50.172Z,1513185950.172 [BPC1] No Fault, FailCount= 1
2017-12-13T17:26:14.572Z,1513185974.572 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T17:26:43.511Z,1513186003.511 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:26:43.511Z,1513186003.511 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:26:43.511Z,1513186003.511 [BPC1](ERROR): Communications Fault
2017-12-13T17:26:43.600Z,1513186003.600 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:26:44.796Z,1513186004.796 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:26:44.796Z,1513186004.796 [BPC1] No Fault, FailCount= 1
2017-12-13T17:27:15.949Z,1513186035.949 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T17:27:15.949Z,1513186035.949 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:27:15.949Z,1513186035.949 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:27:15.950Z,1513186035.950 [Default:CheckIn:D] Running Loop=1
2017-12-13T17:27:16.347Z,1513186036.347 [Default:CheckIn:D] Stopped
2017-12-13T17:27:16.348Z,1513186036.348 [Default:CheckIn:E] Running Loop=1
2017-12-13T17:27:16.738Z,1513186036.738 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.467013 min 
2017-12-13T17:27:16.740Z,1513186036.740 [Default:CheckIn:E] Stopped
2017-12-13T17:27:16.740Z,1513186036.740 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T17:27:16.740Z,1513186036.740 [Default:CheckIn] Stopped
2017-12-13T17:27:16.741Z,1513186036.741 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:27:16.741Z,1513186036.741 [Default:CheckIn](INFO): Running loop #2
2017-12-13T17:27:16.741Z,1513186036.741 [Default:CheckIn] Running Loop=2
2017-12-13T17:27:16.741Z,1513186036.741 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:27:16.741Z,1513186036.741 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:27:20.047Z,1513186040.047 [NAL9602](INFO): Powering up
2017-12-13T17:27:30.853Z,1513186050.853 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:27:40.487Z,1513186060.487 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:27:40.487Z,1513186060.487 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:27:40.487Z,1513186060.487 [BPC1](ERROR): Communications Fault
2017-12-13T17:27:40.580Z,1513186060.580 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:27:41.775Z,1513186061.775 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:27:41.775Z,1513186061.775 [BPC1] No Fault, FailCount= 1
2017-12-13T17:28:02.857Z,1513186082.857 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2017-12-13T17:28:03.376Z,1513186083.376 [NAL9602](FAULT): received: 
2017-12-13T17:28:03.376Z,1513186083.376 [NAL9602] Data Fault, FailCount= 1
2017-12-13T17:28:03.376Z,1513186083.376 [NAL9602](ERROR): Data Fault
2017-12-13T17:28:03.509Z,1513186083.509 [CBIT](ERROR): Data Fault in component: NAL9602
2017-12-13T17:28:03.518Z,1513186083.518 [NAL9602](INFO): Powering down
2017-12-13T17:28:04.465Z,1513186084.465 [CBIT](INFO): Clearing failed state for component NAL9602
2017-12-13T17:28:04.465Z,1513186084.465 [NAL9602] No Fault, FailCount= 1
2017-12-13T17:28:33.669Z,1513186113.669 [NAL9602](INFO): Powering up NAL9602
2017-12-13T17:28:35.299Z,1513186115.299 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:28:35.299Z,1513186115.299 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:28:35.299Z,1513186115.299 [BPC1](ERROR): Communications Fault
2017-12-13T17:28:35.436Z,1513186115.436 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:28:36.592Z,1513186116.592 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:28:36.592Z,1513186116.592 [BPC1] No Fault, FailCount= 1
2017-12-13T17:28:44.865Z,1513186124.865 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:29:35.586Z,1513186175.586 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:29:35.587Z,1513186175.587 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:29:35.587Z,1513186175.587 [BPC1](ERROR): Communications Fault
2017-12-13T17:29:35.716Z,1513186175.716 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:29:36.884Z,1513186176.884 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:29:36.884Z,1513186176.884 [BPC1] No Fault, FailCount= 1
2017-12-13T17:30:29.087Z,1513186229.087 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:30:29.087Z,1513186229.087 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:30:29.087Z,1513186229.087 [BPC1](ERROR): Communications Fault
2017-12-13T17:30:29.177Z,1513186229.177 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:30:30.388Z,1513186230.388 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:30:30.388Z,1513186230.388 [BPC1] No Fault, FailCount= 1
2017-12-13T17:31:29.248Z,1513186289.248 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:31:29.248Z,1513186289.248 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:31:29.248Z,1513186289.248 [BPC1](ERROR): Communications Fault
2017-12-13T17:31:29.340Z,1513186289.340 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:31:30.548Z,1513186290.548 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:31:30.548Z,1513186290.548 [BPC1] No Fault, FailCount= 1
2017-12-13T17:31:38.052Z,1513186298.052 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287
2017-12-13T17:31:51.251Z,1513186311.251 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:31:51.251Z,1513186311.251 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:31:51.251Z,1513186311.251 [BPC1](ERROR): Communications Fault
2017-12-13T17:31:51.341Z,1513186311.341 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:31:52.544Z,1513186312.544 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:31:52.544Z,1513186312.544 [BPC1] No Fault, FailCount= 2
2017-12-13T17:31:56.818Z,1513186316.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=19290, MT Status=2, MTMSN=0
2017-12-13T17:31:56.819Z,1513186316.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:32:00.452Z,1513186320.452 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-12-13T17:32:12.414Z,1513186332.414 [NAL9602](INFO): SBD MO Status=2, MOMSN=19290, MT Status=2, MTMSN=0
2017-12-13T17:32:12.415Z,1513186332.415 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:32:14.854Z,1513186334.854 [PNI_TCM](ERROR): CRC does not match. Expected:0x3361 got:0x145
2017-12-13T17:32:16.896Z,1513186336.896 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-12-13T17:27:16.7Z
2017-12-13T17:32:16.896Z,1513186336.896 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:32:16.896Z,1513186336.896 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T17:32:22.629Z,1513186342.629 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20171213T171603/Courier0008.lzma
2017-12-13T17:32:23.429Z,1513186343.429 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0008.lzma.bak
2017-12-13T17:32:23.429Z,1513186343.429 [DataOverHttps](INFO): SBD MOMSN=5435180
2017-12-13T17:32:32.556Z,1513186352.556 [NAL9602](INFO): SBD MO Status=2, MOMSN=19290, MT Status=2, MTMSN=0
2017-12-13T17:32:32.556Z,1513186352.556 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:32:32.598Z,1513186352.598 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:32:32.599Z,1513186352.599 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:32:32.599Z,1513186352.599 [BPC1](ERROR): Communications Fault
2017-12-13T17:32:32.684Z,1513186352.684 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:32:32.694Z,1513186352.694 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20171213T171603/Express0009.lzma
2017-12-13T17:32:33.493Z,1513186353.493 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0009.lzma.bak
2017-12-13T17:32:33.493Z,1513186353.493 [DataOverHttps](INFO): SBD MOMSN=5435182
2017-12-13T17:32:33.636Z,1513186353.636 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:32:33.636Z,1513186353.636 [BPC1] No Fault, FailCount= 1
2017-12-13T17:32:33.903Z,1513186353.903 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-12-13T17:32:34.063Z,1513186354.063 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T17:32:34.063Z,1513186354.063 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T17:32:34.063Z,1513186354.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:33:04.509Z,1513186384.509 [NAL9602](INFO): Powering down
2017-12-13T17:33:26.136Z,1513186406.136 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:33:26.136Z,1513186406.136 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:33:26.136Z,1513186406.136 [BPC1](ERROR): Communications Fault
2017-12-13T17:33:26.228Z,1513186406.228 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:33:27.428Z,1513186407.428 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:33:27.428Z,1513186407.428 [BPC1] No Fault, FailCount= 1
2017-12-13T17:33:39.658Z,1513186419.658 [PNI_TCM](ERROR): CRC does not match. Expected:0x50204 got:0x37660
2017-12-13T17:33:47.651Z,1513186427.651 [PNI_TCM](ERROR): CRC does not match. Expected:0x48153 got:0x36125
2017-12-13T17:33:49.254Z,1513186429.254 [PNI_TCM](ERROR): CRC does not match. Expected:0x34332 got:0x6465
2017-12-13T17:33:54.051Z,1513186434.051 [PNI_TCM](ERROR): CRC does not match. Expected:0x2951 got:0x16813
2017-12-13T17:33:55.653Z,1513186435.653 [PNI_TCM](ERROR): CRC does not match. Expected:0x37402 got:0x6976
2017-12-13T17:33:56.857Z,1513186436.857 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T17:33:56.857Z,1513186436.857 [BPC1] Data Fault, FailCount= 1
2017-12-13T17:33:56.857Z,1513186436.857 [BPC1](ERROR): Data Fault
2017-12-13T17:33:56.988Z,1513186436.988 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:33:58.156Z,1513186438.156 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:33:58.156Z,1513186438.156 [BPC1] No Fault, FailCount= 1
2017-12-13T17:34:03.652Z,1513186443.652 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-12-13T17:34:06.853Z,1513186446.853 [PNI_TCM](ERROR): CRC does not match. Expected:0x12770 got:0x7233
2017-12-13T17:34:18.050Z,1513186458.050 [PNI_TCM](ERROR): CRC does not match. Expected:0x32070 got:0x17293
2017-12-13T17:34:18.850Z,1513186458.850 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:34:18.850Z,1513186458.850 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:34:18.850Z,1513186458.850 [BPC1](ERROR): Communications Fault
2017-12-13T17:34:18.974Z,1513186458.974 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:34:19.650Z,1513186459.650 [PNI_TCM](ERROR): CRC does not match. Expected:0x38635 got:0x2072
2017-12-13T17:34:20.176Z,1513186460.176 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:34:20.176Z,1513186460.176 [BPC1] No Fault, FailCount= 2
2017-12-13T17:34:21.253Z,1513186461.253 [PNI_TCM](ERROR): CRC does not match. Expected:0x26022 got:0x6021
2017-12-13T17:34:22.853Z,1513186462.853 [PNI_TCM](ERROR): CRC does not match. Expected:0x48865 got:0x7233
2017-12-13T17:34:32.452Z,1513186472.452 [PNI_TCM](ERROR): CRC does not match. Expected:0x32070 got:0x17293
2017-12-13T17:34:41.025Z,1513186481.025 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:34:41.025Z,1513186481.025 [BPC1] Communications Fault, FailCount= 3
2017-12-13T17:34:41.025Z,1513186481.025 [BPC1](ERROR): Communications Fault
2017-12-13T17:34:41.119Z,1513186481.119 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:34:42.320Z,1513186482.320 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:34:42.320Z,1513186482.320 [BPC1] No Fault, FailCount= 3
2017-12-13T17:35:22.220Z,1513186522.220 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:35:22.220Z,1513186522.220 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:35:22.220Z,1513186522.220 [BPC1](ERROR): Communications Fault
2017-12-13T17:35:22.332Z,1513186522.332 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:35:23.532Z,1513186523.532 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:35:23.532Z,1513186523.532 [BPC1] No Fault, FailCount= 1
2017-12-13T17:35:58.763Z,1513186558.763 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:35:58.763Z,1513186558.763 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:35:58.763Z,1513186558.763 [BPC1](ERROR): Communications Fault
2017-12-13T17:35:58.852Z,1513186558.852 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:36:00.068Z,1513186560.068 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:36:00.068Z,1513186560.068 [BPC1] No Fault, FailCount= 1
2017-12-13T17:36:14.856Z,1513186574.856 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T17:36:39.773Z,1513186599.773 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:36:39.773Z,1513186599.773 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:36:39.774Z,1513186599.774 [BPC1](ERROR): Communications Fault
2017-12-13T17:36:39.901Z,1513186599.901 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:36:40.865Z,1513186600.865 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:36:40.865Z,1513186600.865 [BPC1] No Fault, FailCount= 1
2017-12-13T17:37:22.846Z,1513186642.846 [CommandLine](IMPORTANT): got command ibit
2017-12-13T17:37:22.854Z,1513186642.854 [IBIT](IMPORTANT): Beginning Initiated BIT
2017-12-13T17:37:22.854Z,1513186642.854 [IBIT](IMPORTANT): Beginning control surface checks.
2017-12-13T17:37:22.857Z,1513186642.857 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T17:37:23.123Z,1513186643.123 [NAL9602](INFO): Powering up
2017-12-13T17:37:33.650Z,1513186653.650 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005760
CHAN A1 (24V): -0.015669
CHAN A2 (12V): -0.005569
CHAN A3 (5V): -0.002778
CHAN B0 (3.3V): -0.000730
CHAN B1 (3.15aV): -0.000655
CHAN B2 (3.15bV): -0.000925
CHAN B3 (GND): 0.000800
OPEN: 0.004267
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T17:37:34.319Z,1513186654.319 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:37:35.119Z,1513186655.119 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-12-13T17:37:35.119Z,1513186655.119 [NAL9602] Data Fault, FailCount= 2
2017-12-13T17:37:35.119Z,1513186655.119 [NAL9602](ERROR): Data Fault
2017-12-13T17:37:35.280Z,1513186655.280 [CBIT](ERROR): Data Fault in component: NAL9602
2017-12-13T17:37:35.513Z,1513186655.513 [NAL9602](INFO): Powering down
2017-12-13T17:37:36.456Z,1513186656.456 [CBIT](INFO): Clearing failed state for component NAL9602
2017-12-13T17:37:36.456Z,1513186656.456 [NAL9602] No Fault, FailCount= 2
2017-12-13T17:38:00.918Z,1513186680.918 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:38:00.918Z,1513186680.918 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:38:00.918Z,1513186680.918 [BPC1](ERROR): Communications Fault
2017-12-13T17:38:01.036Z,1513186681.036 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:38:02.203Z,1513186682.203 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:38:02.203Z,1513186682.203 [BPC1] No Fault, FailCount= 1
2017-12-13T17:38:05.686Z,1513186685.686 [NAL9602](INFO): Powering up NAL9602
2017-12-13T17:38:16.889Z,1513186696.889 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:38:55.106Z,1513186735.106 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:38:55.106Z,1513186735.106 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:38:55.106Z,1513186735.106 [BPC1](ERROR): Communications Fault
2017-12-13T17:38:55.245Z,1513186735.245 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:38:56.408Z,1513186736.408 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:38:56.408Z,1513186736.408 [BPC1] No Fault, FailCount= 1
2017-12-13T17:39:15.989Z,1513186755.989 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2017-12-13T17:39:16.421Z,1513186756.421 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 345.410004
Voltage: 16.188387
2017-12-13T17:39:16.421Z,1513186756.421 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2017-12-13T17:39:16.422Z,1513186756.422 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2017-12-13T17:39:16.793Z,1513186756.793 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2017-12-13T17:39:16.794Z,1513186756.794 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2017-12-13T17:39:16.794Z,1513186756.794 [IBIT](IMPORTANT): Pressure:8.671073 PSI
2017-12-13T17:39:16.794Z,1513186756.794 [IBIT](IMPORTANT): Humidity:0.421194 %
2017-12-13T17:39:17.197Z,1513186757.197 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2017-12-13T17:39:17.198Z,1513186757.198 [IBIT](IMPORTANT): buoyancyNeutral: 330.000000 cc
2017-12-13T17:39:17.198Z,1513186757.198 [IBIT](IMPORTANT): massDefault: 1.100000 cm
2017-12-13T17:39:17.198Z,1513186757.198 [IBIT](IMPORTANT): stopDepth: 60.000000 m
2017-12-13T17:39:17.199Z,1513186757.199 [IBIT](IMPORTANT): abortDepth: 75.000000 m
2017-12-13T17:39:17.199Z,1513186757.199 [IBIT](IMPORTANT): IBIT FAILED
2017-12-13T17:39:17.549Z,1513186757.549 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T17:39:17.549Z,1513186757.549 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:39:17.549Z,1513186757.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:39:17.549Z,1513186757.549 [Default:CheckIn:D] Running Loop=1
2017-12-13T17:39:17.926Z,1513186757.926 [Default:CheckIn:D] Stopped
2017-12-13T17:39:17.926Z,1513186757.926 [Default:CheckIn:E] Running Loop=1
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.493345 min 
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn:E] Stopped
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn] Stopped
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn](INFO): Running loop #3
2017-12-13T17:39:18.330Z,1513186758.330 [Default:CheckIn] Running Loop=3
2017-12-13T17:39:18.331Z,1513186758.331 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:39:18.331Z,1513186758.331 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:39:49.287Z,1513186789.287 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:39:49.287Z,1513186789.287 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:39:49.287Z,1513186789.287 [BPC1](ERROR): Communications Fault
2017-12-13T17:39:49.428Z,1513186789.428 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:39:50.600Z,1513186790.600 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:39:50.600Z,1513186790.600 [BPC1] No Fault, FailCount= 1
2017-12-13T17:40:15.344Z,1513186815.344 [CommandLine](IMPORTANT): got command ibit
2017-12-13T17:40:15.386Z,1513186815.386 [IBIT](IMPORTANT): Beginning Initiated BIT
2017-12-13T17:40:15.386Z,1513186815.386 [IBIT](IMPORTANT): Beginning control surface checks.
2017-12-13T17:40:15.389Z,1513186815.389 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T17:40:19.283Z,1513186819.283 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T17:40:19.284Z,1513186819.284 [BPC1] Data Fault, FailCount= 1
2017-12-13T17:40:19.284Z,1513186819.284 [BPC1](ERROR): Data Fault
2017-12-13T17:40:19.438Z,1513186819.438 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:40:20.718Z,1513186820.718 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:40:20.718Z,1513186820.718 [BPC1] No Fault, FailCount= 1
2017-12-13T17:40:26.221Z,1513186826.221 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005882
CHAN A1 (24V): -0.015542
CHAN A2 (12V): -0.005264
CHAN A3 (5V): -0.002989
CHAN B0 (3.3V): -0.001073
CHAN B1 (3.15aV): -0.001264
CHAN B2 (3.15bV): -0.001011
CHAN B3 (GND): 0.000283
OPEN: 0.004308
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T17:40:43.649Z,1513186843.649 [PNI_TCM](ERROR): CRC does not match. Expected:0x11429 got:0x12677
2017-12-13T17:40:45.251Z,1513186845.251 [PNI_TCM](ERROR): CRC does not match. Expected:0x4431 got:0x1857
2017-12-13T17:40:46.850Z,1513186846.850 [PNI_TCM](ERROR): CRC does not match. Expected:0x51539 got:0x49477
2017-12-13T17:40:51.650Z,1513186851.650 [PNI_TCM](ERROR): CRC does not match. Expected:0x26930 got:0x59694
2017-12-13T17:40:56.450Z,1513186856.450 [PNI_TCM](ERROR): CRC does not match. Expected:0x56006 got:0x17709
2017-12-13T17:41:02.853Z,1513186862.853 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:41:02.853Z,1513186862.853 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:41:02.853Z,1513186862.853 [BPC1](ERROR): Communications Fault
2017-12-13T17:41:02.976Z,1513186862.976 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:41:04.140Z,1513186864.140 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:41:04.140Z,1513186864.140 [BPC1] No Fault, FailCount= 1
2017-12-13T17:41:17.257Z,1513186877.257 [PNI_TCM](ERROR): CRC does not match. Expected:0x4077 got:0x33088
2017-12-13T17:41:30.050Z,1513186890.050 [PNI_TCM](ERROR): CRC does not match. Expected:0x6569 got:0x85
2017-12-13T17:41:31.654Z,1513186891.654 [PNI_TCM](ERROR): CRC does not match. Expected:0x49143 got:0x9757
2017-12-13T17:41:33.250Z,1513186893.250 [PNI_TCM](ERROR): CRC does not match. Expected:0x56980 got:0x7617
2017-12-13T17:41:34.848Z,1513186894.848 [PNI_TCM](ERROR): CRC does not match. Expected:0x52762 got:0x32896
2017-12-13T17:41:36.450Z,1513186896.450 [PNI_TCM](ERROR): CRC does not match. Expected:0x9237 got:0x7617
2017-12-13T17:41:43.405Z,1513186903.405 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:41:43.405Z,1513186903.405 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:41:43.405Z,1513186903.405 [BPC1](ERROR): Communications Fault
2017-12-13T17:41:43.524Z,1513186903.524 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:41:44.740Z,1513186904.740 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:41:44.741Z,1513186904.741 [BPC1] No Fault, FailCount= 1
2017-12-13T17:42:06.289Z,1513186926.289 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2017-12-13T17:42:06.714Z,1513186926.714 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 345.262024
Voltage: 16.184919
2017-12-13T17:42:06.714Z,1513186926.714 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2017-12-13T17:42:06.714Z,1513186926.714 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2017-12-13T17:42:07.098Z,1513186927.098 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2017-12-13T17:42:07.099Z,1513186927.099 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2017-12-13T17:42:07.099Z,1513186927.099 [IBIT](IMPORTANT): Pressure:8.661859 PSI
2017-12-13T17:42:07.099Z,1513186927.099 [IBIT](IMPORTANT): Humidity:0.366256 %
2017-12-13T17:42:07.489Z,1513186927.489 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2017-12-13T17:42:07.490Z,1513186927.490 [IBIT](IMPORTANT): buoyancyNeutral: 330.000000 cc
2017-12-13T17:42:07.490Z,1513186927.490 [IBIT](IMPORTANT): massDefault: 1.100000 cm
2017-12-13T17:42:07.490Z,1513186927.490 [IBIT](IMPORTANT): stopDepth: 60.000000 m
2017-12-13T17:42:07.490Z,1513186927.490 [IBIT](IMPORTANT): abortDepth: 75.000000 m
2017-12-13T17:42:07.491Z,1513186927.491 [IBIT](IMPORTANT): IBIT FAILED
2017-12-13T17:42:31.378Z,1513186951.378 [NAL9602](INFO): SBD MO Status=2, MOMSN=19290, MT Status=2, MTMSN=0
2017-12-13T17:42:31.379Z,1513186951.379 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:42:39.852Z,1513186959.852 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:42:39.852Z,1513186959.852 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:42:39.852Z,1513186959.852 [BPC1](ERROR): Communications Fault
2017-12-13T17:42:39.988Z,1513186959.988 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:42:40.948Z,1513186960.948 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:42:40.948Z,1513186960.948 [BPC1] No Fault, FailCount= 1
2017-12-13T17:42:49.602Z,1513186969.602 [NAL9602](INFO): SBD MO Status=2, MOMSN=19290, MT Status=2, MTMSN=0
2017-12-13T17:42:49.603Z,1513186969.603 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:42:50.830Z,1513186970.830 [PNI_TCM](ERROR): CRC does not match. Expected:0x25900 got:0x1347
2017-12-13T17:43:09.206Z,1513186989.206 [NAL9602](INFO): SBD MO Status=0, MOMSN=19290, MT Status=0, MTMSN=0
2017-12-13T17:43:09.207Z,1513186989.207 [NAL9602](INFO): No messages in MT queue
2017-12-13T17:43:10.405Z,1513186990.405 [NAL9602](INFO): GPS fix at 20171213T174309: (36.802947, -121.787991)
2017-12-13T17:43:10.485Z,1513186990.485 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:43:10.485Z,1513186990.485 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T17:43:15.054Z,1513186995.054 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20171213T171603/Courier0012.lzma
2017-12-13T17:43:15.854Z,1513186995.854 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0012.lzma.bak
2017-12-13T17:43:15.854Z,1513186995.854 [DataOverHttps](INFO): SBD MOMSN=5435230
2017-12-13T17:43:24.899Z,1513187004.899 [DataOverHttps](INFO): Sending 898 bytes from file Logs/20171213T171603/Express0013.lzma
2017-12-13T17:43:25.697Z,1513187005.697 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0013.lzma.bak
2017-12-13T17:43:25.697Z,1513187005.697 [DataOverHttps](INFO): SBD MOMSN=5435233
2017-12-13T17:43:26.162Z,1513187006.162 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T17:43:26.162Z,1513187006.162 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T17:43:26.162Z,1513187006.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:43:33.736Z,1513187013.736 [CommandLine](IMPORTANT): got command configSet list
2017-12-13T17:43:33.737Z,1513187013.737 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): RudderServo.offsetAngle=1 degree;

2017-12-13T17:43:33.738Z,1513187013.738 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=330 milliliter;

2017-12-13T17:43:33.739Z,1513187013.739 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=41 millimeter;

2017-12-13T17:43:36.092Z,1513187016.092 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:43:36.092Z,1513187016.092 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:43:36.092Z,1513187016.092 [BPC1](ERROR): Communications Fault
2017-12-13T17:43:36.212Z,1513187016.212 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:43:37.385Z,1513187017.385 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:43:37.385Z,1513187017.385 [BPC1] No Fault, FailCount= 1
2017-12-13T17:43:45.258Z,1513187025.258 [NAL9602](INFO): Powering down
2017-12-13T17:44:34.058Z,1513187074.058 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:44:34.058Z,1513187074.058 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:44:34.058Z,1513187074.058 [BPC1](ERROR): Communications Fault
2017-12-13T17:44:34.341Z,1513187074.341 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:44:35.392Z,1513187075.392 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:44:35.392Z,1513187075.392 [BPC1] No Fault, FailCount= 1
2017-12-13T17:44:56.194Z,1513187096.194 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:44:56.194Z,1513187096.194 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:44:56.194Z,1513187096.194 [BPC1](ERROR): Communications Fault
2017-12-13T17:44:56.452Z,1513187096.452 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:44:57.512Z,1513187097.512 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:44:57.513Z,1513187097.513 [BPC1] No Fault, FailCount= 2
2017-12-13T17:45:26.934Z,1513187126.934 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T17:45:26.934Z,1513187126.934 [BPC1] Data Fault, FailCount= 1
2017-12-13T17:45:26.934Z,1513187126.934 [BPC1](ERROR): Data Fault
2017-12-13T17:45:27.085Z,1513187127.085 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:45:31.275Z,1513187131.275 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:45:31.275Z,1513187131.275 [BPC1] No Fault, FailCount= 1
2017-12-13T17:45:51.946Z,1513187151.946 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:45:51.946Z,1513187151.946 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:45:51.946Z,1513187151.946 [BPC1](ERROR): Communications Fault
2017-12-13T17:45:52.084Z,1513187152.084 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:45:53.240Z,1513187153.240 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:45:53.240Z,1513187153.240 [BPC1] No Fault, FailCount= 2
2017-12-13T17:46:13.965Z,1513187173.965 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:46:13.965Z,1513187173.965 [BPC1] Communications Fault, FailCount= 3
2017-12-13T17:46:13.965Z,1513187173.965 [BPC1](ERROR): Communications Fault
2017-12-13T17:46:14.125Z,1513187174.125 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:46:14.977Z,1513187174.977 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T17:46:15.264Z,1513187175.264 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:46:15.264Z,1513187175.264 [BPC1] No Fault, FailCount= 3
2017-12-13T17:46:54.913Z,1513187214.913 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:46:54.913Z,1513187214.913 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:46:54.913Z,1513187214.913 [BPC1](ERROR): Communications Fault
2017-12-13T17:46:55.024Z,1513187215.024 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:46:56.200Z,1513187216.200 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:46:56.200Z,1513187216.200 [BPC1] No Fault, FailCount= 1
2017-12-13T17:48:26.793Z,1513187306.793 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T17:48:26.793Z,1513187306.793 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:48:26.793Z,1513187306.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:48:26.793Z,1513187306.793 [Default:CheckIn:D] Running Loop=1
2017-12-13T17:48:27.169Z,1513187307.169 [Default:CheckIn:D] Stopped
2017-12-13T17:48:27.169Z,1513187307.169 [Default:CheckIn:E] Running Loop=1
2017-12-13T17:48:27.574Z,1513187307.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.647247 min 
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn:E] Stopped
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn] Stopped
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn](INFO): Running loop #4
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn] Running Loop=4
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:48:27.575Z,1513187307.575 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:48:33.081Z,1513187313.081 [NAL9602](INFO): Powering up
2017-12-13T17:48:43.885Z,1513187323.885 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:49:12.680Z,1513187352.680 [NAL9602](INFO): SBD MO Status=0, MOMSN=19291, MT Status=0, MTMSN=0
2017-12-13T17:49:12.681Z,1513187352.681 [NAL9602](INFO): No messages in MT queue
2017-12-13T17:49:17.930Z,1513187357.930 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:49:17.930Z,1513187357.930 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:49:17.930Z,1513187357.930 [BPC1](ERROR): Communications Fault
2017-12-13T17:49:18.037Z,1513187358.037 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:49:19.231Z,1513187359.231 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:49:19.231Z,1513187359.231 [BPC1] No Fault, FailCount= 1
2017-12-13T17:49:22.438Z,1513187362.438 [CommandLine](IMPORTANT): got command configSet CBIT.stopDepth 285.000000 meter persist
2017-12-13T17:49:22.507Z,1513187362.507 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:22.834Z,1513187362.834 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:23.251Z,1513187363.251 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:23.643Z,1513187363.643 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:24.057Z,1513187364.057 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:24.451Z,1513187364.451 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:24.890Z,1513187364.890 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:25.262Z,1513187365.262 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:25.715Z,1513187365.715 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:26.096Z,1513187366.096 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:26.502Z,1513187366.502 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:26.886Z,1513187366.886 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:27.262Z,1513187367.262 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:27.798Z,1513187367.798 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:28.162Z,1513187368.162 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:34.006Z,1513187374.006 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:34.214Z,1513187374.214 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:34.566Z,1513187374.566 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:34.978Z,1513187374.978 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:35.371Z,1513187375.371 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:35.758Z,1513187375.758 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:36.154Z,1513187376.154 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:36.652Z,1513187376.652 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:37.014Z,1513187377.014 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:37.442Z,1513187377.442 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:37.878Z,1513187377.878 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:38.226Z,1513187378.226 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:38.606Z,1513187378.606 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:39.078Z,1513187379.078 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:39.426Z,1513187379.426 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:39.846Z,1513187379.846 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:40.177Z,1513187380.177 [CBIT](FAULT): Stop depth set deeper than abort depth!
2017-12-13T17:49:40.239Z,1513187380.239 [CommandLine](IMPORTANT): got command configSet CBIT.abortDepth 300.000000 meter
2017-12-13T17:49:46.779Z,1513187386.779 [CommandLine](IMPORTANT): got command configSet CBIT.abortDepth 300.000000 meter persist
2017-12-13T17:50:18.884Z,1513187418.884 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:50:18.884Z,1513187418.884 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:50:18.884Z,1513187418.884 [BPC1](ERROR): Communications Fault
2017-12-13T17:50:19.051Z,1513187419.051 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:50:20.383Z,1513187420.383 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:50:20.383Z,1513187420.383 [BPC1] No Fault, FailCount= 1
2017-12-13T17:50:39.997Z,1513187439.997 [BPC1](ERROR): Failed to parse message.
2017-12-13T17:50:39.997Z,1513187439.997 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T17:50:39.997Z,1513187439.997 [BPC1] Data Fault, FailCount= 2
2017-12-13T17:50:39.998Z,1513187439.998 [BPC1](ERROR): Data Fault
2017-12-13T17:50:40.447Z,1513187440.447 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:50:42.450Z,1513187442.450 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:50:42.450Z,1513187442.450 [BPC1] No Fault, FailCount= 2
2017-12-13T17:50:54.992Z,1513187454.992 [CommandLine](IMPORTANT): got command configSet VerticalControl.massDefault 8.000000 millimeter persist
2017-12-13T17:51:39.692Z,1513187499.692 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 130.000000 cubic_centimeter persist
2017-12-13T17:51:40.711Z,1513187500.711 [BPC1](ERROR): Failed to parse message.
2017-12-13T17:51:40.712Z,1513187500.712 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T17:51:40.712Z,1513187500.712 [BPC1] Data Fault, FailCount= 1
2017-12-13T17:51:40.712Z,1513187500.712 [BPC1](ERROR): Data Fault
2017-12-13T17:51:40.897Z,1513187500.897 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:51:41.900Z,1513187501.900 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:51:41.900Z,1513187501.900 [BPC1] No Fault, FailCount= 1
2017-12-13T17:51:56.029Z,1513187516.029 [CommandLine](IMPORTANT): got command configSet list
2017-12-13T17:51:56.029Z,1513187516.029 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2017-12-13T17:51:56.030Z,1513187516.030 [CommandLine](IMPORTANT): CBIT.abortDepth=300 meter;

2017-12-13T17:51:56.030Z,1513187516.030 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2017-12-13T17:51:56.030Z,1513187516.030 [CommandLine](IMPORTANT): CBIT.stopDepth=285 meter;

2017-12-13T17:51:56.030Z,1513187516.030 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): RudderServo.offsetAngle=1 degree;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=130 cubic_centimeter;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): VerticalControl.massDefault=8 millimeter;

2017-12-13T17:51:56.031Z,1513187516.031 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd=41 millimeter;

2017-12-13T17:51:58.271Z,1513187518.271 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T17:51:58.271Z,1513187518.271 [BPC1] Data Fault, FailCount= 2
2017-12-13T17:51:58.271Z,1513187518.271 [BPC1](ERROR): Data Fault
2017-12-13T17:51:58.468Z,1513187518.468 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:51:59.620Z,1513187519.620 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:51:59.620Z,1513187519.620 [BPC1] No Fault, FailCount= 2
2017-12-13T17:52:24.840Z,1513187544.840 [RudderServo](ERROR): getPosition uart error serial timeout
2017-12-13T17:52:24.840Z,1513187544.840 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2017-12-13T17:52:24.840Z,1513187544.840 [RudderServo] Communications Fault, FailCount= 1
2017-12-13T17:52:24.840Z,1513187544.840 [RudderServo](ERROR): Communications Fault
2017-12-13T17:52:24.864Z,1513187544.864 [CBIT](ERROR): Communications Fault in component: RudderServo
2017-12-13T17:52:25.235Z,1513187545.235 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-12-13T17:52:25.235Z,1513187545.235 [RudderServo](INFO): Powering down
2017-12-13T17:52:25.939Z,1513187545.939 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:52:26.057Z,1513187546.057 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-13T17:52:26.061Z,1513187546.061 [CBIT](INFO): Clearing failed state for component RudderServo
2017-12-13T17:52:26.061Z,1513187546.061 [RudderServo] No Fault, FailCount= 1
2017-12-13T17:52:28.231Z,1513187548.231 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T17:52:28.231Z,1513187548.231 [BPC1] Data Fault, FailCount= 1
2017-12-13T17:52:28.231Z,1513187548.231 [BPC1](ERROR): Data Fault
2017-12-13T17:52:28.325Z,1513187548.325 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:52:35.205Z,1513187555.205 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:52:35.205Z,1513187555.205 [BPC1] No Fault, FailCount= 1
2017-12-13T17:52:35.708Z,1513187555.708 [RudderServo](ERROR): getPosition uart error serial timeout
2017-12-13T17:52:35.708Z,1513187555.708 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2017-12-13T17:52:35.708Z,1513187555.708 [RudderServo] Communications Fault, FailCount= 2
2017-12-13T17:52:35.708Z,1513187555.708 [RudderServo](ERROR): Communications Fault
2017-12-13T17:52:35.713Z,1513187555.713 [CBIT](ERROR): Communications Fault in component: RudderServo
2017-12-13T17:52:36.016Z,1513187556.016 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-12-13T17:52:36.016Z,1513187556.016 [RudderServo](INFO): Powering down
2017-12-13T17:52:36.767Z,1513187556.767 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-13T17:52:36.890Z,1513187556.890 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-13T17:52:36.908Z,1513187556.908 [CBIT](INFO): Clearing failed state for component RudderServo
2017-12-13T17:52:36.908Z,1513187556.908 [RudderServo] No Fault, FailCount= 2
2017-12-13T17:52:51.429Z,1513187571.429 [NAL9602](INFO): GPS fix at 20171213T175250: (36.802879, -121.788015)
2017-12-13T17:52:51.794Z,1513187571.794 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T17:52:51.794Z,1513187571.794 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T17:53:05.699Z,1513187585.699 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20171213T171603/Courier0016.lzma
2017-12-13T17:53:06.494Z,1513187586.494 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0016.lzma.bak
2017-12-13T17:53:06.494Z,1513187586.494 [DataOverHttps](INFO): SBD MOMSN=5435300
2017-12-13T17:53:22.301Z,1513187602.301 [DataOverHttps](INFO): Sending 806 bytes from file Logs/20171213T171603/Express0017.lzma
2017-12-13T17:53:23.091Z,1513187603.091 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0017.lzma.bak
2017-12-13T17:53:23.092Z,1513187603.092 [DataOverHttps](INFO): SBD MOMSN=5435303
2017-12-13T17:53:23.605Z,1513187603.605 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T17:53:23.605Z,1513187603.605 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T17:53:23.605Z,1513187603.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:53:28.177Z,1513187608.177 [NAL9602](INFO): Powering down
2017-12-13T17:53:31.217Z,1513187611.217 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:53:31.217Z,1513187611.217 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:53:31.217Z,1513187611.217 [BPC1](ERROR): Communications Fault
2017-12-13T17:53:31.356Z,1513187611.356 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:53:32.564Z,1513187612.564 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:53:32.564Z,1513187612.564 [BPC1] No Fault, FailCount= 1
2017-12-13T17:53:48.470Z,1513187628.470 [BPC1](ERROR): Failed to parse message.
2017-12-13T17:53:48.470Z,1513187628.470 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T17:53:48.470Z,1513187628.470 [BPC1] Data Fault, FailCount= 2
2017-12-13T17:53:48.470Z,1513187628.470 [BPC1](ERROR): Data Fault
2017-12-13T17:53:48.572Z,1513187628.572 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T17:53:49.801Z,1513187629.801 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:53:49.801Z,1513187629.801 [BPC1] No Fault, FailCount= 2
2017-12-13T17:54:29.637Z,1513187669.637 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:54:29.637Z,1513187669.637 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:54:29.637Z,1513187669.637 [BPC1](ERROR): Communications Fault
2017-12-13T17:54:29.796Z,1513187669.796 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:54:31.012Z,1513187671.012 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:54:31.012Z,1513187671.012 [BPC1] No Fault, FailCount= 1
2017-12-13T17:54:51.678Z,1513187691.678 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:54:51.678Z,1513187691.678 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:54:51.678Z,1513187691.678 [BPC1](ERROR): Communications Fault
2017-12-13T17:54:51.870Z,1513187691.870 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:54:52.988Z,1513187692.988 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:54:52.988Z,1513187692.988 [BPC1] No Fault, FailCount= 2
2017-12-13T17:55:34.128Z,1513187734.128 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:55:34.128Z,1513187734.128 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:55:34.128Z,1513187734.128 [BPC1](ERROR): Communications Fault
2017-12-13T17:55:34.256Z,1513187734.256 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:55:35.242Z,1513187735.242 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:55:35.243Z,1513187735.243 [BPC1] No Fault, FailCount= 1
2017-12-13T17:55:55.909Z,1513187755.909 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:55:55.909Z,1513187755.909 [BPC1] Communications Fault, FailCount= 2
2017-12-13T17:55:55.909Z,1513187755.909 [BPC1](ERROR): Communications Fault
2017-12-13T17:55:56.028Z,1513187756.028 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:55:57.237Z,1513187757.237 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:55:57.238Z,1513187757.238 [BPC1] No Fault, FailCount= 2
2017-12-13T17:56:15.020Z,1513187775.020 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T17:56:31.764Z,1513187791.764 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:56:31.764Z,1513187791.764 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:56:31.764Z,1513187791.764 [BPC1](ERROR): Communications Fault
2017-12-13T17:56:31.921Z,1513187791.921 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:56:32.965Z,1513187792.965 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:56:32.965Z,1513187792.965 [BPC1] No Fault, FailCount= 1
2017-12-13T17:57:30.473Z,1513187850.473 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:57:30.473Z,1513187850.473 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:57:30.473Z,1513187850.473 [BPC1](ERROR): Communications Fault
2017-12-13T17:57:30.624Z,1513187850.624 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:57:31.434Z,1513187851.434 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T175731
2017-12-13T17:57:31.616Z,1513187851.616 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:57:31.616Z,1513187851.616 [BPC1] No Fault, FailCount= 1
2017-12-13T17:57:36.347Z,1513187856.347 [DataOverHttps](INFO): Received command:load Science/sci2.xml;set sci2.NeedCommsTime 30 minute;set sci2.Lat1 36.76 degree;set sci2.Lon1 -122.012 degree;set sci2.YoYoMaxDepth 50 meter;set sci2.MaxDepth 65 meter;run 
2017-12-13T17:57:36.372Z,1513187856.372 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2017-12-13T17:57:36.372Z,1513187856.372 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2017-12-13T17:57:36.675Z,1513187856.675 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2017-12-13T17:57:36.677Z,1513187856.677 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2017-12-13T17:57:36.680Z,1513187856.680 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2017-12-13T17:57:36.683Z,1513187856.683 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2017-12-13T17:57:36.741Z,1513187856.741 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2017-12-13T17:57:36.744Z,1513187856.744 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2017-12-13T17:57:36.747Z,1513187856.747 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2017-12-13T17:57:36.765Z,1513187856.765 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2017-12-13T17:57:36.768Z,1513187856.768 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2017-12-13T17:57:36.771Z,1513187856.771 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2017-12-13T17:57:36.778Z,1513187856.778 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2017-12-13T17:57:36.780Z,1513187856.780 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2017-12-13T17:57:36.783Z,1513187856.783 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2017-12-13T17:57:36.823Z,1513187856.823 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2017-12-13T17:57:36.842Z,1513187856.842 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2017-12-13T17:57:36.845Z,1513187856.845 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2017-12-13T17:57:36.864Z,1513187856.864 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2017-12-13T17:57:36.867Z,1513187856.867 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2017-12-13T17:57:36.878Z,1513187856.878 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2017-12-13T17:57:36.895Z,1513187856.895 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2017-12-13T17:57:36.897Z,1513187856.897 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2017-12-13T17:57:36.917Z,1513187856.917 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2017-12-13T17:57:36.932Z,1513187856.932 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2017-12-13T17:57:36.935Z,1513187856.935 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 130.000000 cc
2017-12-13T17:57:36.939Z,1513187856.939 [MissionManager](INFO): DefineArg sci2.MassDefault = 0.800000 cm
2017-12-13T17:57:36.972Z,1513187856.972 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2017-12-13T17:57:36.975Z,1513187856.975 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2017-12-13T17:57:36.990Z,1513187856.990 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2017-12-13T17:57:36.991Z,1513187856.991 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2017-12-13T17:57:37.446Z,1513187857.446 [MissionManager](INFO): DefineArg sci2:Science.SampleISUS = 0 bool
2017-12-13T17:57:37.470Z,1513187857.470 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2017-12-13T17:57:37.485Z,1513187857.485 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSminPitch = -60.000000 arcdeg
2017-12-13T17:57:37.500Z,1513187857.500 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2017-12-13T17:57:37.503Z,1513187857.503 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2017-12-13T17:57:37.518Z,1513187857.518 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2017-12-13T17:57:37.524Z,1513187857.524 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2017-12-13T17:57:37.527Z,1513187857.527 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2017-12-13T17:57:37.542Z,1513187857.542 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2017-12-13T17:57:37.561Z,1513187857.561 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2017-12-13T17:57:37.576Z,1513187857.576 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2017-12-13T17:57:37.584Z,1513187857.584 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 1.000000 bool
2017-12-13T17:57:37.587Z,1513187857.587 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0.000000 bool
2017-12-13T17:57:37.603Z,1513187857.603 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 1.000000 bool
2017-12-13T17:57:37.654Z,1513187857.654 [MissionManager](INFO): DefineArg sci2:Science.EnabledTurbulence_NPS = 0.000000 bool
2017-12-13T17:57:37.658Z,1513187857.658 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 1.000000 bool
2017-12-13T17:57:37.681Z,1513187857.681 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2017-12-13T17:57:37.684Z,1513187857.684 [MissionManager](INFO): DefineArg sci2:Science.EnabledISUS = 0.000000 bool
2017-12-13T17:57:37.687Z,1513187857.687 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2017-12-13T17:57:37.708Z,1513187857.708 [MissionManager](INFO): DefineArg sci2:Science.PeakChlShallowBound = nan m
2017-12-13T17:57:37.724Z,1513187857.724 [MissionManager](INFO): DefineArg sci2:Science.PeakChlDeepBound = nan m
2017-12-13T17:57:37.727Z,1513187857.727 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2017-12-13T17:57:37.730Z,1513187857.730 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2017-12-13T17:57:37.750Z,1513187857.750 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2017-12-13T17:57:37.753Z,1513187857.753 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2017-12-13T17:57:37.779Z,1513187857.779 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2017-12-13T17:57:37.782Z,1513187857.782 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2017-12-13T17:57:37.801Z,1513187857.801 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2017-12-13T17:57:37.804Z,1513187857.804 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2017-12-13T17:57:37.807Z,1513187857.807 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2017-12-13T17:57:37.830Z,1513187857.830 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2017-12-13T17:57:37.833Z,1513187857.833 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2017-12-13T17:57:37.856Z,1513187857.856 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2017-12-13T17:57:37.861Z,1513187857.861 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2017-12-13T17:57:37.890Z,1513187857.890 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan arcdeg
2017-12-13T17:57:37.893Z,1513187857.893 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2017-12-13T17:57:37.916Z,1513187857.916 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2017-12-13T17:57:37.919Z,1513187857.919 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2017-12-13T17:57:37.922Z,1513187857.922 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2017-12-13T17:57:37.982Z,1513187857.982 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-12-13T17:57:38.035Z,1513187858.035 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2017-12-13T17:57:38.101Z,1513187858.101 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-12-13T17:57:38.130Z,1513187858.130 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-12-13T17:57:38.163Z,1513187858.163 [MissionManager](INFO): RedefineArg sci2:Science.SampleISUS = value:1 bool
2017-12-13T17:57:38.171Z,1513187858.171 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2017-12-13T17:57:38.171Z,1513187858.171 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2017-12-13T17:57:38.360Z,1513187858.360 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2017-12-13T17:57:38.362Z,1513187858.362 [MissionManager](INFO): DefineArg sci2:NeedComms.AcousticTimeout = 96.000000 h
2017-12-13T17:57:38.375Z,1513187858.375 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2017-12-13T17:57:38.378Z,1513187858.378 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2017-12-13T17:57:38.409Z,1513187858.409 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2017-12-13T17:57:38.412Z,1513187858.412 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2017-12-13T17:57:38.415Z,1513187858.415 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2017-12-13T17:57:38.435Z,1513187858.435 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2017-12-13T17:57:38.458Z,1513187858.458 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T17:57:38.523Z,1513187858.523 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2017-12-13T17:57:38.583Z,1513187858.583 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2017-12-13T17:57:38.606Z,1513187858.606 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2017-12-13T17:57:38.611Z,1513187858.611 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2017-12-13T17:57:38.628Z,1513187858.628 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2017-12-13T17:57:38.653Z,1513187858.653 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2017-12-13T17:57:38.692Z,1513187858.692 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2017-12-13T17:57:38.733Z,1513187858.733 [sci2:I.Pitch](DEBUG): Construct.
2017-12-13T17:57:38.756Z,1513187858.756 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2017-12-13T17:57:38.759Z,1513187858.759 [sci2:K.SetSpeed](DEBUG): Construct.
2017-12-13T17:57:38.766Z,1513187858.766 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2017-12-13T17:57:38.804Z,1513187858.804 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2017-12-13T17:57:38.833Z,1513187858.833 [sci2:N.YoYo](DEBUG): Construct YoYo.
2017-12-13T17:57:38.857Z,1513187858.857 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:38.899Z,1513187858.899 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:38.921Z,1513187858.921 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:39.008Z,1513187859.008 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:39.034Z,1513187859.034 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:39.072Z,1513187859.072 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:39.078Z,1513187859.078 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2017-12-13T17:57:39.176Z,1513187859.176 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance"
       xmlns:Trigger="Tethys/Trigger"
       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/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>

<!-- Almost every mission should start with an overall timeout and a NeedCommsTime. -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>60</Value></DefineArg>

<!-- You probably need to change these. -->

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through waypoints..
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

<!-- You probably do not need to change these. -->

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>9</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-20</Value></DefineArg>

<!-- You are even less likely to need to change these. -->

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position
    </Description><Control:VerticalControl.massDefault/></DefineArg>

<!--
    <DefineArg Name="CommsTimeout"><Description>
        Maximum time to try communicating before diving again.
    </Description><Units:minute/><Value>30</Value></DefineArg>
-->

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

<!-- Missions should almost always start with a timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

<!-- Most missions should use the NeedComms aggregate. -->

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

<!--    <Assign><Sequence/><Arg Name="NeedComms:CommsTimeout"/><Arg Name="CommsTimeout"/></Assign> -->

<!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

<!-- Check for additional instructions first. -->

    <Call Id="StartingMission" RefId="NeedComms"/>

<!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="Lap">

        <Sequence Repeat="Repeat"/>

        <Aggregate Id="Wpt1">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt2">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt3">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt4">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt5">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt6">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt7">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

    </Aggregate>

</Mission>


2017-12-13T17:57:39.177Z,1513187859.177 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2017-12-13T17:57:48.397Z,1513187868.397 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 30.000000 minute
2017-12-13T17:57:48.397Z,1513187868.397 [CommandLine](IMPORTANT): got command set sci2.Lat1 36.759998 degree
2017-12-13T17:57:48.398Z,1513187868.398 [CommandLine](IMPORTANT): got command set sci2.Lon1 -122.012001 degree
2017-12-13T17:57:48.399Z,1513187868.399 [CommandLine](IMPORTANT): got command set sci2.YoYoMaxDepth 50.000000 meter
2017-12-13T17:57:48.432Z,1513187868.432 [CommandLine](IMPORTANT): got command set sci2.MaxDepth 65.000000 meter
2017-12-13T17:57:48.433Z,1513187868.433 [CommandLine](IMPORTANT): got command run 
2017-12-13T17:57:48.435Z,1513187868.435 [CommandLine](IMPORTANT): Running 
2017-12-13T17:57:48.608Z,1513187868.608 [Default] Stopped
2017-12-13T17:57:48.608Z,1513187868.608 [Default](DEBUG): Aggregate::uninitialize Default
2017-12-13T17:57:48.608Z,1513187868.608 [Default:B.GoToSurface] Stopped
2017-12-13T17:57:48.608Z,1513187868.608 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T17:57:48.608Z,1513187868.608 [Default:CheckIn] Stopped
2017-12-13T17:57:48.608Z,1513187868.608 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T17:57:48.608Z,1513187868.608 [Default:CheckIn:C.Wait] Stopped
2017-12-13T17:57:48.609Z,1513187868.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:57:48.609Z,1513187868.609 [MissionManager](IMPORTANT): Started mission sci2
2017-12-13T17:57:48.609Z,1513187868.609 [sci2] Running Loop=1
2017-12-13T17:57:48.609Z,1513187868.609 [sci2](DEBUG): Aggregate::initialize sci2
2017-12-13T17:57:48.609Z,1513187868.609 [sci2:Science] Running Loop=1
2017-12-13T17:57:48.609Z,1513187868.609 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2017-12-13T17:57:48.609Z,1513187868.609 [sci2:Science:A] Running Loop=1
2017-12-13T17:57:48.609Z,1513187868.609 [sci2:Science:B] Running Loop=1
2017-12-13T17:57:48.609Z,1513187868.609 [sci2:Science:C] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:D] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:E] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:F] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:G] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:H] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:PeakDetectChl] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2017-12-13T17:57:48.610Z,1513187868.610 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-12-13T17:57:48.625Z,1513187868.625 [sci2:Science:HighestChlPeakReport] Running Loop=1
2017-12-13T17:57:48.625Z,1513187868.625 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2017-12-13T17:57:48.626Z,1513187868.626 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2017-12-13T17:57:48.626Z,1513187868.626 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2017-12-13T17:57:48.626Z,1513187868.626 [sci2:Science:PeakDetectNO3] Running Loop=1
2017-12-13T17:57:48.626Z,1513187868.626 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2017-12-13T17:57:48.626Z,1513187868.626 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2017-12-13T17:57:48.626Z,1513187868.626 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-12-13T17:57:48.653Z,1513187868.653 [sci2:Science:PeakDetectOil] Running Loop=1
2017-12-13T17:57:48.653Z,1513187868.653 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2017-12-13T17:57:48.653Z,1513187868.653 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2017-12-13T17:57:48.653Z,1513187868.653 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-12-13T17:57:48.654Z,1513187868.654 [sci2:StandardEnvelopes] Running Loop=1
2017-12-13T17:57:48.654Z,1513187868.654 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2017-12-13T17:57:48.654Z,1513187868.654 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:I.Pitch] Running Loop=1
2017-12-13T17:57:48.655Z,1513187868.655 [sci2:I.Pitch](DEBUG): Initialize.
2017-12-13T17:57:48.660Z,1513187868.660 [sci2:J.Buoyancy] Running Loop=1
2017-12-13T17:57:48.660Z,1513187868.660 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2017-12-13T17:57:48.660Z,1513187868.660 [sci2:K.SetSpeed] Running Loop=1
2017-12-13T17:57:48.660Z,1513187868.660 [sci2:K.SetSpeed](DEBUG): Initialize.
2017-12-13T17:57:48.660Z,1513187868.660 [sci2:L.DepthEnvelope] Running Loop=1
2017-12-13T17:57:48.660Z,1513187868.660 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2017-12-13T17:57:48.661Z,1513187868.661 [sci2:M.AltitudeEnvelope] Running Loop=1
2017-12-13T17:57:48.661Z,1513187868.661 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2017-12-13T17:57:48.661Z,1513187868.661 [sci2:N.YoYo] Running Loop=1
2017-12-13T17:57:48.661Z,1513187868.661 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2017-12-13T17:57:48.661Z,1513187868.661 [sci2:C] Running Loop=1
2017-12-13T17:57:48.662Z,1513187868.662 [sci2:N.YoYo] Running Loop=1
2017-12-13T17:57:48.683Z,1513187868.683 [sci2:M.AltitudeEnvelope] Running Loop=1
2017-12-13T17:57:48.721Z,1513187868.721 [sci2:L.DepthEnvelope] Running Loop=1
2017-12-13T17:57:48.753Z,1513187868.753 [sci2:K.SetSpeed] Running Loop=1
2017-12-13T17:57:48.774Z,1513187868.774 [sci2:J.Buoyancy] Running Loop=1
2017-12-13T17:57:48.794Z,1513187868.794 [sci2:I.Pitch] Running Loop=1
2017-12-13T17:57:48.807Z,1513187868.807 [sci2:StandardEnvelopes] Running Loop=1
2017-12-13T17:57:48.807Z,1513187868.807 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2017-12-13T17:57:48.878Z,1513187868.878 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2017-12-13T17:57:48.903Z,1513187868.903 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2017-12-13T17:57:48.941Z,1513187868.941 [sci2:C] Stopped
2017-12-13T17:57:48.941Z,1513187868.941 [sci2:E] Running Loop=1
2017-12-13T17:57:48.941Z,1513187868.941 [sci2:Science] Running Loop=1
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectOil] Stopped
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectNO3] Stopped
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2017-12-13T17:57:48.961Z,1513187868.961 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-12-13T17:57:48.962Z,1513187868.962 [sci2:Science:HighestChlPeakReport] Stopped
2017-12-13T17:57:48.962Z,1513187868.962 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2017-12-13T17:57:48.962Z,1513187868.962 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2017-12-13T17:57:48.962Z,1513187868.962 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2017-12-13T17:57:48.962Z,1513187868.962 [sci2:Science:PeakDetectChl] Running Loop=1
2017-12-13T17:57:48.966Z,1513187868.966 [sci2:Science:H] Running Loop=1
2017-12-13T17:57:48.967Z,1513187868.967 [sci2:Science:H](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2017-12-13T17:57:48.992Z,1513187868.992 [sci2:Science:G] Running Loop=1
2017-12-13T17:57:48.993Z,1513187868.993 [sci2:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2017-12-13T17:57:48.994Z,1513187868.994 [sci2:Science:F] Stopped
2017-12-13T17:57:48.994Z,1513187868.994 [sci2:Science:E] Stopped
2017-12-13T17:57:48.994Z,1513187868.994 [sci2:Science:D] Running Loop=1
2017-12-13T17:57:48.995Z,1513187868.995 [sci2:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2017-12-13T17:57:48.996Z,1513187868.996 [sci2:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2017-12-13T17:57:48.997Z,1513187868.997 [sci2:Science:C] Running Loop=1
2017-12-13T17:57:48.998Z,1513187868.998 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2017-12-13T17:57:48.999Z,1513187868.999 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2017-12-13T17:57:49.032Z,1513187869.032 [sci2:Science:B] Stopped
2017-12-13T17:57:49.032Z,1513187869.032 [sci2:Science:A] Running Loop=1
2017-12-13T17:57:49.033Z,1513187869.033 [sci2:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2017-12-13T17:57:49.081Z,1513187869.081 [CTD_Seabird](INFO): Initializing
2017-12-13T17:57:49.081Z,1513187869.081 [CTD_Seabird](INFO): Checking LCM
2017-12-13T17:57:49.085Z,1513187869.085 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2017-12-13T17:57:49.085Z,1513187869.085 [WetLabsBB2FL](INFO): Powering up
2017-12-13T17:57:49.427Z,1513187869.427 [sci2:E] Stopped
2017-12-13T17:57:49.427Z,1513187869.427 [sci2:F] Running Loop=1
2017-12-13T17:57:49.925Z,1513187869.925 [sci2:F] Stopped
2017-12-13T17:57:49.925Z,1513187869.925 [sci2:G] Running Loop=1
2017-12-13T17:57:50.092Z,1513187870.092 [CTD_Seabird](INFO): LCM OK
2017-12-13T17:57:50.092Z,1513187870.092 [CTD_Seabird](INFO): Powering up
2017-12-13T17:57:50.344Z,1513187870.344 [sci2:G] Stopped
2017-12-13T17:57:50.344Z,1513187870.344 [sci2:StartingMission] Running Loop=1
2017-12-13T17:57:50.345Z,1513187870.345 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2017-12-13T17:57:50.870Z,1513187870.870 [sci2:NeedComms] Running Loop=1
2017-12-13T17:57:50.870Z,1513187870.870 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2017-12-13T17:57:50.870Z,1513187870.870 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2017-12-13T17:57:50.870Z,1513187870.870 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T17:57:50.870Z,1513187870.870 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2017-12-13T17:57:50.871Z,1513187870.871 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2017-12-13T17:57:50.871Z,1513187870.871 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2017-12-13T17:57:50.871Z,1513187870.871 [sci2:NeedComms:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T17:57:50.884Z,1513187870.884 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T17:57:50.884Z,1513187870.884 [sci2:NeedComms:A] Running Loop=1
2017-12-13T17:57:50.894Z,1513187870.894 [sci2:NeedComms:A](INFO): last time_fix was: 1513187570.000000 second since 1970/01/01T00:00:00Z 
2017-12-13T17:57:50.912Z,1513187870.912 [sci2:NeedComms:A] Stopped
2017-12-13T17:57:51.601Z,1513187871.601 [sci2:NeedComms:C] Running Loop=1
2017-12-13T17:57:52.286Z,1513187872.286 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-12-13T17:57:52.538Z,1513187872.538 [NAL9602](INFO): Powering up
2017-12-13T17:57:54.708Z,1513187874.708 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T17:57:54.955Z,1513187874.955 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2017-12-13T17:57:55.211Z,1513187875.211 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2017-12-13T17:57:57.083Z,1513187877.083 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	14		254.570		77.504		13.088		32.976		33.147		42.412		9.265		467.3		492.0		306.4	



2017-12-13T17:57:57.083Z,1513187877.083 [Aanderaa_O2](DEBUG): Error parsing device response
2017-12-13T17:57:58.300Z,1513187878.300 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	14		254.806		77.575		13.087		32.965		33.136		42.399		9.262		467.2		491.4		306.4	


2017-12-13T17:57:58.300Z,1513187878.300 [Aanderaa_O2](DEBUG): Error parsing device response
2017-12-13T17:57:59.101Z,1513187879.101 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	14		254.647		77.526		13.087		32.973		33.144		42.405		9.261		467.1		490.9		306.4	



2017-12-13T17:57:59.101Z,1513187879.101 [Aanderaa_O2](DEBUG): Error parsing device response
2017-12-13T17:58:00.288Z,1513187880.288 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	14		254.651		77.527		13.087		32.973		33.144		42.406		9.262		467.0		490.6		306.4	

2017-12-13T17:58:00.288Z,1513187880.288 [Aanderaa_O2](DEBUG): Error parsing device response
2017-12-13T17:58:03.451Z,1513187883.451 [NAL9602](INFO): NAL9602 initialized
2017-12-13T17:58:05.923Z,1513187885.923 [CommandLine](IMPORTANT): got command show variable threshold
2017-12-13T17:58:06.112Z,1513187886.112 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2017-12-13T17:58:06.455Z,1513187886.455 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent)
2017-12-13T17:58:06.473Z,1513187886.473 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch)
2017-12-13T17:58:06.474Z,1513187886.474 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit)
2017-12-13T17:58:06.488Z,1513187886.488 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius)
2017-12-13T17:58:06.489Z,1513187886.489 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2017-12-13T17:58:06.490Z,1513187886.490 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2017-12-13T17:58:06.490Z,1513187886.490 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2017-12-13T17:58:06.491Z,1513187886.491 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2017-12-13T17:58:06.491Z,1513187886.491 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2017-12-13T17:58:06.504Z,1513187886.504 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2017-12-13T17:58:06.504Z,1513187886.504 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2017-12-13T17:58:06.505Z,1513187886.505 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2017-12-13T17:58:06.507Z,1513187886.507 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour)
2017-12-13T17:58:06.507Z,1513187886.507 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt)
2017-12-13T17:58:06.536Z,1513187886.536 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold (celsius)
2017-12-13T17:58:06.538Z,1513187886.538 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius)
2017-12-13T17:58:06.538Z,1513187886.538 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count)
2017-12-13T17:58:10.795Z,1513187890.795 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005751 psu
2017-12-13T17:58:11.587Z,1513187891.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005751 psu
2017-12-13T17:58:12.790Z,1513187892.790 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005752 psu
2017-12-13T17:58:13.998Z,1513187893.998 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005753 psu
2017-12-13T17:58:14.591Z,1513187894.591 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005754 psu
2017-12-13T17:58:15.801Z,1513187895.801 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005754 psu
2017-12-13T17:58:16.591Z,1513187896.591 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005755 psu
2017-12-13T17:58:17.797Z,1513187897.797 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005754 psu
2017-12-13T17:58:19.022Z,1513187899.022 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005755 psu
2017-12-13T17:58:19.593Z,1513187899.593 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005757 psu
2017-12-13T17:58:20.813Z,1513187900.813 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005758 psu
2017-12-13T17:58:21.587Z,1513187901.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005759 psu
2017-12-13T17:58:22.790Z,1513187902.790 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005760 psu
2017-12-13T17:58:23.993Z,1513187903.993 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005760 psu
2017-12-13T17:58:24.587Z,1513187904.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005741 psu
2017-12-13T17:58:25.821Z,1513187905.821 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005760 psu
2017-12-13T17:58:26.601Z,1513187906.601 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005760 psu
2017-12-13T17:58:27.805Z,1513187907.805 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005761 psu
2017-12-13T17:58:28.595Z,1513187908.595 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005761 psu
2017-12-13T17:58:29.807Z,1513187909.807 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005743 psu
2017-12-13T17:58:30.588Z,1513187910.588 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005763 psu
2017-12-13T17:58:31.791Z,1513187911.791 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005762 psu
2017-12-13T17:58:32.589Z,1513187912.589 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005744 psu
2017-12-13T17:58:33.805Z,1513187913.805 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005764 psu
2017-12-13T17:58:34.592Z,1513187914.592 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005765 psu
2017-12-13T17:58:35.809Z,1513187915.809 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005765 psu
2017-12-13T17:58:36.587Z,1513187916.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005763 psu
2017-12-13T17:58:37.822Z,1513187917.822 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005765 psu
2017-12-13T17:58:38.617Z,1513187918.617 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005765 psu
2017-12-13T17:58:39.818Z,1513187919.818 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005730 psu
2017-12-13T17:58:40.590Z,1513187920.590 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005748 psu
2017-12-13T17:58:41.794Z,1513187921.794 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005766 psu
2017-12-13T17:58:42.998Z,1513187922.998 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005731 psu
2017-12-13T17:58:43.591Z,1513187923.591 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005747 psu
2017-12-13T17:58:44.807Z,1513187924.807 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005731 psu
2017-12-13T17:58:45.600Z,1513187925.600 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005746 psu
2017-12-13T17:58:46.518Z,1513187926.518 [CommandLine](IMPORTANT): got command get IBIT.batteryVoltageThreshold
2017-12-13T17:58:46.519Z,1513187926.519 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold 13.500000 V
2017-12-13T17:58:46.801Z,1513187926.801 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005788 psu
2017-12-13T17:58:47.587Z,1513187927.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005746 psu
2017-12-13T17:58:48.802Z,1513187928.802 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005765 psu
2017-12-13T17:58:49.587Z,1513187929.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005747 psu
2017-12-13T17:58:50.817Z,1513187930.817 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005720 psu
2017-12-13T17:58:51.595Z,1513187931.595 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005730 psu
2017-12-13T17:58:52.821Z,1513187932.821 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005744 psu
2017-12-13T17:58:53.587Z,1513187933.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005744 psu
2017-12-13T17:58:54.793Z,1513187934.793 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005763 psu
2017-12-13T17:58:56.002Z,1513187936.002 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005726 psu
2017-12-13T17:58:56.587Z,1513187936.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005807 psu
2017-12-13T17:58:57.803Z,1513187937.803 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005739 psu
2017-12-13T17:58:58.600Z,1513187938.600 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005739 psu
2017-12-13T17:58:59.817Z,1513187939.817 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005738 psu
2017-12-13T17:59:00.586Z,1513187940.586 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005739 psu
2017-12-13T17:59:01.805Z,1513187941.805 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005781 psu
2017-12-13T17:59:02.587Z,1513187942.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005781 psu
2017-12-13T17:59:03.799Z,1513187943.799 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005758 psu
2017-12-13T17:59:04.590Z,1513187944.590 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005738 psu
2017-12-13T17:59:05.793Z,1513187945.793 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005758 psu
2017-12-13T17:59:06.597Z,1513187946.597 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005757 psu
2017-12-13T17:59:07.797Z,1513187947.797 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005757 psu
2017-12-13T17:59:08.596Z,1513187948.596 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005756 psu
2017-12-13T17:59:09.821Z,1513187949.821 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005736 psu
2017-12-13T17:59:10.590Z,1513187950.590 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005755 psu
2017-12-13T17:59:11.809Z,1513187951.809 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005755 psu
2017-12-13T17:59:12.589Z,1513187952.589 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005754 psu
2017-12-13T17:59:13.794Z,1513187953.794 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005734 psu
2017-12-13T17:59:14.587Z,1513187954.587 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005734 psu
2017-12-13T17:59:15.805Z,1513187955.805 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005753 psu
2017-12-13T17:59:16.346Z,1513187956.346 [NAL9602](INFO): SBD MO Status=2, MOMSN=19292, MT Status=2, MTMSN=0
2017-12-13T17:59:16.347Z,1513187956.347 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T17:59:16.588Z,1513187956.588 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005753 psu
2017-12-13T17:59:17.800Z,1513187957.800 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005753 psu
2017-12-13T17:59:19.006Z,1513187959.006 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005734 psu
2017-12-13T17:59:19.592Z,1513187959.592 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005753 psu
2017-12-13T17:59:20.805Z,1513187960.805 [CTD_Seabird](ERROR): Salinity reading out of range: 0.005754 psu
2017-12-13T17:59:21.874Z,1513187961.874 [CommandLine](IMPORTANT): got command stop
2017-12-13T17:59:21.875Z,1513187961.875 [CommandLine](IMPORTANT): Scheduling is paused
2017-12-13T17:59:21.875Z,1513187961.875 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2017-12-13T17:59:21.974Z,1513187961.974 [MissionManager](INFO): MissionManager is completed.
2017-12-13T17:59:21.974Z,1513187961.974 [MissionManager](INFO): Uninitializing Mission sci2
2017-12-13T17:59:21.975Z,1513187961.975 [sci2] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2](DEBUG): Aggregate::uninitialize sci2
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:A] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:C] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:D] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:G] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:H] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:PeakDetectChl] Stopped
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2017-12-13T17:59:21.975Z,1513187961.975 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:NeedComms] Stopped
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:NeedComms:B.GoToSurface] Stopped
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:NeedComms:C] Stopped
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:StandardEnvelopes] Stopped
2017-12-13T17:59:21.996Z,1513187961.996 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StartingMission] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:I.Pitch] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:J.Buoyancy] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:K.SetSpeed] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:L.DepthEnvelope] Stopped
2017-12-13T17:59:21.997Z,1513187961.997 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2017-12-13T17:59:21.998Z,1513187961.998 [sci2:M.AltitudeEnvelope] Stopped
2017-12-13T17:59:21.998Z,1513187961.998 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2017-12-13T17:59:21.998Z,1513187961.998 [sci2:N.YoYo] Stopped
2017-12-13T17:59:21.998Z,1513187961.998 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2017-12-13T17:59:22.128Z,1513187962.128 [WetLabsBB2FL](INFO): Powering down
2017-12-13T17:59:22.881Z,1513187962.881 [MissionManager](IMPORTANT): Started mission Default
2017-12-13T17:59:22.881Z,1513187962.881 [Default] Running Loop=1
2017-12-13T17:59:22.881Z,1513187962.881 [Default](DEBUG): Aggregate::initialize Default
2017-12-13T17:59:22.881Z,1513187962.881 [Default:B.GoToSurface] Running Loop=1
2017-12-13T17:59:22.882Z,1513187962.882 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T17:59:22.882Z,1513187962.882 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T17:59:22.882Z,1513187962.882 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T17:59:22.882Z,1513187962.882 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T17:59:22.883Z,1513187962.883 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T17:59:22.883Z,1513187962.883 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T17:59:22.904Z,1513187962.904 [Default:A.Wait] Running Loop=1
2017-12-13T17:59:22.904Z,1513187962.904 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-12-13T17:59:22.989Z,1513187962.989 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T17:59:22.989Z,1513187962.989 [CTD_Seabird](INFO): Powering down
2017-12-13T17:59:23.243Z,1513187963.243 [Aanderaa_O2](INFO): Powering down
2017-12-13T17:59:35.850Z,1513187975.850 [CommandLine](IMPORTANT): got command get IBIT.batteryCapacityThreshold
2017-12-13T17:59:35.851Z,1513187975.851 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold 5.000000 Ah
2017-12-13T17:59:36.949Z,1513187976.949 [Default:A.Wait](INFO): Done Waiting.
2017-12-13T17:59:36.949Z,1513187976.949 [Default:A.Wait] Stopped
2017-12-13T17:59:36.949Z,1513187976.949 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T17:59:37.109Z,1513187977.109 [Default:CheckIn] Running Loop=1
2017-12-13T17:59:37.109Z,1513187977.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T17:59:37.109Z,1513187977.109 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T17:59:37.769Z,1513187977.769 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T175937
2017-12-13T17:59:37.846Z,1513187977.846 [BPC1](ERROR): Failed to receive battery data
2017-12-13T17:59:37.846Z,1513187977.846 [BPC1] Communications Fault, FailCount= 1
2017-12-13T17:59:37.846Z,1513187977.846 [BPC1](ERROR): Communications Fault
2017-12-13T17:59:37.950Z,1513187977.950 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T17:59:46.205Z,1513187986.205 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T17:59:46.205Z,1513187986.205 [BPC1] No Fault, FailCount= 1
2017-12-13T17:59:46.231Z,1513187986.231 [DataOverHttps](INFO): Received command:stop
2017-12-13T17:59:46.327Z,1513187986.327 [CommandLine](IMPORTANT): got command stop
2017-12-13T17:59:54.043Z,1513187994.043 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-12-13T18:00:03.641Z,1513188003.641 [PNI_TCM](ERROR): CRC does not match. Expected:0x45773 got:0x34329
2017-12-13T18:00:18.567Z,1513188018.567 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:00:18.567Z,1513188018.567 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:00:18.567Z,1513188018.567 [BPC1](ERROR): Communications Fault
2017-12-13T18:00:18.781Z,1513188018.781 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:00:19.896Z,1513188019.896 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:00:19.896Z,1513188019.896 [BPC1] No Fault, FailCount= 1
2017-12-13T18:00:35.426Z,1513188035.426 [NAL9602](INFO): SBD MO Status=0, MOMSN=19292, MT Status=0, MTMSN=0
2017-12-13T18:00:35.426Z,1513188035.426 [NAL9602](INFO): No messages in MT queue
2017-12-13T18:00:36.629Z,1513188036.629 [NAL9602](INFO): GPS fix at 20171213T180035: (36.802895, -121.788032)
2017-12-13T18:00:36.704Z,1513188036.704 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T18:00:36.704Z,1513188036.704 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T18:00:41.443Z,1513188041.443 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20171213T171603/Courier0020.lzma
2017-12-13T18:00:42.241Z,1513188042.241 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0020.lzma.bak
2017-12-13T18:00:42.242Z,1513188042.242 [DataOverHttps](INFO): SBD MOMSN=5435381
2017-12-13T18:00:59.975Z,1513188059.975 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T171603/Courier0024.lzma
2017-12-13T18:01:00.769Z,1513188060.769 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0024.lzma.bak
2017-12-13T18:01:00.769Z,1513188060.769 [DataOverHttps](INFO): SBD MOMSN=5435384
2017-12-13T18:01:01.246Z,1513188061.246 [PNI_TCM](ERROR): CRC does not match. Expected:0x52770 got:0x7360
2017-12-13T18:01:14.410Z,1513188074.410 [NAL9602](INFO): Powering down
2017-12-13T18:01:14.712Z,1513188074.712 [DataOverHttps](INFO): Sending 917 bytes from file Logs/20171213T171603/Express0021.lzma
2017-12-13T18:01:15.470Z,1513188075.470 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0021.lzma.bak
2017-12-13T18:01:15.470Z,1513188075.470 [DataOverHttps](INFO): SBD MOMSN=5435386
2017-12-13T18:01:24.176Z,1513188084.176 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:01:24.176Z,1513188084.176 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:01:24.176Z,1513188084.176 [BPC1](ERROR): Communications Fault
2017-12-13T18:01:24.307Z,1513188084.307 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:01:25.257Z,1513188085.257 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:01:25.258Z,1513188085.258 [BPC1] No Fault, FailCount= 1
2017-12-13T18:01:27.108Z,1513188087.108 [CommandLine](IMPORTANT): got command get platform_battery_voltage
2017-12-13T18:01:27.108Z,1513188087.108 [CommandLine](FAULT): Element has no value
2017-12-13T18:01:33.439Z,1513188093.439 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T171603/Express0025.lzma
2017-12-13T18:01:34.250Z,1513188094.250 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Express0025.lzma.bak
2017-12-13T18:01:34.250Z,1513188094.250 [DataOverHttps](INFO): SBD MOMSN=5435427
2017-12-13T18:01:34.779Z,1513188094.779 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T18:01:34.779Z,1513188094.779 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T18:01:34.779Z,1513188094.779 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T18:01:46.361Z,1513188106.361 [CommandLine](IMPORTANT): got command get platform_battery_voltage
2017-12-13T18:01:46.361Z,1513188106.361 [CommandLine](FAULT): Element has no value
2017-12-13T18:02:06.917Z,1513188126.917 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:02:06.918Z,1513188126.918 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:02:06.918Z,1513188126.918 [BPC1](ERROR): Communications Fault
2017-12-13T18:02:07.106Z,1513188127.106 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:02:08.170Z,1513188128.170 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:02:08.170Z,1513188128.170 [BPC1] No Fault, FailCount= 1
2017-12-13T18:02:08.586Z,1513188128.586 [CommandLine](IMPORTANT): got command failComponent
2017-12-13T18:02:08.586Z,1513188128.586 [CommandLine](IMPORTANT): Failed components:
2017-12-13T18:02:08.586Z,1513188128.586 [CommandLine](IMPORTANT): No failed Components.
2017-12-13T18:02:22.281Z,1513188142.281 [CommandLine](IMPORTANT): got command get platform_battery_voltage
2017-12-13T18:02:22.282Z,1513188142.282 [CommandLine](IMPORTANT): platform_battery_voltage 16.181499 _
2017-12-13T18:03:07.487Z,1513188187.487 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:03:07.487Z,1513188187.487 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:03:07.487Z,1513188187.487 [BPC1](ERROR): Communications Fault
2017-12-13T18:03:07.619Z,1513188187.619 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:03:08.772Z,1513188188.772 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:03:08.772Z,1513188188.772 [BPC1] No Fault, FailCount= 1
2017-12-13T18:03:29.477Z,1513188209.477 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:03:29.477Z,1513188209.477 [BPC1] Communications Fault, FailCount= 2
2017-12-13T18:03:29.477Z,1513188209.477 [BPC1](ERROR): Communications Fault
2017-12-13T18:03:29.565Z,1513188209.565 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:03:30.737Z,1513188210.737 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:03:30.737Z,1513188210.737 [BPC1] No Fault, FailCount= 2
2017-12-13T18:04:04.053Z,1513188244.053 [CommandLine](IMPORTANT): got command ibit
2017-12-13T18:04:04.162Z,1513188244.162 [IBIT](IMPORTANT): Beginning Initiated BIT
2017-12-13T18:04:04.162Z,1513188244.162 [IBIT](IMPORTANT): Beginning control surface checks.
2017-12-13T18:04:04.173Z,1513188244.173 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T18:04:04.425Z,1513188244.425 [NAL9602](INFO): Powering up
2017-12-13T18:04:10.057Z,1513188250.057 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:04:10.057Z,1513188250.057 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:04:10.057Z,1513188250.057 [BPC1](ERROR): Communications Fault
2017-12-13T18:04:10.185Z,1513188250.185 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:04:11.368Z,1513188251.368 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:04:11.368Z,1513188251.368 [BPC1] No Fault, FailCount= 1
2017-12-13T18:04:14.991Z,1513188254.991 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006120
CHAN A1 (24V): -0.016307
CHAN A2 (12V): -0.004955
CHAN A3 (5V): -0.002855
CHAN B0 (3.3V): -0.001313
CHAN B1 (3.15aV): -0.001097
CHAN B2 (3.15bV): -0.001688
CHAN B3 (GND): -0.000082
OPEN: 0.005108
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T18:04:15.229Z,1513188255.229 [NAL9602](INFO): NAL9602 initialized
2017-12-13T18:04:19.362Z,1513188259.362 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.750024
2017-12-13T18:04:19.362Z,1513188259.362 [IBIT](FAULT): Control surface position failure.
2017-12-13T18:04:44.238Z,1513188284.238 [NAL9602](INFO): SBD MO Status=0, MOMSN=19293, MT Status=0, MTMSN=0
2017-12-13T18:04:44.238Z,1513188284.238 [NAL9602](INFO): No messages in MT queue
2017-12-13T18:05:06.742Z,1513188306.742 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:05:06.742Z,1513188306.742 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:05:06.742Z,1513188306.742 [BPC1](ERROR): Communications Fault
2017-12-13T18:05:07.024Z,1513188307.024 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:05:08.316Z,1513188308.316 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:05:08.316Z,1513188308.316 [BPC1] No Fault, FailCount= 1
2017-12-13T18:05:44.828Z,1513188344.828 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:05:44.828Z,1513188344.828 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:05:44.828Z,1513188344.828 [BPC1](ERROR): Communications Fault
2017-12-13T18:05:45.064Z,1513188345.064 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:05:46.156Z,1513188346.156 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:05:46.156Z,1513188346.156 [BPC1] No Fault, FailCount= 1
2017-12-13T18:05:55.073Z,1513188355.073 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2017-12-13T18:05:55.434Z,1513188355.434 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 345.016022
Voltage: 16.181499
2017-12-13T18:05:55.434Z,1513188355.434 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2017-12-13T18:05:55.434Z,1513188355.434 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2017-12-13T18:05:55.790Z,1513188355.790 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2017-12-13T18:05:55.790Z,1513188355.790 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2017-12-13T18:05:55.790Z,1513188355.790 [IBIT](IMPORTANT): Pressure:8.523625 PSI
2017-12-13T18:05:55.791Z,1513188355.791 [IBIT](IMPORTANT): Humidity:0.366256 %
2017-12-13T18:05:56.222Z,1513188356.222 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2017-12-13T18:05:56.222Z,1513188356.222 [IBIT](IMPORTANT): buoyancyNeutral: 130.000000 cc
2017-12-13T18:05:56.222Z,1513188356.222 [IBIT](IMPORTANT): massDefault: 0.800000 cm
2017-12-13T18:05:56.223Z,1513188356.223 [IBIT](IMPORTANT): stopDepth: 285.000000 m
2017-12-13T18:05:56.223Z,1513188356.223 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2017-12-13T18:05:56.223Z,1513188356.223 [IBIT](IMPORTANT): IBIT FAILED
2017-12-13T18:06:02.041Z,1513188362.041 [PNI_TCM](ERROR): CRC does not match. Expected:0x55564 got:0x16790
2017-12-13T18:06:05.257Z,1513188365.257 [PNI_TCM](ERROR): CRC does not match. Expected:0x25900 got:0x1347
2017-12-13T18:06:11.663Z,1513188371.663 [PNI_TCM](ERROR): CRC does not match. Expected:0x31139 got:0x48901
2017-12-13T18:06:15.024Z,1513188375.024 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T18:06:18.040Z,1513188378.040 [PNI_TCM](ERROR): CRC does not match. Expected:0x30927 got:0x65336
2017-12-13T18:06:28.012Z,1513188388.012 [NAL9602](INFO): Powering down
2017-12-13T18:06:35.307Z,1513188395.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T18:06:35.308Z,1513188395.308 [Default:CheckIn:C.Wait] Stopped
2017-12-13T18:06:35.308Z,1513188395.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T18:06:35.308Z,1513188395.308 [Default:CheckIn:D] Running Loop=1
2017-12-13T18:06:35.660Z,1513188395.660 [Default:CheckIn:D] Stopped
2017-12-13T18:06:35.660Z,1513188395.660 [Default:CheckIn:E] Running Loop=1
2017-12-13T18:06:36.067Z,1513188396.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.212976 min 
2017-12-13T18:06:36.067Z,1513188396.067 [Default:CheckIn:E] Stopped
2017-12-13T18:06:36.067Z,1513188396.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T18:06:36.067Z,1513188396.067 [Default:CheckIn] Stopped
2017-12-13T18:06:36.069Z,1513188396.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T18:06:36.069Z,1513188396.069 [Default:CheckIn](INFO): Running loop #2
2017-12-13T18:06:36.069Z,1513188396.069 [Default:CheckIn] Running Loop=2
2017-12-13T18:06:36.069Z,1513188396.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T18:06:36.069Z,1513188396.069 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T18:06:36.507Z,1513188396.507 [CommandLine](IMPORTANT): got command get RudderServo.offsetAngle
2017-12-13T18:06:36.507Z,1513188396.507 [CommandLine](IMPORTANT): RudderServo.offsetAngle 1.000000 arcdeg
2017-12-13T18:06:41.021Z,1513188401.021 [NAL9602](INFO): Powering up
2017-12-13T18:06:51.840Z,1513188411.840 [NAL9602](INFO): NAL9602 initialized
2017-12-13T18:07:01.905Z,1513188421.905 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:07:01.905Z,1513188421.905 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:07:01.905Z,1513188421.905 [BPC1](ERROR): Communications Fault
2017-12-13T18:07:02.096Z,1513188422.096 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:07:03.480Z,1513188423.480 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:07:03.480Z,1513188423.480 [BPC1] No Fault, FailCount= 1
2017-12-13T18:07:31.679Z,1513188451.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=19294, MT Status=0, MTMSN=0
2017-12-13T18:07:31.679Z,1513188451.679 [NAL9602](INFO): No messages in MT queue
2017-12-13T18:07:36.097Z,1513188456.097 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:07:36.097Z,1513188456.097 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:07:36.097Z,1513188456.097 [BPC1](ERROR): Communications Fault
2017-12-13T18:07:36.192Z,1513188456.192 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:07:37.430Z,1513188457.430 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:07:37.430Z,1513188457.430 [BPC1] No Fault, FailCount= 1
2017-12-13T18:07:50.573Z,1513188470.573 [CommandLine](IMPORTANT): got command configSet RudderServo.offsetAngle 0.500000 degree persist
2017-12-13T18:07:55.978Z,1513188475.978 [CommandLine](IMPORTANT): got command ibit
2017-12-13T18:07:56.415Z,1513188476.415 [IBIT](IMPORTANT): Beginning Initiated BIT
2017-12-13T18:07:56.415Z,1513188476.415 [IBIT](IMPORTANT): Beginning control surface checks.
2017-12-13T18:07:56.418Z,1513188476.418 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T18:08:07.311Z,1513188487.311 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.122150
CHAN A1 (24V): -0.015897
CHAN A2 (12V): -0.005282
CHAN A3 (5V): -0.002925
CHAN B0 (3.3V): -0.001332
CHAN B1 (3.15aV): -0.000452
CHAN B2 (3.15bV): -0.001159
CHAN B3 (GND): 0.000158
OPEN: 0.004959
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T18:08:11.578Z,1513188491.578 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.752955
2017-12-13T18:08:11.578Z,1513188491.578 [IBIT](FAULT): Control surface position failure.
2017-12-13T18:08:12.673Z,1513188492.673 [BPC1](ERROR): Failed to parse message.
2017-12-13T18:08:12.674Z,1513188492.674 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T18:08:12.674Z,1513188492.674 [BPC1] Data Fault, FailCount= 1
2017-12-13T18:08:12.674Z,1513188492.674 [BPC1](ERROR): Data Fault
2017-12-13T18:08:12.798Z,1513188492.798 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T18:08:13.980Z,1513188493.980 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:08:13.980Z,1513188493.980 [BPC1] No Fault, FailCount= 1
2017-12-13T18:08:55.973Z,1513188535.973 [BPC1](ERROR): Failed to receive battery data
2017-12-13T18:08:55.973Z,1513188535.973 [BPC1] Communications Fault, FailCount= 1
2017-12-13T18:08:55.973Z,1513188535.973 [BPC1](ERROR): Communications Fault
2017-12-13T18:08:56.228Z,1513188536.228 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T18:08:57.272Z,1513188537.272 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T18:08:57.272Z,1513188537.272 [BPC1] No Fault, FailCount= 1
2017-12-13T18:09:01.501Z,1513188541.501 [NAL9602](INFO): GPS fix at 20171213T180901: (36.802996, -121.787943)
2017-12-13T18:09:01.702Z,1513188541.702 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802998 Longitude: -121.787941
2017-12-13T18:09:02.082Z,1513188542.082 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2017-12-13T18:09:02.082Z,1513188542.082 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2017-12-13T18:09:02.082Z,1513188542.082 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2017-12-13T18:09:02.446Z,1513188542.446 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2017-12-13T18:09:02.446Z,1513188542.446 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2017-12-13T18:09:02.447Z,1513188542.447 [IBIT](IMPORTANT): Pressure:8.542056 PSI
2017-12-13T18:09:02.447Z,1513188542.447 [IBIT](IMPORTANT): Humidity:0.470028 %
2017-12-13T18:09:02.869Z,1513188542.869 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2017-12-13T18:09:02.870Z,1513188542.870 [IBIT](IMPORTANT): buoyancyNeutral: 130.000000 cc
2017-12-13T18:09:02.870Z,1513188542.870 [IBIT](IMPORTANT): massDefault: 0.800000 cm
2017-12-13T18:09:02.870Z,1513188542.870 [IBIT](IMPORTANT): stopDepth: 285.000000 m
2017-12-13T18:09:02.871Z,1513188542.871 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2017-12-13T18:09:02.871Z,1513188542.871 [IBIT](IMPORTANT): IBIT FAILED
2017-12-13T18:09:03.163Z,1513188543.163 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T18:09:03.164Z,1513188543.164 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T18:09:12.377Z,1513188552.377 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20171213T171603/Courier0028.lzma
2017-12-13T18:09:13.169Z,1513188553.169 [DataOverHttps](INFO): Moved sent file to Logs/20171213T171603/Courier0028.lzma.bak
2017-12-13T18:09:13.169Z,1513188553.169 [DataOverHttps](INFO): SBD MOMSN=5435445
2017-12-13T18:09:14.097Z,1513188554.097 [PNI_TCM](ERROR): CRC does not match. Expected:0x56056 got:0x12700
2017-12-13T18:09:27.598Z,1513188567.598 [CommandLine](IMPORTANT): got command restart application
2017-12-13T18:09:28.600Z,1513188568.600 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:28.600Z,1513188568.600 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:28.601Z,1513188568.601 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-12-13T18:09:28.601Z,1513188568.601 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:28.602Z,1513188568.602 [CommandLine](INFO): Join timeout helper Thread ID is 1879
2017-12-13T18:09:28.632Z,1513188568.632 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-12-13T18:09:28.632Z,1513188568.632 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:28.633Z,1513188568.633 [NavChartDb](INFO): Join timeout helper Thread ID is 1880
2017-12-13T18:09:28.940Z,1513188568.940 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:28.940Z,1513188568.940 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:28.948Z,1513188568.948 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2017-12-13T18:09:28.948Z,1513188568.948 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:28.948Z,1513188568.948 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1881
2017-12-13T18:09:29.020Z,1513188569.020 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:29.020Z,1513188569.020 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:29.025Z,1513188569.025 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-12-13T18:09:29.025Z,1513188569.025 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:29.026Z,1513188569.026 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1882
2017-12-13T18:09:29.173Z,1513188569.173 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:29.173Z,1513188569.173 [WetLabsBB2FL](INFO): Powering down
2017-12-13T18:09:29.174Z,1513188569.174 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:29.196Z,1513188569.196 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-12-13T18:09:29.196Z,1513188569.196 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:29.196Z,1513188569.196 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1883
2017-12-13T18:09:29.197Z,1513188569.197 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:29.637Z,1513188569.637 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T18:09:29.638Z,1513188569.638 [CTD_Seabird](INFO): Powering down
2017-12-13T18:09:29.640Z,1513188569.640 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:29.657Z,1513188569.657 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-12-13T18:09:29.657Z,1513188569.657 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:29.658Z,1513188569.658 [Radio_Surface](INFO): Join timeout helper Thread ID is 1886
2017-12-13T18:09:30.036Z,1513188570.036 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:30.036Z,1513188570.036 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:30.038Z,1513188570.038 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-12-13T18:09:30.038Z,1513188570.038 [logger ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:30.038Z,1513188570.038 [logger](INFO): Join timeout helper Thread ID is 1889
2017-12-13T18:09:30.072Z,1513188570.072 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:30.072Z,1513188570.072 [logger ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:30.085Z,1513188570.085 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-12-13T18:09:30.085Z,1513188570.085 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:30.085Z,1513188570.085 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-12-13T18:09:30.085Z,1513188570.085 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:30.086Z,1513188570.086 [controlThread](INFO): Join timeout helper Thread ID is 1890
2017-12-13T18:09:30.942Z,1513188570.942 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-12-13T18:09:30.942Z,1513188570.942 [controlThread](DEBUG): Uninitializing ControlThread
2017-12-13T18:09:30.943Z,1513188570.943 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-12-13T18:09:31.066Z,1513188571.066 [NAL9602](INFO): Powering down
2017-12-13T18:09:31.068Z,1513188571.068 [PNI_TCM](INFO): Powering down
2017-12-13T18:09:31.153Z,1513188571.153 [Aanderaa_O2](INFO): Powering down
2017-12-13T18:09:31.154Z,1513188571.154 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-13T18:09:31.155Z,1513188571.155 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2017-12-13T18:09:31.157Z,1513188571.157 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-12-13T18:09:31.157Z,1513188571.157 [MissionManager](INFO): Uninitializing Mission Default
2017-12-13T18:09:31.158Z,1513188571.158 [Default] Stopped
2017-12-13T18:09:31.158Z,1513188571.158 [Default](DEBUG): Aggregate::uninitialize Default
2017-12-13T18:09:31.158Z,1513188571.158 [Default:B.GoToSurface] Stopped
2017-12-13T18:09:31.158Z,1513188571.158 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T18:09:31.158Z,1513188571.158 [Default:CheckIn] Stopped
2017-12-13T18:09:31.158Z,1513188571.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T18:09:31.158Z,1513188571.158 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T18:09:31.161Z,1513188571.161 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-12-13T18:09:31.162Z,1513188571.162 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-12-13T18:09:31.162Z,1513188571.162 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-12-13T18:09:31.162Z,1513188571.162 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-12-13T18:09:31.163Z,1513188571.163 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-12-13T18:09:31.163Z,1513188571.163 [BuoyancyServo](INFO): Powering down
2017-12-13T18:09:31.176Z,1513188571.176 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-12-13T18:09:31.176Z,1513188571.176 [ElevatorServo](INFO): Powering down
2017-12-13T18:09:31.177Z,1513188571.177 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-12-13T18:09:31.177Z,1513188571.177 [MassServo](INFO): Powering down
2017-12-13T18:09:31.178Z,1513188571.178 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-12-13T18:09:31.178Z,1513188571.178 [RudderServo](INFO): Powering down
2017-12-13T18:09:31.179Z,1513188571.179 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-12-13T18:09:31.179Z,1513188571.179 [ThrusterServo](INFO): Powering down
2017-12-13T18:09:31.180Z,1513188571.180 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-12-13T18:09:31.181Z,1513188571.181 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-12-13T18:09:31.181Z,1513188571.181 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-12-13T18:09:31.182Z,1513188571.182 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:31.422Z,1513188571.422 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:31.523Z,1513188571.523 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:31.538Z,1513188571.538 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:31.541Z,1513188571.541 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:31.794Z,1513188571.794 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-12-13T18:09:31.971Z,1513188571.971 [logger ThreadHandler](INFO): Thread cancelled.