2018-07-30T15:51:43.868Z,1532965903.868 [Supervisor](DEBUG): Initializing supervisor.
2018-07-30T15:51:43.871Z,1532965903.871 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-30T15:51:43.872Z,1532965903.872 [SyncHandler](INFO): Protected caller Thread ID is 798
2018-07-30T15:51:43.872Z,1532965903.872 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-30T15:51:43.873Z,1532965903.873 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-30T15:51:43.873Z,1532965903.873 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 799
2018-07-30T15:51:43.876Z,1532965903.876 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-30T15:51:43.888Z,1532965903.888 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-30T15:51:43.889Z,1532965903.889 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-30T15:51:43.889Z,1532965903.889 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 800
2018-07-30T15:51:43.890Z,1532965903.890 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-30T15:51:43.891Z,1532965903.891 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-30T15:51:43.891Z,1532965903.891 [logger ThreadHandler](INFO): Protected caller Thread ID is 801
2018-07-30T15:51:43.893Z,1532965903.893 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-30T15:51:43.893Z,1532965903.893 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-30T15:51:43.897Z,1532965903.897 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-30T15:51:44.242Z,1532965904.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-30T15:51:44.244Z,1532965904.244 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-30T15:51:44.451Z,1532965904.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-30T15:51:44.452Z,1532965904.452 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-30T15:51:44.751Z,1532965904.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-30T15:51:44.753Z,1532965904.753 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-30T15:51:44.895Z,1532965904.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-30T15:51:44.896Z,1532965904.896 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-30T15:51:44.995Z,1532965904.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-30T15:51:44.996Z,1532965904.996 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-30T15:51:45.479Z,1532965905.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-30T15:51:45.481Z,1532965905.481 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-30T15:51:45.882Z,1532965905.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-30T15:51:45.883Z,1532965905.883 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-30T15:51:46.344Z,1532965906.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-30T15:51:46.346Z,1532965906.346 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-30T15:51:46.453Z,1532965906.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-30T15:51:46.454Z,1532965906.454 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-30T15:51:46.552Z,1532965906.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-30T15:51:46.553Z,1532965906.553 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-30T15:51:46.764Z,1532965906.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-30T15:51:46.765Z,1532965906.765 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-30T15:51:46.979Z,1532965906.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-30T15:51:46.980Z,1532965906.980 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-30T15:51:47.340Z,1532965907.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-30T15:51:47.341Z,1532965907.341 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-30T15:51:47.485Z,1532965907.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-30T15:51:47.485Z,1532965907.485 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-30T15:51:47.572Z,1532965907.572 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-07-30T15:51:48.098Z,1532965908.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-07-30T15:51:48.205Z,1532965908.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-07-30T15:51:48.326Z,1532965908.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-07-30T15:51:48.423Z,1532965908.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-07-30T15:51:48.509Z,1532965908.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-07-30T15:51:48.664Z,1532965908.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-07-30T15:51:48.847Z,1532965908.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-07-30T15:51:48.923Z,1532965908.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-07-30T15:51:49.157Z,1532965909.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-30T15:51:49.158Z,1532965909.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-07-30T15:51:49.242Z,1532965909.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-07-30T15:51:49.333Z,1532965909.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-07-30T15:51:49.435Z,1532965909.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-07-30T15:51:49.563Z,1532965909.563 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-30T15:51:49.577Z,1532965909.577 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-30T15:51:49.858Z,1532965909.858 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-30T15:51:49.860Z,1532965909.860 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-30T15:51:49.917Z,1532965909.917 [DepthRateCalculator] Loaded
2018-07-30T15:51:49.917Z,1532965909.917 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-30T15:51:49.923Z,1532965909.923 [PitchRateCalculator] Loaded
2018-07-30T15:51:49.923Z,1532965909.923 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-30T15:51:49.939Z,1532965909.939 [SpeedCalculator] Loaded
2018-07-30T15:51:49.940Z,1532965909.940 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-30T15:51:49.961Z,1532965909.961 [TempGradientCalculator] Loaded
2018-07-30T15:51:49.961Z,1532965909.961 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-30T15:51:49.977Z,1532965909.977 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-30T15:51:49.977Z,1532965909.977 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-30T15:51:49.982Z,1532965909.982 [YawRateCalculator] Loaded
2018-07-30T15:51:49.983Z,1532965909.983 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-30T15:51:50.022Z,1532965910.022 [ElevatorOffsetCalculator] Loaded
2018-07-30T15:51:50.023Z,1532965910.023 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-30T15:51:50.023Z,1532965910.023 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-30T15:51:50.024Z,1532965910.024 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-30T15:51:50.144Z,1532965910.144 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-30T15:51:50.144Z,1532965910.144 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-30T15:51:50.144Z,1532965910.144 [StratificationFrontDetector] Loaded
2018-07-30T15:51:50.145Z,1532965910.145 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-30T15:51:50.145Z,1532965910.145 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-30T15:51:50.146Z,1532965910.146 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-30T15:51:50.280Z,1532965910.280 [BuoyancyServo] Loaded
2018-07-30T15:51:50.280Z,1532965910.280 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-30T15:51:50.295Z,1532965910.295 [ElevatorServo] Loaded
2018-07-30T15:51:50.296Z,1532965910.296 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-30T15:51:50.310Z,1532965910.310 [MassServo] Loaded
2018-07-30T15:51:50.310Z,1532965910.310 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-30T15:51:50.326Z,1532965910.326 [RudderServo] Loaded
2018-07-30T15:51:50.326Z,1532965910.326 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-30T15:51:50.340Z,1532965910.340 [ThrusterServo] Loaded
2018-07-30T15:51:50.341Z,1532965910.341 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-30T15:51:50.341Z,1532965910.341 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-30T15:51:50.342Z,1532965910.342 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-30T15:51:50.365Z,1532965910.365 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-30T15:51:50.366Z,1532965910.366 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-30T15:51:50.491Z,1532965910.491 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-30T15:51:50.492Z,1532965910.492 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-30T15:51:51.053Z,1532965911.053 [DataOverHttps] Loaded
2018-07-30T15:51:51.054Z,1532965911.054 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-30T15:51:51.067Z,1532965911.067 [Depth_Keller] Loaded
2018-07-30T15:51:51.068Z,1532965911.068 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-30T15:51:51.072Z,1532965911.072 [DropWeight] Loaded
2018-07-30T15:51:51.073Z,1532965911.073 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-30T15:51:51.171Z,1532965911.171 [NAL9602] Loaded
2018-07-30T15:51:51.171Z,1532965911.171 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-30T15:51:51.187Z,1532965911.187 [Onboard] Loaded
2018-07-30T15:51:51.188Z,1532965911.188 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-30T15:51:51.198Z,1532965911.198 [Radio_Surface] Loaded
2018-07-30T15:51:51.198Z,1532965911.198 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-30T15:51:51.199Z,1532965911.199 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2018-07-30T15:51:51.200Z,1532965911.200 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 883
2018-07-30T15:51:51.353Z,1532965911.353 [PNI_TCM] Loaded
2018-07-30T15:51:51.353Z,1532965911.353 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-07-30T15:51:51.414Z,1532965911.414 [Rowe_600LCM] Loaded
2018-07-30T15:51:51.414Z,1532965911.414 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-07-30T15:51:51.415Z,1532965911.415 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0
2018-07-30T15:51:51.415Z,1532965911.415 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 884
2018-07-30T15:51:53.591Z,1532965913.591 [BPC1] Loaded
2018-07-30T15:51:53.592Z,1532965913.592 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-30T15:51:53.592Z,1532965913.592 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-30T15:51:53.593Z,1532965913.593 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-30T15:51:53.893Z,1532965913.893 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-30T15:51:53.915Z,1532965913.915 [SBIT] Loaded
2018-07-30T15:51:53.916Z,1532965913.916 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-30T15:51:53.916Z,1532965913.916 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-30T15:51:53.928Z,1532965913.928 [IBIT] Loaded
2018-07-30T15:51:53.928Z,1532965913.928 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-30T15:51:53.931Z,1532965913.931 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-30T15:51:54.082Z,1532965914.082 [CBIT] Loaded
2018-07-30T15:51:54.082Z,1532965914.082 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-30T15:51:54.082Z,1532965914.082 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-30T15:51:54.083Z,1532965914.083 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-30T15:51:54.270Z,1532965914.270 [Aanderaa_O2] Loaded
2018-07-30T15:51:54.270Z,1532965914.270 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-30T15:51:54.349Z,1532965914.349 [CTD_NeilBrown] Loaded
2018-07-30T15:51:54.349Z,1532965914.349 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-07-30T15:51:54.350Z,1532965914.350 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409754E0
2018-07-30T15:51:54.351Z,1532965914.351 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 885
2018-07-30T15:51:54.397Z,1532965914.397 [CTD_Seabird] Loaded
2018-07-30T15:51:54.397Z,1532965914.397 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-07-30T15:51:54.398Z,1532965914.398 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0
2018-07-30T15:51:54.399Z,1532965914.399 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-07-30T15:51:54.413Z,1532965914.413 [PAR_Licor] Loaded
2018-07-30T15:51:54.413Z,1532965914.413 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-07-30T15:51:54.463Z,1532965914.463 [WetLabsBB2FL] Loaded
2018-07-30T15:51:54.464Z,1532965914.464 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-30T15:51:54.465Z,1532965914.465 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409D54E0
2018-07-30T15:51:54.465Z,1532965914.465 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 887
2018-07-30T15:51:54.466Z,1532965914.466 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-30T15:51:54.466Z,1532965914.466 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-30T15:51:54.554Z,1532965914.554 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-30T15:51:54.639Z,1532965914.639 [VerticalControl] Loaded
2018-07-30T15:51:54.640Z,1532965914.640 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-30T15:51:54.640Z,1532965914.640 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-30T15:51:54.698Z,1532965914.698 [HorizontalControl] Loaded
2018-07-30T15:51:54.698Z,1532965914.698 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-30T15:51:54.699Z,1532965914.699 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-30T15:51:54.700Z,1532965914.700 [SpeedControl] Loaded
2018-07-30T15:51:54.701Z,1532965914.701 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-30T15:51:54.701Z,1532965914.701 [LoopControl](DEBUG): Construct LoopControl.
2018-07-30T15:51:54.702Z,1532965914.702 [LoopControl] Loaded
2018-07-30T15:51:54.702Z,1532965914.702 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-30T15:51:54.702Z,1532965914.702 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-30T15:51:54.703Z,1532965914.703 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-30T15:51:54.740Z,1532965914.740 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-30T15:51:54.741Z,1532965914.741 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-30T15:51:54.849Z,1532965914.849 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-30T15:51:54.849Z,1532965914.849 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-30T15:51:54.866Z,1532965914.866 [NavChart] Loaded
2018-07-30T15:51:54.866Z,1532965914.866 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-30T15:51:54.870Z,1532965914.870 [UniversalFixResidualReporter] Loaded
2018-07-30T15:51:54.870Z,1532965914.870 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-30T15:51:54.871Z,1532965914.871 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-30T15:51:54.874Z,1532965914.874 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-30T15:51:54.875Z,1532965914.875 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-30T15:51:54.882Z,1532965914.882 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-30T15:51:54.883Z,1532965914.883 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A614E0
2018-07-30T15:51:54.883Z,1532965914.883 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-07-30T15:51:54.888Z,1532965914.888 [Supervisor](INFO): Main Thread ID is 797
2018-07-30T15:51:54.888Z,1532965914.888 [Supervisor](DEBUG): Running supervisor.
2018-07-30T15:51:54.889Z,1532965914.889 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-07-30T15:51:54.891Z,1532965914.891 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-07-30T15:51:54.891Z,1532965914.891 [controlThread](DEBUG): Initializing ControlThread
2018-07-30T15:51:54.892Z,1532965914.892 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-30T15:51:54.893Z,1532965914.893 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-30T15:51:54.893Z,1532965914.893 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-30T15:51:54.893Z,1532965914.893 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-30T15:51:54.894Z,1532965914.894 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-30T15:51:54.895Z,1532965914.895 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-30T15:51:54.895Z,1532965914.895 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-30T15:51:54.901Z,1532965914.901 [SBIT](INFO): Initialize SBIT Component.
2018-07-30T15:51:54.902Z,1532965914.902 [SBIT](IMPORTANT): git: 2018-06-03
2018-07-30T15:51:54.902Z,1532965914.902 [SBIT](INFO): git hash: e89e4219fcae2edd415aee450018ea5d8b44159b
2018-07-30T15:51:54.902Z,1532965914.902 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-30T15:51:54.904Z,1532965914.904 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-30T15:51:54.905Z,1532965914.905 [SBIT](INFO): Beginning SBIT in 41.000000 seconds.
2018-07-30T15:51:54.905Z,1532965914.905 [IBIT](INFO): Initialize IBIT Component.
2018-07-30T15:51:54.906Z,1532965914.906 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-30T15:51:54.906Z,1532965914.906 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-07-30T15:51:54.907Z,1532965914.907 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-07-30T15:51:54.927Z,1532965914.927 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 892
2018-07-30T15:51:54.933Z,1532965914.933 [Radio_Surface](INFO): Powering up
2018-07-30T15:51:54.939Z,1532965914.939 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 893
2018-07-30T15:51:54.940Z,1532965914.940 [Rowe_600LCM](INFO): Initializing
2018-07-30T15:51:54.940Z,1532965914.940 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T15:51:55.019Z,1532965915.019 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-30T15:51:55.021Z,1532965915.021 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-30T15:51:55.022Z,1532965915.022 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-30T15:51:55.022Z,1532965915.022 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-30T15:51:55.023Z,1532965915.023 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T15:51:55.024Z,1532965915.024 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T15:51:55.058Z,1532965915.058 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T15:51:55.059Z,1532965915.059 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-30T15:51:55.059Z,1532965915.059 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-30T15:51:55.060Z,1532965915.060 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-30T15:51:55.071Z,1532965915.071 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 894
2018-07-30T15:51:55.075Z,1532965915.075 [CTD_NeilBrown](INFO): Powering down
2018-07-30T15:51:55.206Z,1532965915.206 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 895
2018-07-30T15:51:55.206Z,1532965915.206 [CTD_Seabird](INFO): Initializing
2018-07-30T15:51:55.207Z,1532965915.207 [CTD_Seabird](INFO): Checking LCM
2018-07-30T15:51:55.208Z,1532965915.208 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 896
2018-07-30T15:51:55.216Z,1532965915.216 [WetLabsBB2FL](INFO): Powering down
2018-07-30T15:51:55.302Z,1532965915.302 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 897
2018-07-30T15:51:55.311Z,1532965915.311 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-30T15:51:55.311Z,1532965915.311 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-30T15:51:55.311Z,1532965915.311 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-30T15:51:55.312Z,1532965915.312 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-30T15:51:55.312Z,1532965915.312 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-30T15:51:55.312Z,1532965915.312 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-30T15:51:55.312Z,1532965915.312 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-30T15:51:55.312Z,1532965915.312 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-30T15:51:55.313Z,1532965915.313 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-30T15:51:55.313Z,1532965915.313 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-30T15:51:55.313Z,1532965915.313 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-30T15:51:55.313Z,1532965915.313 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-30T15:51:55.314Z,1532965915.314 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-30T15:51:55.314Z,1532965915.314 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-30T15:51:55.314Z,1532965915.314 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-30T15:51:55.314Z,1532965915.314 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-30T15:51:55.331Z,1532965915.331 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-30T15:51:55.399Z,1532965915.399 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-07-30T15:51:55.400Z,1532965915.400 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-30T15:51:55.419Z,1532965915.419 [CTD_Seabird](INFO): LCM OK
2018-07-30T15:51:55.419Z,1532965915.419 [CTD_Seabird](INFO): Powering up
2018-07-30T15:51:55.420Z,1532965915.420 [Rowe_600LCM](INFO): LCM OK
2018-07-30T15:51:55.420Z,1532965915.420 [Rowe_600LCM](INFO): Powering up
2018-07-30T15:51:55.522Z,1532965915.522 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-30T15:51:55.535Z,1532965915.535 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-30T15:51:55.537Z,1532965915.537 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-30T15:51:55.561Z,1532965915.561 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-30T15:51:55.583Z,1532965915.583 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-30T15:51:55.589Z,1532965915.589 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-30T15:51:55.609Z,1532965915.609 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-07-30T15:51:55.614Z,1532965915.614 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-30T15:51:55.635Z,1532965915.635 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-30T15:51:55.658Z,1532965915.658 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-07-30T15:51:55.928Z,1532965915.928 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-07-30T15:51:56.020Z,1532965916.020 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:56.246Z,1532965916.246 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T15:51:56.259Z,1532965916.259 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T15:51:56.282Z,1532965916.282 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-30T15:51:56.287Z,1532965916.287 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-30T15:51:56.310Z,1532965916.310 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-30T15:51:56.315Z,1532965916.315 [MassServo](DEBUG): Initializing MassServo.
2018-07-30T15:51:56.321Z,1532965916.321 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-30T15:51:56.327Z,1532965916.327 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-30T15:51:56.333Z,1532965916.333 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-30T15:51:56.339Z,1532965916.339 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-30T15:51:56.661Z,1532965916.661 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:56.915Z,1532965916.915 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:57.413Z,1532965917.413 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:57.867Z,1532965917.867 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:58.251Z,1532965918.251 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:58.623Z,1532965918.623 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:59.127Z,1532965919.127 [Aanderaa_O2](INFO): Powering down
2018-07-30T15:51:59.238Z,1532965919.238 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T15:51:59.352Z,1532965919.352 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T15:51:59.498Z,1532965919.498 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T15:51:59.666Z,1532965919.666 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T15:51:59.681Z,1532965919.681 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T15:51:59.696Z,1532965919.696 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T15:52:00.665Z,1532965920.665 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-07-30T15:52:00.870Z,1532965920.870 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-07-30T15:52:00.871Z,1532965920.871 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-07-30T15:52:01.703Z,1532965921.703 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-07-30T15:52:01.703Z,1532965921.703 [CTD_Seabird](INFO): Powering down
2018-07-30T15:52:21.357Z,1532965941.357 [NAL9602](INFO): Powering up NAL9602
2018-07-30T15:52:29.744Z,1532965949.744 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:52:30.540Z,1532965950.540 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:52:32.569Z,1532965952.569 [NAL9602](INFO): NAL9602 initialized
2018-07-30T15:52:36.683Z,1532965956.683 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-30T15:52:36.688Z,1532965956.688 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-30T15:52:47.720Z,1532965967.720 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.009304
CHAN A2 (12V): -0.002383
CHAN A3 (5V): -0.001513
CHAN B0 (3.3V): -0.000301
CHAN B1 (3.15aV): -0.000308
CHAN B2 (3.15bV): 0.000108
CHAN B3 (GND): -0.000485
OPEN: -0.000267
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-30T15:53:27.340Z,1532966007.340 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:53:28.013Z,1532966008.013 [BPC1](ERROR): Failed to parse Bank B battery data
2018-07-30T15:53:28.064Z,1532966008.064 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:53:30.833Z,1532966010.833 [SBIT](IMPORTANT): SBIT PASSED
2018-07-30T15:53:30.926Z,1532966010.926 [CommandLine](IMPORTANT): got command configSet list
2018-07-30T15:53:30.927Z,1532966010.927 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-30T15:53:30.929Z,1532966010.929 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2018-07-30T15:53:30.929Z,1532966010.929 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2018-07-30T15:53:30.929Z,1532966010.929 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=5 minute;

2018-07-30T15:53:30.930Z,1532966010.930 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2018-07-30T15:53:30.931Z,1532966010.931 [CommandLine](IMPORTANT): Express none Rowe_600LCM.height_above_sea_floor;

2018-07-30T15:53:30.932Z,1532966010.932 [CommandLine](IMPORTANT): IBIT.batteryMissingStickThreshold=5 count;

2018-07-30T15:53:30.934Z,1532966010.934 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-07-30T15:53:30.935Z,1532966010.935 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=132 cubic_centimeter;

2018-07-30T15:53:30.935Z,1532966010.935 [CommandLine](IMPORTANT): VerticalControl.massDefault=5.3 millimeter;

2018-07-30T15:53:31.223Z,1532966011.223 [MissionManager](IMPORTANT): Started mission Startup
2018-07-30T15:53:31.223Z,1532966011.223 [Startup] Running Loop=1
2018-07-30T15:53:31.223Z,1532966011.223 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-30T15:53:31.223Z,1532966011.223 [Startup:A.GoToSurface] Running Loop=1
2018-07-30T15:53:31.223Z,1532966011.223 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T15:53:31.224Z,1532966011.224 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-30T15:53:31.225Z,1532966011.225 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-30T15:53:31.225Z,1532966011.225 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-30T15:53:31.225Z,1532966011.225 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T15:53:31.226Z,1532966011.226 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T15:53:31.232Z,1532966011.232 [Startup:StartupSatComms] Running Loop=1
2018-07-30T15:53:31.232Z,1532966011.232 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-30T15:53:31.232Z,1532966011.232 [Startup:StartupSatComms:A] Running Loop=1
2018-07-30T15:53:31.622Z,1532966011.622 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-30T15:54:21.740Z,1532966061.740 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:54:22.535Z,1532966062.535 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:54:29.970Z,1532966069.970 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T15:54:29.971Z,1532966069.971 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T15:54:31.614Z,1532966071.614 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-30T15:53:31.2Z
2018-07-30T15:54:31.614Z,1532966071.614 [Startup:StartupSatComms:A] Stopped
2018-07-30T15:54:31.614Z,1532966071.614 [Startup:StartupSatComms:B] Running Loop=1
2018-07-30T15:54:32.017Z,1532966072.017 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-30T15:54:55.208Z,1532966095.208 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T15:54:55.208Z,1532966095.208 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-30T15:54:55.208Z,1532966095.208 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-30T15:54:55.221Z,1532966095.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-30T15:54:55.644Z,1532966095.644 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-30T15:54:55.644Z,1532966095.644 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-30T15:54:56.021Z,1532966096.021 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T15:54:56.022Z,1532966096.022 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T15:54:56.022Z,1532966096.022 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T15:55:30.409Z,1532966130.409 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003949
2018-07-30T15:55:34.674Z,1532966134.674 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-30T15:54:31.6Z
2018-07-30T15:55:34.674Z,1532966134.674 [Startup:StartupSatComms:B] Stopped
2018-07-30T15:55:34.674Z,1532966134.674 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-30T15:55:34.674Z,1532966134.674 [Startup:StartupSatComms] Stopped
2018-07-30T15:55:34.674Z,1532966134.674 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-30T15:55:34.675Z,1532966134.675 [Startup](INFO): Completed Startup
2018-07-30T15:55:34.675Z,1532966134.675 [MissionManager](INFO): Startup is completed.
2018-07-30T15:55:34.676Z,1532966134.676 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-30T15:55:34.676Z,1532966134.676 [Startup] Stopped
2018-07-30T15:55:34.676Z,1532966134.676 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-30T15:55:34.676Z,1532966134.676 [Startup:A.GoToSurface] Stopped
2018-07-30T15:55:34.676Z,1532966134.676 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T15:55:34.710Z,1532966134.710 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T153117/Courier0010.lzma
2018-07-30T15:55:34.808Z,1532966134.808 [MissionManager](IMPORTANT): Started mission Default
2018-07-30T15:55:34.808Z,1532966134.808 [Default] Running Loop=1
2018-07-30T15:55:34.809Z,1532966134.809 [Default](DEBUG): Aggregate::initialize Default
2018-07-30T15:55:34.809Z,1532966134.809 [Default:B.GoToSurface] Running Loop=1
2018-07-30T15:55:34.809Z,1532966134.809 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T15:55:34.809Z,1532966134.809 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-30T15:55:34.809Z,1532966134.809 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-30T15:55:34.810Z,1532966134.810 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-30T15:55:34.810Z,1532966134.810 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T15:55:34.810Z,1532966134.810 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T15:55:34.815Z,1532966134.815 [Default:A.Wait] Running Loop=1
2018-07-30T15:55:34.815Z,1532966134.815 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-30T15:55:35.514Z,1532966135.514 [DataOverHttps](INFO): Moved sent file to Logs/20180730T153117/Courier0010.lzma.bak
2018-07-30T15:55:35.514Z,1532966135.514 [DataOverHttps](INFO): SBD MOMSN=8385346
2018-07-30T15:55:48.035Z,1532966148.035 [Default:A.Wait](INFO): Done Waiting.
2018-07-30T15:55:48.035Z,1532966148.035 [Default:A.Wait] Stopped
2018-07-30T15:55:48.035Z,1532966148.035 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T15:55:48.425Z,1532966148.425 [Default:CheckIn] Running Loop=1
2018-07-30T15:55:48.425Z,1532966148.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T15:55:48.426Z,1532966148.426 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T15:55:48.822Z,1532966148.822 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-30T15:56:13.744Z,1532966173.744 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:56:14.539Z,1532966174.539 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:56:25.974Z,1532966185.974 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T15:56:25.974Z,1532966185.974 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T15:57:23.213Z,1532966243.213 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T15:57:23.214Z,1532966243.214 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T15:57:39.976Z,1532966259.976 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T15:57:39.976Z,1532966259.976 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T15:57:40.099Z,1532966260.099 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-07-30T15:57:56.155Z,1532966276.155 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T15:57:56.156Z,1532966276.156 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-07-30T15:57:56.156Z,1532966276.156 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-30T15:57:56.189Z,1532966276.189 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-30T15:57:56.568Z,1532966276.568 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-30T15:57:56.568Z,1532966276.568 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-07-30T15:57:57.019Z,1532966277.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T15:57:57.021Z,1532966277.021 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T15:57:57.021Z,1532966277.021 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T15:58:19.800Z,1532966299.800 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:58:20.596Z,1532966300.596 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T15:58:34.551Z,1532966314.551 [BPC1](ERROR): Bank A: No match for stick 2 serial number:1717 in the onboard configuration file.
2018-07-30T15:58:34.551Z,1532966314.551 [BPC1](ERROR): Failed to parse Bank A battery data
2018-07-30T15:59:07.879Z,1532966347.879 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T15:59:07.879Z,1532966347.879 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-07-30T15:59:07.879Z,1532966347.879 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T15:59:08.233Z,1532966348.233 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T15:59:08.283Z,1532966348.283 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T15:59:08.376Z,1532966348.376 [Rowe_600LCM](INFO): Powering down
2018-07-30T15:59:09.809Z,1532966349.809 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T15:59:09.809Z,1532966349.809 [Rowe_600LCM] No Fault, FailCount= 1
2018-07-30T15:59:09.991Z,1532966349.991 [Rowe_600LCM](INFO): Initializing
2018-07-30T15:59:09.991Z,1532966349.991 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T15:59:09.992Z,1532966349.992 [Rowe_600LCM](INFO): LCM OK
2018-07-30T15:59:09.992Z,1532966349.992 [Rowe_600LCM](INFO): Powering up
2018-07-30T15:59:14.115Z,1532966354.115 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T15:59:14.277Z,1532966354.277 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T15:59:14.278Z,1532966354.278 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T15:59:14.279Z,1532966354.279 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T15:59:14.280Z,1532966354.280 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:00:39.740Z,1532966439.740 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:00:40.536Z,1532966440.536 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:00:48.733Z,1532966448.733 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-30T15:55:48.4Z
2018-07-30T16:00:48.733Z,1532966448.733 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:00:48.733Z,1532966448.733 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:00:49.173Z,1532966449.173 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-30T16:00:53.457Z,1532966453.457 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T155143/Courier0004.lzma
2018-07-30T16:00:54.260Z,1532966454.260 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0004.lzma.bak
2018-07-30T16:00:54.260Z,1532966454.260 [DataOverHttps](INFO): SBD MOMSN=8385361
2018-07-30T16:00:58.536Z,1532966458.536 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T16:00:58.536Z,1532966458.536 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-07-30T16:00:58.536Z,1532966458.536 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-30T16:00:58.550Z,1532966458.550 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-30T16:00:58.693Z,1532966458.693 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-30T16:00:58.693Z,1532966458.693 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-07-30T16:00:59.099Z,1532966459.099 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T16:00:59.100Z,1532966459.100 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T16:00:59.101Z,1532966459.101 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T16:01:03.729Z,1532966463.729 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20180730T153117/Express0011.lzma
2018-07-30T16:01:04.525Z,1532966464.525 [DataOverHttps](INFO): Moved sent file to Logs/20180730T153117/Express0011.lzma.bak
2018-07-30T16:01:04.526Z,1532966464.526 [DataOverHttps](INFO): SBD MOMSN=8385364
2018-07-30T16:01:13.870Z,1532966473.870 [DataOverHttps](INFO): Sending 783 bytes from file Logs/20180730T155143/Express0001.lzma
2018-07-30T16:01:14.669Z,1532966474.669 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0001.lzma.bak
2018-07-30T16:01:14.670Z,1532966474.670 [DataOverHttps](INFO): SBD MOMSN=8385368
2018-07-30T16:01:24.030Z,1532966484.030 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20180730T155143/Express0005.lzma
2018-07-30T16:01:24.805Z,1532966484.805 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0005.lzma.bak
2018-07-30T16:01:24.806Z,1532966484.806 [DataOverHttps](INFO): SBD MOMSN=8385389
2018-07-30T16:01:25.268Z,1532966485.268 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:01:25.268Z,1532966485.268 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:01:25.268Z,1532966485.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:01:31.883Z,1532966491.883 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T16:01:31.883Z,1532966491.883 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-07-30T16:01:31.883Z,1532966491.883 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T16:01:32.205Z,1532966492.205 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T16:01:32.287Z,1532966492.287 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:01:32.422Z,1532966492.422 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:01:33.832Z,1532966493.832 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T16:01:33.832Z,1532966493.832 [Rowe_600LCM] No Fault, FailCount= 2
2018-07-30T16:01:34.039Z,1532966494.039 [Rowe_600LCM](INFO): Initializing
2018-07-30T16:01:34.040Z,1532966494.040 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T16:01:34.040Z,1532966494.040 [Rowe_600LCM](INFO): LCM OK
2018-07-30T16:01:34.040Z,1532966494.040 [Rowe_600LCM](INFO): Powering up
2018-07-30T16:01:38.292Z,1532966498.292 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T16:01:38.359Z,1532966498.359 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T16:01:38.360Z,1532966498.360 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T16:01:38.360Z,1532966498.360 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T16:01:38.361Z,1532966498.361 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:02:19.306Z,1532966539.306 [NAL9602](INFO): GPS fix at 20180730T160219: (36.802400, -121.787421)
2018-07-30T16:02:25.678Z,1532966545.678 [CommandLine](IMPORTANT): got command ibit
2018-07-30T16:02:25.861Z,1532966545.861 [IBIT](IMPORTANT): Beginning Initiated BIT
2018-07-30T16:02:25.861Z,1532966545.861 [IBIT](IMPORTANT): Beginning control surface checks.
2018-07-30T16:02:25.864Z,1532966545.864 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-30T16:02:32.137Z,1532966552.137 [NAL9602](INFO): GPS fix at 20180730T160231: (36.802365, -121.787359)
2018-07-30T16:02:34.529Z,1532966554.529 [NAL9602](INFO): GPS fix at 20180730T160234: (36.802365, -121.787344)
2018-07-30T16:02:35.740Z,1532966555.740 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:02:36.538Z,1532966556.538 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:02:37.333Z,1532966557.333 [NAL9602](INFO): GPS fix at 20180730T160237: (36.802364, -121.787337)
2018-07-30T16:02:40.536Z,1532966560.536 [NAL9602](INFO): GPS fix at 20180730T160240: (36.802362, -121.787335)
2018-07-30T16:02:41.843Z,1532966561.843 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005839
CHAN A1 (24V): -0.009354
CHAN A2 (12V): -0.001683
CHAN A3 (5V): -0.001497
CHAN B0 (3.3V): -0.000018
CHAN B1 (3.15aV): -0.000010
CHAN B2 (3.15bV): -0.000139
CHAN B3 (GND): -0.000122
OPEN: -0.000400
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-30T16:02:43.339Z,1532966563.339 [NAL9602](INFO): GPS fix at 20180730T160243: (36.802361, -121.787333)
2018-07-30T16:02:46.528Z,1532966566.528 [NAL9602](INFO): GPS fix at 20180730T160246: (36.802362, -121.787331)
2018-07-30T16:02:49.329Z,1532966569.329 [NAL9602](INFO): GPS fix at 20180730T160249: (36.802362, -121.787328)
2018-07-30T16:02:52.528Z,1532966572.528 [NAL9602](INFO): GPS fix at 20180730T160252: (36.802359, -121.787312)
2018-07-30T16:02:55.328Z,1532966575.328 [NAL9602](INFO): GPS fix at 20180730T160255: (36.802346, -121.787280)
2018-07-30T16:02:58.532Z,1532966578.532 [NAL9602](INFO): GPS fix at 20180730T160258: (36.802343, -121.787245)
2018-07-30T16:03:01.336Z,1532966581.336 [NAL9602](INFO): GPS fix at 20180730T160301: (36.802357, -121.787215)
2018-07-30T16:03:04.528Z,1532966584.528 [NAL9602](INFO): GPS fix at 20180730T160304: (36.802374, -121.787187)
2018-07-30T16:03:07.328Z,1532966587.328 [NAL9602](INFO): GPS fix at 20180730T160307: (36.802387, -121.787159)
2018-07-30T16:03:10.528Z,1532966590.528 [NAL9602](INFO): GPS fix at 20180730T160310: (36.802391, -121.787140)
2018-07-30T16:03:11.811Z,1532966591.811 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802391 Longitude: -121.787140
2018-07-30T16:03:12.205Z,1532966592.205 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2018-07-30T16:03:12.601Z,1532966592.601 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2018-07-30T16:03:12.601Z,1532966592.601 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2018-07-30T16:03:12.601Z,1532966592.601 [IBIT](IMPORTANT): Pressure:9.509684 PSI
2018-07-30T16:03:12.602Z,1532966592.602 [IBIT](IMPORTANT): Humidity:15.114149 %
2018-07-30T16:03:13.079Z,1532966593.079 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2018-07-30T16:03:13.080Z,1532966593.080 [IBIT](IMPORTANT): buoyancyNeutral: 132.000000 cc
2018-07-30T16:03:13.080Z,1532966593.080 [IBIT](IMPORTANT): massDefault: 0.530000 cm
2018-07-30T16:03:13.080Z,1532966593.080 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2018-07-30T16:03:13.081Z,1532966593.081 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2018-07-30T16:03:13.081Z,1532966593.081 [IBIT](IMPORTANT): IBIT PASSED
2018-07-30T16:03:42.919Z,1532966622.919 [NAL9602](INFO): Powering down
2018-07-30T16:03:51.867Z,1532966631.867 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T16:03:51.867Z,1532966631.867 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-07-30T16:03:51.867Z,1532966631.867 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T16:03:52.176Z,1532966632.176 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T16:03:52.271Z,1532966632.271 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:03:52.370Z,1532966632.370 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:03:53.795Z,1532966633.795 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T16:03:53.795Z,1532966633.795 [Rowe_600LCM] No Fault, FailCount= 3
2018-07-30T16:03:53.987Z,1532966633.987 [Rowe_600LCM](INFO): Initializing
2018-07-30T16:03:53.987Z,1532966633.987 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T16:03:53.988Z,1532966633.988 [Rowe_600LCM](INFO): LCM OK
2018-07-30T16:03:53.988Z,1532966633.988 [Rowe_600LCM](INFO): Powering up
2018-07-30T16:03:58.169Z,1532966638.169 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T16:03:58.309Z,1532966638.309 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T16:03:58.310Z,1532966638.310 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T16:03:58.311Z,1532966638.311 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T16:03:58.312Z,1532966638.312 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:04:13.764Z,1532966653.764 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:04:14.560Z,1532966654.560 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:37.732Z,1532966737.732 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:38.528Z,1532966738.528 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:39.323Z,1532966739.323 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:40.024Z,1532966740.024 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:40.819Z,1532966740.819 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:41.616Z,1532966741.616 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:05:42.023Z,1532966742.023 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:06:26.006Z,1532966786.006 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:06:26.006Z,1532966786.006 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:06:26.006Z,1532966786.006 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:06:26.006Z,1532966786.006 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:06:26.403Z,1532966786.403 [Default:CheckIn:D] Stopped
2018-07-30T16:06:26.403Z,1532966786.403 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:06:29.869Z,1532966789.869 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.859751 min 
2018-07-30T16:06:29.871Z,1532966789.871 [Default:CheckIn:E] Stopped
2018-07-30T16:06:29.871Z,1532966789.871 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:06:29.871Z,1532966789.871 [Default:CheckIn] Stopped
2018-07-30T16:06:29.872Z,1532966789.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:06:29.872Z,1532966789.872 [Default:CheckIn](INFO): Running loop #2
2018-07-30T16:06:29.872Z,1532966789.872 [Default:CheckIn] Running Loop=2
2018-07-30T16:06:29.872Z,1532966789.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:06:29.872Z,1532966789.872 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:06:30.296Z,1532966790.296 [NAL9602](INFO): Powering up
2018-07-30T16:06:41.497Z,1532966801.497 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:07:04.302Z,1532966824.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T16:07:04.302Z,1532966824.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:07:21.898Z,1532966841.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T16:07:21.898Z,1532966841.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:07:39.350Z,1532966859.350 [NAL9602](INFO): SBD MO Status=2, MOMSN=7599, MT Status=2, MTMSN=0
2018-07-30T16:07:39.350Z,1532966859.350 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:08:11.720Z,1532966891.720 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:08:12.516Z,1532966892.516 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:08:20.150Z,1532966900.150 [NAL9602](INFO): SBD MO Status=0, MOMSN=7599, MT Status=0, MTMSN=0
2018-07-30T16:08:20.150Z,1532966900.150 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:08:21.353Z,1532966901.353 [NAL9602](INFO): GPS fix at 20180730T160820: (36.802721, -121.786919)
2018-07-30T16:08:21.422Z,1532966901.422 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:08:21.422Z,1532966901.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:08:27.262Z,1532966907.262 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20180730T155143/Courier0007.lzma
2018-07-30T16:08:28.057Z,1532966908.057 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0007.lzma.bak
2018-07-30T16:08:28.058Z,1532966908.058 [DataOverHttps](INFO): SBD MOMSN=8385394
2018-07-30T16:08:37.602Z,1532966917.602 [DataOverHttps](INFO): Sending 921 bytes from file Logs/20180730T155143/Express0008.lzma
2018-07-30T16:08:38.385Z,1532966918.385 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0008.lzma.bak
2018-07-30T16:08:38.386Z,1532966918.386 [DataOverHttps](INFO): SBD MOMSN=8385397
2018-07-30T16:08:38.878Z,1532966918.878 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:08:38.883Z,1532966918.883 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:08:38.883Z,1532966918.883 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:08:57.422Z,1532966937.422 [NAL9602](INFO): Powering down
2018-07-30T16:09:07.465Z,1532966947.465 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.388642 seconds old.
2018-07-30T16:09:07.874Z,1532966947.874 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.797829 seconds old.
2018-07-30T16:09:08.262Z,1532966948.262 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.185915 seconds old.
2018-07-30T16:09:08.670Z,1532966948.670 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.594348 seconds old.
2018-07-30T16:09:09.068Z,1532966949.068 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.992305 seconds old.
2018-07-30T16:09:09.509Z,1532966949.509 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.433156 seconds old.
2018-07-30T16:09:09.883Z,1532966949.883 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.807432 seconds old.
2018-07-30T16:09:10.270Z,1532966950.270 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.193878 seconds old.
2018-07-30T16:09:10.667Z,1532966950.667 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.591324 seconds old.
2018-07-30T16:09:11.084Z,1532966951.084 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.008178 seconds old.
2018-07-30T16:09:11.517Z,1532966951.517 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.440005 seconds old.
2018-07-30T16:09:11.883Z,1532966951.883 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.807108 seconds old.
2018-07-30T16:09:12.274Z,1532966952.274 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.198394 seconds old.
2018-07-30T16:09:12.677Z,1532966952.677 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 5.600537 seconds old.
2018-07-30T16:09:13.115Z,1532966953.115 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.038728 seconds old.
2018-07-30T16:09:13.470Z,1532966953.470 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 6.394261 seconds old.
2018-07-30T16:09:31.469Z,1532966971.469 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.361580 seconds old.
2018-07-30T16:09:31.867Z,1532966971.867 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.759712 seconds old.
2018-07-30T16:09:32.266Z,1532966972.266 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.157960 seconds old.
2018-07-30T16:09:32.665Z,1532966972.665 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.556950 seconds old.
2018-07-30T16:09:33.112Z,1532966973.112 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.004228 seconds old.
2018-07-30T16:09:33.482Z,1532966973.482 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.374531 seconds old.
2018-07-30T16:09:33.863Z,1532966973.863 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.755656 seconds old.
2018-07-30T16:09:34.266Z,1532966974.266 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.157893 seconds old.
2018-07-30T16:10:17.732Z,1532967017.732 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:10:18.527Z,1532967018.527 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:12:09.732Z,1532967129.732 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:12:10.532Z,1532967130.532 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:12:56.428Z,1532967176.428 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 14 sticks.
2018-07-30T16:12:56.428Z,1532967176.428 [BPC1] Data Fault, FailCount= 1
2018-07-30T16:12:56.428Z,1532967176.428 [BPC1](ERROR): Data Fault
2018-07-30T16:12:56.516Z,1532967176.516 [CBIT](ERROR): Data Fault in component: BPC1
2018-07-30T16:12:57.650Z,1532967177.650 [CBIT](INFO): Clearing failed state for component BPC1
2018-07-30T16:12:57.650Z,1532967177.650 [BPC1] No Fault, FailCount= 1
2018-07-30T16:13:39.638Z,1532967219.638 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:13:39.638Z,1532967219.638 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:13:39.638Z,1532967219.638 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:13:39.639Z,1532967219.639 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:13:40.033Z,1532967220.033 [Default:CheckIn:D] Stopped
2018-07-30T16:13:40.033Z,1532967220.033 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:13:40.437Z,1532967220.437 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.087069 min 
2018-07-30T16:13:40.437Z,1532967220.437 [Default:CheckIn:E] Stopped
2018-07-30T16:13:40.437Z,1532967220.437 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:13:40.437Z,1532967220.437 [Default:CheckIn] Stopped
2018-07-30T16:13:40.437Z,1532967220.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:13:40.437Z,1532967220.437 [Default:CheckIn](INFO): Running loop #3
2018-07-30T16:13:40.438Z,1532967220.438 [Default:CheckIn] Running Loop=3
2018-07-30T16:13:40.438Z,1532967220.438 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:13:40.438Z,1532967220.438 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:13:43.980Z,1532967223.980 [NAL9602](INFO): Powering up
2018-07-30T16:13:55.140Z,1532967235.140 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:14:01.728Z,1532967241.728 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:14:02.523Z,1532967242.523 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:14:12.742Z,1532967252.742 [NAL9602](INFO): SBD MO Status=0, MOMSN=7600, MT Status=0, MTMSN=0
2018-07-30T16:14:12.742Z,1532967252.742 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:14:15.724Z,1532967255.724 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:14:16.519Z,1532967256.519 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:14:31.560Z,1532967271.560 [NAL9602](INFO): GPS fix at 20180730T161430: (36.802664, -121.786967)
2018-07-30T16:14:31.667Z,1532967271.667 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:14:31.667Z,1532967271.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:14:36.395Z,1532967276.395 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180730T155143/Courier0010.lzma
2018-07-30T16:14:37.182Z,1532967277.182 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0010.lzma.bak
2018-07-30T16:14:37.182Z,1532967277.182 [DataOverHttps](INFO): SBD MOMSN=8385433
2018-07-30T16:14:46.917Z,1532967286.917 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20180730T155143/Express0011.lzma
2018-07-30T16:14:47.698Z,1532967287.698 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0011.lzma.bak
2018-07-30T16:14:47.698Z,1532967287.698 [DataOverHttps](INFO): SBD MOMSN=8385436
2018-07-30T16:14:48.155Z,1532967288.155 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:14:48.155Z,1532967288.155 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:14:48.155Z,1532967288.155 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:15:06.584Z,1532967306.584 [NAL9602](INFO): Powering down
2018-07-30T16:16:07.728Z,1532967367.728 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:16:08.524Z,1532967368.524 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:17:59.720Z,1532967479.720 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:18:00.517Z,1532967480.517 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:18:05.895Z,1532967485.895 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T16:18:05.895Z,1532967485.895 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-07-30T16:18:05.895Z,1532967485.895 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T16:18:05.936Z,1532967485.936 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T16:18:06.299Z,1532967486.299 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:18:06.483Z,1532967486.483 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:18:07.944Z,1532967487.944 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T16:18:07.944Z,1532967487.944 [Rowe_600LCM] No Fault, FailCount= 4
2018-07-30T16:18:08.099Z,1532967488.099 [Rowe_600LCM](INFO): Initializing
2018-07-30T16:18:08.099Z,1532967488.099 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T16:18:08.100Z,1532967488.100 [Rowe_600LCM](INFO): LCM OK
2018-07-30T16:18:08.100Z,1532967488.100 [Rowe_600LCM](INFO): Powering up
2018-07-30T16:18:12.226Z,1532967492.226 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T16:18:12.351Z,1532967492.351 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T16:18:12.352Z,1532967492.352 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T16:18:12.352Z,1532967492.352 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T16:18:12.353Z,1532967492.353 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:19:48.969Z,1532967588.969 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:19:48.970Z,1532967588.970 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:19:48.970Z,1532967588.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:19:48.970Z,1532967588.970 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:19:49.341Z,1532967589.341 [Default:CheckIn:D] Stopped
2018-07-30T16:19:49.341Z,1532967589.341 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:19:49.748Z,1532967589.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.242204 min 
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn:E] Stopped
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn] Stopped
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn](INFO): Running loop #4
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn] Running Loop=4
2018-07-30T16:19:49.749Z,1532967589.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:19:49.750Z,1532967589.750 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:19:51.720Z,1532967591.720 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:19:52.515Z,1532967592.515 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:19:53.128Z,1532967593.128 [NAL9602](INFO): Powering up
2018-07-30T16:20:04.272Z,1532967604.272 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:20:08.407Z,1532967608.407 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T16:20:08.407Z,1532967608.407 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-07-30T16:20:08.407Z,1532967608.407 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T16:20:08.763Z,1532967608.763 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T16:20:08.764Z,1532967608.764 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-07-30T16:20:08.811Z,1532967608.811 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:20:08.900Z,1532967608.900 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:20:38.278Z,1532967638.278 [NAL9602](INFO): SBD MO Status=2, MOMSN=7601, MT Status=2, MTMSN=0
2018-07-30T16:20:38.278Z,1532967638.278 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:21:25.263Z,1532967685.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=7601, MT Status=2, MTMSN=0
2018-07-30T16:21:25.263Z,1532967685.263 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:21:45.262Z,1532967705.262 [NAL9602](INFO): SBD MO Status=0, MOMSN=7601, MT Status=0, MTMSN=0
2018-07-30T16:21:45.262Z,1532967705.262 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:21:46.469Z,1532967706.469 [NAL9602](INFO): GPS fix at 20180730T162146: (36.802157, -121.787126)
2018-07-30T16:21:46.523Z,1532967706.523 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:21:46.523Z,1532967706.523 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:21:50.926Z,1532967710.926 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180730T155143/Courier0013.lzma
2018-07-30T16:21:51.701Z,1532967711.701 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0013.lzma.bak
2018-07-30T16:21:51.701Z,1532967711.701 [DataOverHttps](INFO): SBD MOMSN=8385457
2018-07-30T16:22:00.941Z,1532967720.941 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20180730T155143/Express0014.lzma
2018-07-30T16:22:01.725Z,1532967721.725 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0014.lzma.bak
2018-07-30T16:22:01.726Z,1532967721.726 [DataOverHttps](INFO): SBD MOMSN=8385460
2018-07-30T16:22:02.194Z,1532967722.194 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:22:02.194Z,1532967722.194 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:22:02.194Z,1532967722.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:22:21.128Z,1532967741.128 [NAL9602](INFO): Powering down
2018-07-30T16:25:09.701Z,1532967909.701 [BPC1](ERROR): Bank A: No match for stick 0 serial number:1717 in the onboard configuration file.
2018-07-30T16:25:09.702Z,1532967909.702 [BPC1](ERROR): Failed to parse Bank A battery data
2018-07-30T16:27:02.765Z,1532968022.765 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:27:02.766Z,1532968022.766 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:27:02.766Z,1532968022.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:27:02.766Z,1532968022.766 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:27:03.197Z,1532968023.197 [Default:CheckIn:D] Stopped
2018-07-30T16:27:03.197Z,1532968023.197 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:27:06.306Z,1532968026.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.473132 min 
2018-07-30T16:27:06.306Z,1532968026.306 [Default:CheckIn:E] Stopped
2018-07-30T16:27:06.306Z,1532968026.306 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:27:06.306Z,1532968026.306 [Default:CheckIn] Stopped
2018-07-30T16:27:06.306Z,1532968026.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:27:06.306Z,1532968026.306 [Default:CheckIn](INFO): Running loop #5
2018-07-30T16:27:06.307Z,1532968026.307 [Default:CheckIn] Running Loop=5
2018-07-30T16:27:06.307Z,1532968026.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:27:06.307Z,1532968026.307 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:27:06.737Z,1532968026.737 [NAL9602](INFO): Powering up
2018-07-30T16:27:17.932Z,1532968037.932 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:27:39.538Z,1532968059.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=7602, MT Status=2, MTMSN=0
2018-07-30T16:27:39.538Z,1532968059.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:28:18.482Z,1532968098.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=7602, MT Status=2, MTMSN=0
2018-07-30T16:28:18.482Z,1532968098.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T16:30:08.517Z,1532968208.517 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T16:30:08.518Z,1532968208.518 [Rowe_600LCM] No Fault, FailCount= 5
2018-07-30T16:30:08.787Z,1532968208.787 [Rowe_600LCM](INFO): Initializing
2018-07-30T16:30:08.787Z,1532968208.787 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T16:30:08.787Z,1532968208.787 [Rowe_600LCM](INFO): LCM OK
2018-07-30T16:30:08.787Z,1532968208.787 [Rowe_600LCM](INFO): Powering up
2018-07-30T16:30:13.027Z,1532968213.027 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T16:30:13.113Z,1532968213.113 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T16:30:13.114Z,1532968213.114 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T16:30:13.114Z,1532968213.114 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T16:30:13.115Z,1532968213.115 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:30:30.000Z,1532968231.000 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:30:31.795Z,1532968231.796 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:30:42.704Z,1532968242.704 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:30:43.499Z,1532968243.499 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:31:57.458Z,1532968317.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=7602, MT Status=0, MTMSN=0
2018-07-30T16:31:57.458Z,1532968317.458 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:31:58.660Z,1532968318.660 [NAL9602](INFO): GPS fix at 20180730T163157: (36.802221, -121.786883)
2018-07-30T16:31:58.730Z,1532968318.730 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:31:58.730Z,1532968318.730 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:32:03.294Z,1532968323.294 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180730T155143/Courier0016.lzma
2018-07-30T16:32:04.086Z,1532968324.086 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0016.lzma.bak
2018-07-30T16:32:04.089Z,1532968324.089 [DataOverHttps](INFO): SBD MOMSN=8385488
2018-07-30T16:32:13.484Z,1532968333.484 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20180730T155143/Express0017.lzma
2018-07-30T16:32:14.258Z,1532968334.258 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0017.lzma.bak
2018-07-30T16:32:14.258Z,1532968334.258 [DataOverHttps](INFO): SBD MOMSN=8385491
2018-07-30T16:32:14.718Z,1532968334.718 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:32:14.718Z,1532968334.718 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:32:14.718Z,1532968334.718 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:32:33.580Z,1532968353.580 [NAL9602](INFO): Powering down
2018-07-30T16:32:34.708Z,1532968354.708 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:32:35.503Z,1532968355.503 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:33:58.472Z,1532968438.472 [BPC1](FAULT): Failed to receive data from all sticks prior to timeout. Missing data from 14 sticks.
2018-07-30T16:33:58.472Z,1532968438.472 [BPC1] Data Fault, FailCount= 2
2018-07-30T16:33:58.472Z,1532968438.472 [BPC1](ERROR): Data Fault
2018-07-30T16:33:58.522Z,1532968438.522 [CBIT](ERROR): Data Fault in component: BPC1
2018-07-30T16:33:59.684Z,1532968439.684 [CBIT](INFO): Clearing failed state for component BPC1
2018-07-30T16:33:59.684Z,1532968439.684 [BPC1] No Fault, FailCount= 2
2018-07-30T16:34:40.712Z,1532968480.712 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:34:41.507Z,1532968481.507 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:36:32.708Z,1532968592.708 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:36:33.506Z,1532968593.506 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:37:15.348Z,1532968635.348 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:37:15.348Z,1532968635.348 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:37:15.348Z,1532968635.348 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:37:15.349Z,1532968635.349 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:37:15.750Z,1532968635.750 [Default:CheckIn:D] Stopped
2018-07-30T16:37:15.750Z,1532968635.750 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:37:16.154Z,1532968636.154 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.682357 min 
2018-07-30T16:37:16.154Z,1532968636.154 [Default:CheckIn:E] Stopped
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn] Stopped
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn](INFO): Running loop #6
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn] Running Loop=6
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:37:16.155Z,1532968636.155 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:37:19.592Z,1532968639.592 [NAL9602](INFO): Powering up
2018-07-30T16:37:30.779Z,1532968650.779 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:37:45.174Z,1532968665.174 [NAL9602](INFO): SBD MO Status=0, MOMSN=7603, MT Status=0, MTMSN=0
2018-07-30T16:37:45.174Z,1532968665.174 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:38:10.305Z,1532968690.305 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T16:38:10.305Z,1532968690.305 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-07-30T16:38:10.305Z,1532968690.305 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T16:38:10.471Z,1532968690.471 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T16:38:10.707Z,1532968690.707 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:38:10.896Z,1532968690.896 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:38:12.436Z,1532968692.436 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T16:38:12.436Z,1532968692.436 [Rowe_600LCM] No Fault, FailCount= 1
2018-07-30T16:38:12.511Z,1532968692.511 [Rowe_600LCM](INFO): Initializing
2018-07-30T16:38:12.511Z,1532968692.511 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T16:38:12.512Z,1532968692.512 [Rowe_600LCM](INFO): LCM OK
2018-07-30T16:38:12.512Z,1532968692.512 [Rowe_600LCM](INFO): Powering up
2018-07-30T16:38:16.638Z,1532968696.638 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T16:38:16.702Z,1532968696.702 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T16:38:16.703Z,1532968696.703 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T16:38:16.703Z,1532968696.703 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T16:38:16.704Z,1532968696.704 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:38:25.536Z,1532968705.536 [NAL9602](INFO): GPS fix at 20180730T163824: (36.801963, -121.786697)
2018-07-30T16:38:25.608Z,1532968705.608 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:38:25.608Z,1532968705.608 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:38:30.474Z,1532968710.474 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180730T155143/Courier0019.lzma
2018-07-30T16:38:31.273Z,1532968711.273 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0019.lzma.bak
2018-07-30T16:38:31.274Z,1532968711.274 [DataOverHttps](INFO): SBD MOMSN=8385504
2018-07-30T16:38:40.660Z,1532968720.660 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20180730T155143/Express0020.lzma
2018-07-30T16:38:41.443Z,1532968721.443 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0020.lzma.bak
2018-07-30T16:38:41.443Z,1532968721.443 [DataOverHttps](INFO): SBD MOMSN=8385508
2018-07-30T16:38:41.969Z,1532968721.969 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:38:41.969Z,1532968721.969 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:38:41.969Z,1532968721.969 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:39:00.620Z,1532968740.620 [NAL9602](INFO): Powering down
2018-07-30T16:40:10.704Z,1532968810.704 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:40:11.499Z,1532968811.499 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:42:02.709Z,1532968922.709 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:42:03.503Z,1532968923.503 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:43:42.477Z,1532969022.477 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:43:42.477Z,1532969022.477 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:43:42.478Z,1532969022.478 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:43:42.478Z,1532969022.478 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:43:42.836Z,1532969022.836 [Default:CheckIn:D] Stopped
2018-07-30T16:43:42.836Z,1532969022.836 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:43:43.214Z,1532969023.214 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.133789 min 
2018-07-30T16:43:43.215Z,1532969023.215 [Default:CheckIn:E] Stopped
2018-07-30T16:43:43.215Z,1532969023.215 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:43:43.215Z,1532969023.215 [Default:CheckIn] Stopped
2018-07-30T16:43:43.215Z,1532969023.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:43:43.216Z,1532969023.216 [Default:CheckIn](INFO): Running loop #7
2018-07-30T16:43:43.216Z,1532969023.216 [Default:CheckIn] Running Loop=7
2018-07-30T16:43:43.216Z,1532969023.216 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:43:43.216Z,1532969023.216 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:43:47.041Z,1532969027.041 [NAL9602](INFO): Powering up
2018-07-30T16:43:54.700Z,1532969034.700 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:43:55.496Z,1532969035.496 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:43:57.936Z,1532969037.936 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:44:14.742Z,1532969054.742 [NAL9602](INFO): SBD MO Status=0, MOMSN=7604, MT Status=0, MTMSN=0
2018-07-30T16:44:14.742Z,1532969054.742 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:44:33.545Z,1532969073.545 [NAL9602](INFO): GPS fix at 20180730T164432: (36.808064, -121.787649)
2018-07-30T16:44:33.615Z,1532969073.615 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:44:33.615Z,1532969073.615 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T16:44:38.240Z,1532969078.240 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180730T155143/Courier0022.lzma
2018-07-30T16:44:39.026Z,1532969079.026 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0022.lzma.bak
2018-07-30T16:44:39.026Z,1532969079.026 [DataOverHttps](INFO): SBD MOMSN=8385531
2018-07-30T16:44:48.929Z,1532969088.929 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180730T155143/Express0023.lzma
2018-07-30T16:44:49.726Z,1532969089.726 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Express0023.lzma.bak
2018-07-30T16:44:49.726Z,1532969089.726 [DataOverHttps](INFO): SBD MOMSN=8385534
2018-07-30T16:44:50.302Z,1532969090.302 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T16:44:50.302Z,1532969090.302 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T16:44:50.302Z,1532969090.302 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:45:08.264Z,1532969108.264 [NAL9602](INFO): Powering down
2018-07-30T16:45:40.979Z,1532969140.979 [CommandLine](IMPORTANT): got command show variable numSat
2018-07-30T16:45:41.112Z,1532969141.112 [CommandLine](IMPORTANT): NAL9602.numSatellites (count)
2018-07-30T16:45:46.708Z,1532969146.708 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:45:47.350Z,1532969147.350 [CommandLine](IMPORTANT): got command get NAL9602.numSatellites
2018-07-30T16:45:47.351Z,1532969147.351 [CommandLine](IMPORTANT): NAL9602.numSatellites 0 count
2018-07-30T16:45:47.504Z,1532969147.504 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:47:19.750Z,1532969239.750 [CommandLine](IMPORTANT): got command show variable gga
2018-07-30T16:47:19.891Z,1532969239.891 [CommandLine](IMPORTANT): NAL9602.requestGGA (bool)
2018-07-30T16:47:25.665Z,1532969245.665 [CommandLine](IMPORTANT): got command get NAL9602.requestGGA
2018-07-30T16:47:25.665Z,1532969245.665 [CommandLine](IMPORTANT): NAL9602.requestGGA 0 bool
2018-07-30T16:47:38.696Z,1532969258.696 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:47:39.492Z,1532969259.492 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:48:14.839Z,1532969294.839 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-07-30T16:48:14.839Z,1532969294.839 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-07-30T16:48:14.839Z,1532969294.839 [Rowe_600LCM](ERROR): Communications Fault
2018-07-30T16:48:15.057Z,1532969295.057 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-07-30T16:48:15.243Z,1532969295.243 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:48:15.343Z,1532969295.343 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:48:16.670Z,1532969296.670 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-07-30T16:48:16.670Z,1532969296.670 [Rowe_600LCM] No Fault, FailCount= 2
2018-07-30T16:48:16.959Z,1532969296.959 [Rowe_600LCM](INFO): Initializing
2018-07-30T16:48:16.959Z,1532969296.959 [Rowe_600LCM](INFO): Checking LCM
2018-07-30T16:48:16.960Z,1532969296.960 [Rowe_600LCM](INFO): LCM OK
2018-07-30T16:48:16.960Z,1532969296.960 [Rowe_600LCM](INFO): Powering up
2018-07-30T16:48:21.213Z,1532969301.213 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-07-30T16:48:21.277Z,1532969301.277 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-07-30T16:48:21.278Z,1532969301.278 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-07-30T16:48:21.279Z,1532969301.279 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-07-30T16:48:21.280Z,1532969301.280 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-07-30T16:48:56.181Z,1532969336.181 [DataOverHttps](IMPORTANT): SBD MTMSN=20180730T164855
2018-07-30T16:49:00.664Z,1532969340.664 [DataOverHttps](INFO): Received command:configSet NAL9602.requestGGA 1 bool persist
2018-07-30T16:49:00.885Z,1532969340.885 [CommandLine](IMPORTANT): got command configSet NAL9602.requestGGA 1.000000 bool persist
2018-07-30T16:49:32.708Z,1532969372.708 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:49:33.504Z,1532969373.504 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-07-30T16:49:50.705Z,1532969390.705 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T16:49:50.705Z,1532969390.705 [Default:CheckIn:C.Wait] Stopped
2018-07-30T16:49:50.705Z,1532969390.705 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:49:50.705Z,1532969390.705 [Default:CheckIn:D] Running Loop=1
2018-07-30T16:49:51.098Z,1532969391.098 [Default:CheckIn:D] Stopped
2018-07-30T16:49:51.098Z,1532969391.098 [Default:CheckIn:E] Running Loop=1
2018-07-30T16:49:51.499Z,1532969391.499 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.271497 min 
2018-07-30T16:49:51.499Z,1532969391.499 [Default:CheckIn:E] Stopped
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn] Stopped
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn](INFO): Running loop #8
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn] Running Loop=8
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T16:49:51.500Z,1532969391.500 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T16:49:52.244Z,1532969392.244 [NAL9602](INFO): Powering up
2018-07-30T16:50:01.562Z,1532969401.562 [DataOverHttps](IMPORTANT): SBD MTMSN=20180730T165001
2018-07-30T16:50:06.057Z,1532969406.057 [DataOverHttps](INFO): Received command:load Science/sci2.xml;set sci2.NeedCommsTime 20 minute;set sci2.Lat1 36.797 degree;set sci2.Lon1 -121.847 degree;set sci2.Lat2 36.75 degree;set sci2.Lon2 -122.022 degree;set sci2.YoYoMaxDepth 20 meter;run 
2018-07-30T16:50:06.090Z,1532969406.090 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2018-07-30T16:50:06.090Z,1532969406.090 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2018-07-30T16:50:06.276Z,1532969406.276 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2018-07-30T16:50:06.279Z,1532969406.279 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2018-07-30T16:50:06.282Z,1532969406.282 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2018-07-30T16:50:06.285Z,1532969406.285 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2018-07-30T16:50:06.288Z,1532969406.288 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2018-07-30T16:50:06.291Z,1532969406.291 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2018-07-30T16:50:06.294Z,1532969406.294 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2018-07-30T16:50:06.328Z,1532969406.328 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2018-07-30T16:50:06.331Z,1532969406.331 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2018-07-30T16:50:06.334Z,1532969406.334 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2018-07-30T16:50:06.348Z,1532969406.348 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2018-07-30T16:50:06.364Z,1532969406.364 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2018-07-30T16:50:06.367Z,1532969406.367 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2018-07-30T16:50:06.370Z,1532969406.370 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2018-07-30T16:50:06.373Z,1532969406.373 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2018-07-30T16:50:06.396Z,1532969406.396 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2018-07-30T16:50:06.399Z,1532969406.399 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2018-07-30T16:50:06.401Z,1532969406.401 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2018-07-30T16:50:06.404Z,1532969406.404 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2018-07-30T16:50:06.410Z,1532969406.410 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2018-07-30T16:50:06.413Z,1532969406.413 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2018-07-30T16:50:06.416Z,1532969406.416 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2018-07-30T16:50:06.439Z,1532969406.439 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2018-07-30T16:50:06.447Z,1532969406.447 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 132.000001 cc
2018-07-30T16:50:06.452Z,1532969406.452 [MissionManager](INFO): DefineArg sci2.MassDefault = 0.530000 cm
2018-07-30T16:50:06.463Z,1532969406.463 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2018-07-30T16:50:06.466Z,1532969406.466 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2018-07-30T16:50:06.469Z,1532969406.469 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2018-07-30T16:50:06.470Z,1532969406.470 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-07-30T16:50:06.785Z,1532969406.785 [MissionManager](INFO): DefineArg sci2:Science.SampleISUS = 0 bool
2018-07-30T16:50:06.788Z,1532969406.788 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-07-30T16:50:06.791Z,1532969406.791 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-07-30T16:50:06.793Z,1532969406.793 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2018-07-30T16:50:06.808Z,1532969406.808 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2018-07-30T16:50:06.811Z,1532969406.811 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2018-07-30T16:50:06.814Z,1532969406.814 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2018-07-30T16:50:06.817Z,1532969406.817 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2018-07-30T16:50:06.832Z,1532969406.832 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2018-07-30T16:50:06.834Z,1532969406.834 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2018-07-30T16:50:06.853Z,1532969406.853 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-07-30T16:50:06.858Z,1532969406.858 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 1.000000 bool
2018-07-30T16:50:06.861Z,1532969406.861 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 1.000000 bool
2018-07-30T16:50:06.865Z,1532969406.865 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 1.000000 bool
2018-07-30T16:50:06.878Z,1532969406.878 [MissionManager](INFO): DefineArg sci2:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-07-30T16:50:06.893Z,1532969406.893 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-07-30T16:50:06.896Z,1532969406.896 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-07-30T16:50:06.911Z,1532969406.911 [MissionManager](INFO): DefineArg sci2:Science.EnabledISUS = 0.000000 bool
2018-07-30T16:50:06.914Z,1532969406.914 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2018-07-30T16:50:06.917Z,1532969406.917 [MissionManager](INFO): DefineArg sci2:Science.PeakChlShallowBound = nan m
2018-07-30T16:50:06.942Z,1532969406.942 [MissionManager](INFO): DefineArg sci2:Science.PeakChlDeepBound = nan m
2018-07-30T16:50:06.945Z,1532969406.945 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2018-07-30T16:50:06.948Z,1532969406.948 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2018-07-30T16:50:06.963Z,1532969406.963 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2018-07-30T16:50:06.966Z,1532969406.966 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2018-07-30T16:50:06.969Z,1532969406.969 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2018-07-30T16:50:06.991Z,1532969406.991 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2018-07-30T16:50:06.994Z,1532969406.994 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2018-07-30T16:50:06.997Z,1532969406.997 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2018-07-30T16:50:07.011Z,1532969407.011 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2018-07-30T16:50:07.015Z,1532969407.015 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2018-07-30T16:50:07.018Z,1532969407.018 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2018-07-30T16:50:07.043Z,1532969407.043 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2018-07-30T16:50:07.046Z,1532969407.046 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2018-07-30T16:50:07.054Z,1532969407.054 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m
2018-07-30T16:50:07.061Z,1532969407.061 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2018-07-30T16:50:07.068Z,1532969407.068 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2018-07-30T16:50:07.072Z,1532969407.072 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2018-07-30T16:50:07.083Z,1532969407.083 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2018-07-30T16:50:07.118Z,1532969407.118 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-30T16:50:07.142Z,1532969407.142 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-07-30T16:50:07.175Z,1532969407.175 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-30T16:50:07.188Z,1532969407.188 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-30T16:50:07.213Z,1532969407.213 [MissionManager](INFO): RedefineArg sci2:Science.SampleISUS = value:1 bool
2018-07-30T16:50:07.215Z,1532969407.215 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2018-07-30T16:50:07.216Z,1532969407.216 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-07-30T16:50:07.309Z,1532969407.309 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2018-07-30T16:50:07.312Z,1532969407.312 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2018-07-30T16:50:07.315Z,1532969407.315 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-07-30T16:50:07.318Z,1532969407.318 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2018-07-30T16:50:07.343Z,1532969407.343 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-07-30T16:50:07.346Z,1532969407.346 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2018-07-30T16:50:07.349Z,1532969407.349 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2018-07-30T16:50:07.364Z,1532969407.364 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-30T16:50:07.425Z,1532969407.425 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-07-30T16:50:07.457Z,1532969407.457 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2018-07-30T16:50:07.460Z,1532969407.460 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2018-07-30T16:50:07.475Z,1532969407.475 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-07-30T16:50:07.477Z,1532969407.477 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-07-30T16:50:07.484Z,1532969407.484 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-07-30T16:50:07.505Z,1532969407.505 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-07-30T16:50:07.539Z,1532969407.539 [sci2:I.Pitch](DEBUG): Construct.
2018-07-30T16:50:07.545Z,1532969407.545 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-07-30T16:50:07.548Z,1532969407.548 [sci2:K.SetSpeed](DEBUG): Construct.
2018-07-30T16:50:07.551Z,1532969407.551 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-07-30T16:50:07.583Z,1532969407.583 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-07-30T16:50:07.602Z,1532969407.602 [sci2:N.YoYo](DEBUG): Construct YoYo.
2018-07-30T16:50:07.635Z,1532969407.635 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.642Z,1532969407.642 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.670Z,1532969407.670 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.690Z,1532969407.690 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.696Z,1532969407.696 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.715Z,1532969407.715 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.721Z,1532969407.721 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T16:50:07.800Z,1532969407.800 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>
    <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>

    <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>

    <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>

    <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="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>
    <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>

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

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

    <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>

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

    <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>

2018-07-30T16:50:07.800Z,1532969407.800 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2018-07-30T16:50:14.824Z,1532969414.824 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 20.000000 minute
2018-07-30T16:50:14.825Z,1532969414.825 [CommandLine](IMPORTANT): got command set sci2.Lat1 36.797001 degree
2018-07-30T16:50:14.826Z,1532969414.826 [CommandLine](IMPORTANT): got command set sci2.Lon1 -121.847000 degree
2018-07-30T16:50:14.827Z,1532969414.827 [CommandLine](IMPORTANT): got command set sci2.Lat2 36.750000 degree
2018-07-30T16:50:14.828Z,1532969414.828 [CommandLine](IMPORTANT): got command set sci2.Lon2 -122.022003 degree
2018-07-30T16:50:14.828Z,1532969414.828 [CommandLine](IMPORTANT): got command set sci2.YoYoMaxDepth 20.000000 meter
2018-07-30T16:50:14.829Z,1532969414.829 [CommandLine](IMPORTANT): got command run 
2018-07-30T16:50:14.832Z,1532969414.832 [CommandLine](IMPORTANT): Running 
2018-07-30T16:50:14.836Z,1532969414.836 [NAL9602](INFO): NAL9602 initialized
2018-07-30T16:50:14.946Z,1532969414.946 [Default] Stopped
2018-07-30T16:50:14.946Z,1532969414.946 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-30T16:50:14.946Z,1532969414.946 [Default:B.GoToSurface] Stopped
2018-07-30T16:50:14.946Z,1532969414.946 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T16:50:14.946Z,1532969414.946 [Default:CheckIn] Stopped
2018-07-30T16:50:14.947Z,1532969414.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T16:50:14.947Z,1532969414.947 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T16:50:14.947Z,1532969414.947 [MissionManager](IMPORTANT): Started mission sci2
2018-07-30T16:50:14.947Z,1532969414.947 [sci2] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2](DEBUG): Aggregate::initialize sci2
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:A] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:B] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:C] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:D] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:E] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:F] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:G] Running Loop=1
2018-07-30T16:50:14.948Z,1532969414.948 [sci2:Science:Read_Oil] Running Loop=1
2018-07-30T16:50:14.949Z,1532969414.949 [sci2:Science:PeakDetectChl] Running Loop=1
2018-07-30T16:50:14.949Z,1532969414.949 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2018-07-30T16:50:14.949Z,1532969414.949 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-07-30T16:50:14.949Z,1532969414.949 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-30T16:50:14.955Z,1532969414.955 [sci2:Science:HighestChlPeakReport] Running Loop=1
2018-07-30T16:50:14.955Z,1532969414.955 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2018-07-30T16:50:14.955Z,1532969414.955 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-07-30T16:50:14.956Z,1532969414.956 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-07-30T16:50:14.956Z,1532969414.956 [sci2:Science:PeakDetectNO3] Running Loop=1
2018-07-30T16:50:14.956Z,1532969414.956 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2018-07-30T16:50:14.956Z,1532969414.956 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-07-30T16:50:14.956Z,1532969414.956 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-30T16:50:14.961Z,1532969414.961 [sci2:Science:PeakDetectOil] Running Loop=1
2018-07-30T16:50:14.961Z,1532969414.961 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2018-07-30T16:50:14.961Z,1532969414.961 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-07-30T16:50:14.961Z,1532969414.961 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-30T16:50:14.963Z,1532969414.963 [sci2:StandardEnvelopes] Running Loop=1
2018-07-30T16:50:14.963Z,1532969414.963 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2018-07-30T16:50:14.963Z,1532969414.963 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-07-30T16:50:14.963Z,1532969414.963 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-07-30T16:50:14.964Z,1532969414.964 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-07-30T16:50:14.964Z,1532969414.964 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-07-30T16:50:14.964Z,1532969414.964 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-07-30T16:50:14.964Z,1532969414.964 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-07-30T16:50:14.964Z,1532969414.964 [sci2:I.Pitch] Running Loop=1
2018-07-30T16:50:14.964Z,1532969414.964 [sci2:I.Pitch](DEBUG): Initialize.
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:J.Buoyancy] Running Loop=1
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:K.SetSpeed] Running Loop=1
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:K.SetSpeed](DEBUG): Initialize.
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:L.DepthEnvelope] Running Loop=1
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-07-30T16:50:14.965Z,1532969414.965 [sci2:M.AltitudeEnvelope] Running Loop=1
2018-07-30T16:50:14.966Z,1532969414.966 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-07-30T16:50:14.966Z,1532969414.966 [sci2:N.YoYo] Running Loop=1
2018-07-30T16:50:14.966Z,1532969414.966 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-07-30T16:50:14.966Z,1532969414.966 [sci2:C] Running Loop=1
2018-07-30T16:50:14.971Z,1532969414.971 [sci2:N.YoYo] Running Loop=1
2018-07-30T16:50:14.981Z,1532969414.981 [sci2:M.AltitudeEnvelope] Running Loop=1
2018-07-30T16:50:14.997Z,1532969414.997 [sci2:L.DepthEnvelope] Running Loop=1
2018-07-30T16:50:15.015Z,1532969415.015 [sci2:K.SetSpeed] Running Loop=1
2018-07-30T16:50:15.020Z,1532969415.020 [sci2:J.Buoyancy] Running Loop=1
2018-07-30T16:50:15.030Z,1532969415.030 [sci2:I.Pitch] Running Loop=1
2018-07-30T16:50:15.047Z,1532969415.047 [sci2:StandardEnvelopes] Running Loop=1
2018-07-30T16:50:15.047Z,1532969415.047 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-07-30T16:50:15.081Z,1532969415.081 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-07-30T16:50:15.086Z,1532969415.086 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-07-30T16:50:15.093Z,1532969415.093 [sci2:C] Stopped
2018-07-30T16:50:15.094Z,1532969415.094 [sci2:E] Running Loop=1
2018-07-30T16:50:15.094Z,1532969415.094 [sci2:Science] Running Loop=1
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectOil] Stopped
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectNO3] Stopped
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-07-30T16:50:15.110Z,1532969415.110 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-30T16:50:15.111Z,1532969415.111 [sci2:Science:HighestChlPeakReport] Stopped
2018-07-30T16:50:15.111Z,1532969415.111 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2018-07-30T16:50:15.111Z,1532969415.111 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-07-30T16:50:15.111Z,1532969415.111 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-07-30T16:50:15.111Z,1532969415.111 [sci2:Science:PeakDetectChl] Running Loop=1
2018-07-30T16:50:15.132Z,1532969415.132 [sci2:Science:Read_Oil] Running Loop=1
2018-07-30T16:50:15.133Z,1532969415.133 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2018-07-30T16:50:15.135Z,1532969415.135 [sci2:Science:G] Running Loop=1
2018-07-30T16:50:15.136Z,1532969415.136 [sci2:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-07-30T16:50:15.137Z,1532969415.137 [sci2:Science:F] Stopped
2018-07-30T16:50:15.137Z,1532969415.137 [sci2:Science:E] Stopped
2018-07-30T16:50:15.137Z,1532969415.137 [sci2:Science:D] Running Loop=1
2018-07-30T16:50:15.138Z,1532969415.138 [sci2:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2018-07-30T16:50:15.139Z,1532969415.139 [sci2:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2018-07-30T16:50:15.140Z,1532969415.140 [sci2:Science:C] Running Loop=1
2018-07-30T16:50:15.141Z,1532969415.141 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-07-30T16:50:15.142Z,1532969415.142 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-07-30T16:50:15.144Z,1532969415.144 [sci2:Science:B] Stopped
2018-07-30T16:50:15.144Z,1532969415.144 [sci2:Science:A] Running Loop=1
2018-07-30T16:50:15.145Z,1532969415.145 [sci2:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-07-30T16:50:15.177Z,1532969415.177 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2018-07-30T16:50:15.177Z,1532969415.177 [WetLabsBB2FL](INFO): Powering up
2018-07-30T16:50:15.268Z,1532969415.268 [sci2:E] Stopped
2018-07-30T16:50:15.268Z,1532969415.268 [sci2:F] Running Loop=1
2018-07-30T16:50:15.363Z,1532969415.363 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-07-30T16:50:15.371Z,1532969415.371 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-07-30T16:50:15.520Z,1532969415.520 [CTD_Seabird](INFO): Initializing
2018-07-30T16:50:15.521Z,1532969415.521 [CTD_Seabird](INFO): Checking LCM
2018-07-30T16:50:15.521Z,1532969415.521 [CTD_Seabird](INFO): LCM OK
2018-07-30T16:50:15.521Z,1532969415.521 [CTD_Seabird](INFO): Powering up
2018-07-30T16:50:15.698Z,1532969415.698 [sci2:F] Stopped
2018-07-30T16:50:15.698Z,1532969415.698 [sci2:G] Running Loop=1
2018-07-30T16:50:15.815Z,1532969415.815 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:16.055Z,1532969416.055 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-07-30T16:50:16.055Z,1532969416.055 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-07-30T16:50:16.055Z,1532969416.055 [ThrusterServo] Communications Fault, FailCount= 1
2018-07-30T16:50:16.055Z,1532969416.055 [ThrusterServo](ERROR): Communications Fault
2018-07-30T16:50:16.059Z,1532969416.059 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-07-30T16:50:16.179Z,1532969416.179 [sci2:G] Stopped
2018-07-30T16:50:16.179Z,1532969416.179 [sci2:StartingMission] Running Loop=1
2018-07-30T16:50:16.179Z,1532969416.179 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2018-07-30T16:50:16.227Z,1532969416.227 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:16.429Z,1532969416.429 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-30T16:50:16.430Z,1532969416.430 [ThrusterServo](INFO): Powering down
2018-07-30T16:50:16.635Z,1532969416.635 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:16.691Z,1532969416.691 [sci2:NeedComms] Running Loop=1
2018-07-30T16:50:16.691Z,1532969416.691 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2018-07-30T16:50:16.691Z,1532969416.691 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2018-07-30T16:50:16.692Z,1532969416.692 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T16:50:16.692Z,1532969416.692 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-07-30T16:50:16.692Z,1532969416.692 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-07-30T16:50:16.693Z,1532969416.693 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-07-30T16:50:16.693Z,1532969416.693 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T16:50:16.694Z,1532969416.694 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T16:50:16.694Z,1532969416.694 [sci2:NeedComms:A] Running Loop=1
2018-07-30T16:50:16.726Z,1532969416.726 [sci2:NeedComms:A](INFO): last time_fix was: 1532969072.000000 second since 1970/01/01T00:00:00Z 
2018-07-30T16:50:16.744Z,1532969416.744 [sci2:NeedComms:A] Stopped
2018-07-30T16:50:16.841Z,1532969416.841 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079500 psu
2018-07-30T16:50:16.859Z,1532969416.859 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:17.040Z,1532969417.040 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082500 psu
2018-07-30T16:50:17.044Z,1532969417.044 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:17.107Z,1532969417.107 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:17.256Z,1532969417.256 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078400 psu
2018-07-30T16:50:17.293Z,1532969417.293 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:17.451Z,1532969417.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.068100 psu
2018-07-30T16:50:17.456Z,1532969417.456 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:17.522Z,1532969417.522 [sci2:NeedComms:C] Running Loop=1
2018-07-30T16:50:17.651Z,1532969417.651 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082400 psu
2018-07-30T16:50:17.665Z,1532969417.665 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:17.844Z,1532969417.844 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-07-30T16:50:17.844Z,1532969417.844 [ThrusterServo] No Fault, FailCount= 1
2018-07-30T16:50:17.861Z,1532969417.861 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085800 psu
2018-07-30T16:50:17.878Z,1532969417.878 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:17.979Z,1532969417.979 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-30T16:50:18.053Z,1532969418.053 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-30T16:50:18.054Z,1532969418.054 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-30T16:50:18.063Z,1532969418.063 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069900 psu
2018-07-30T16:50:18.068Z,1532969418.068 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:18.268Z,1532969418.268 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081000 psu
2018-07-30T16:50:18.304Z,1532969418.304 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:18.464Z,1532969418.464 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078800 psu
2018-07-30T16:50:18.470Z,1532969418.470 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:18.668Z,1532969418.668 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069100 psu
2018-07-30T16:50:18.673Z,1532969418.673 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:18.869Z,1532969418.869 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087900 psu
2018-07-30T16:50:18.878Z,1532969418.878 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:18.968Z,1532969418.968 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-07-30T16:50:19.035Z,1532969419.035 [ThrusterServo](FAULT): Thruster failed to initialize
2018-07-30T16:50:19.035Z,1532969419.035 [ThrusterServo] Communications Fault, FailCount= 2
2018-07-30T16:50:19.035Z,1532969419.035 [ThrusterServo](ERROR): Communications Fault
2018-07-30T16:50:19.041Z,1532969419.041 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-07-30T16:50:19.072Z,1532969419.072 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083700 psu
2018-07-30T16:50:19.076Z,1532969419.076 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:19.272Z,1532969419.272 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-30T16:50:19.272Z,1532969419.272 [ThrusterServo](INFO): Powering down
2018-07-30T16:50:19.276Z,1532969419.276 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083200 psu
2018-07-30T16:50:19.281Z,1532969419.281 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:19.343Z,1532969419.343 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:19.489Z,1532969419.489 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077600 psu
2018-07-30T16:50:19.516Z,1532969419.516 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:19.688Z,1532969419.688 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084900 psu
2018-07-30T16:50:19.700Z,1532969419.700 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:19.903Z,1532969419.903 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082800 psu
2018-07-30T16:50:19.910Z,1532969419.910 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:20.095Z,1532969420.095 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073700 psu
2018-07-30T16:50:20.101Z,1532969420.101 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:20.313Z,1532969420.313 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081800 psu
2018-07-30T16:50:20.320Z,1532969420.320 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:20.464Z,1532969420.464 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-07-30T16:50:20.464Z,1532969420.464 [ThrusterServo] No Fault, FailCount= 2
2018-07-30T16:50:20.508Z,1532969420.508 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081500 psu
2018-07-30T16:50:20.539Z,1532969420.539 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:20.708Z,1532969420.708 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080500 psu
2018-07-30T16:50:20.717Z,1532969420.717 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:20.848Z,1532969420.848 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-30T16:50:20.855Z,1532969420.855 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-30T16:50:20.903Z,1532969420.903 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075400 psu
2018-07-30T16:50:20.909Z,1532969420.909 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:21.114Z,1532969421.114 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-07-30T16:50:21.115Z,1532969421.115 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081900 psu
2018-07-30T16:50:21.121Z,1532969421.121 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:21.258Z,1532969421.258 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-07-30T16:50:21.259Z,1532969421.259 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-07-30T16:50:21.309Z,1532969421.309 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081300 psu
2018-07-30T16:50:21.314Z,1532969421.314 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:21.520Z,1532969421.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078900 psu
2018-07-30T16:50:21.524Z,1532969421.524 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:21.716Z,1532969421.716 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082600 psu
2018-07-30T16:50:21.723Z,1532969421.723 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:21.924Z,1532969421.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071300 psu
2018-07-30T16:50:21.941Z,1532969421.941 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:22.120Z,1532969422.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077600 psu
2018-07-30T16:50:22.125Z,1532969422.125 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:22.187Z,1532969422.187 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:22.328Z,1532969422.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081400 psu
2018-07-30T16:50:22.333Z,1532969422.333 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:22.536Z,1532969422.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071900 psu
2018-07-30T16:50:22.541Z,1532969422.541 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:22.740Z,1532969422.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080300 psu
2018-07-30T16:50:22.753Z,1532969422.753 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:22.942Z,1532969422.942 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085800 psu
2018-07-30T16:50:22.946Z,1532969422.946 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:23.148Z,1532969423.148 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077000 psu
2018-07-30T16:50:23.153Z,1532969423.153 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:23.154Z,1532969423.154 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		215.617		80.389		22.916		30.333		30.333		39.063		8.730		462.0		648.3		2.3	



2018-07-30T16:50:23.154Z,1532969423.154 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T16:50:23.440Z,1532969423.440 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071000 psu
2018-07-30T16:50:23.465Z,1532969423.465 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:23.651Z,1532969423.651 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083900 psu
2018-07-30T16:50:23.656Z,1532969423.656 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:23.856Z,1532969423.856 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079600 psu
2018-07-30T16:50:23.924Z,1532969423.924 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:24.060Z,1532969424.060 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078600 psu
2018-07-30T16:50:24.067Z,1532969424.067 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:24.092Z,1532969424.092 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		215.710		80.423		22.916		30.328		30.328		39.060		8.732		461.9		647.6		2.3	



2018-07-30T16:50:24.092Z,1532969424.092 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T16:50:24.253Z,1532969424.253 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077300 psu
2018-07-30T16:50:24.258Z,1532969424.258 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:24.456Z,1532969424.456 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079400 psu
2018-07-30T16:50:24.470Z,1532969424.470 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:24.660Z,1532969424.660 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079300 psu
2018-07-30T16:50:24.665Z,1532969424.665 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:24.860Z,1532969424.860 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080000 psu
2018-07-30T16:50:24.871Z,1532969424.871 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:24.943Z,1532969424.943 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:25.064Z,1532969425.064 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073900 psu
2018-07-30T16:50:25.069Z,1532969425.069 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:25.209Z,1532969425.209 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		215.432		80.319		22.915		30.343		30.343		39.073		8.730		461.9		646.9		2.3	

2018-07-30T16:50:25.210Z,1532969425.210 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T16:50:25.275Z,1532969425.275 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080200 psu
2018-07-30T16:50:25.278Z,1532969425.278 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:25.473Z,1532969425.473 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076300 psu
2018-07-30T16:50:25.478Z,1532969425.478 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:25.680Z,1532969425.680 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079700 psu
2018-07-30T16:50:25.689Z,1532969425.689 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:25.880Z,1532969425.880 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074200 psu
2018-07-30T16:50:25.888Z,1532969425.888 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:26.084Z,1532969426.084 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074000 psu
2018-07-30T16:50:26.092Z,1532969426.092 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:26.287Z,1532969426.287 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076400 psu
2018-07-30T16:50:26.294Z,1532969426.294 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:26.488Z,1532969426.488 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080900 psu
2018-07-30T16:50:26.493Z,1532969426.493 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:26.700Z,1532969426.700 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080900 psu
2018-07-30T16:50:26.706Z,1532969426.706 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:26.896Z,1532969426.896 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087900 psu
2018-07-30T16:50:26.902Z,1532969426.902 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:27.100Z,1532969427.100 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084000 psu
2018-07-30T16:50:27.104Z,1532969427.104 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:27.227Z,1532969427.227 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		215.639		80.396		22.915		30.332		30.332		39.066		8.734		461.9		645.7		2.3	

2018-07-30T16:50:27.227Z,1532969427.227 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T16:50:27.300Z,1532969427.300 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078000 psu
2018-07-30T16:50:27.305Z,1532969427.305 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:27.511Z,1532969427.511 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073700 psu
2018-07-30T16:50:27.516Z,1532969427.516 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:27.709Z,1532969427.709 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079800 psu
2018-07-30T16:50:27.715Z,1532969427.715 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:27.908Z,1532969427.908 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080500 psu
2018-07-30T16:50:27.913Z,1532969427.913 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:27.979Z,1532969427.979 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:28.120Z,1532969428.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077300 psu
2018-07-30T16:50:28.133Z,1532969428.133 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:28.320Z,1532969428.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078500 psu
2018-07-30T16:50:28.327Z,1532969428.327 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:28.520Z,1532969428.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083200 psu
2018-07-30T16:50:28.525Z,1532969428.525 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:28.724Z,1532969428.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076800 psu
2018-07-30T16:50:28.728Z,1532969428.728 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:28.932Z,1532969428.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079100 psu
2018-07-30T16:50:28.939Z,1532969428.939 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:29.132Z,1532969429.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074900 psu
2018-07-30T16:50:29.137Z,1532969429.137 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:29.332Z,1532969429.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072500 psu
2018-07-30T16:50:29.336Z,1532969429.336 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:29.543Z,1532969429.543 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079400 psu
2018-07-30T16:50:29.548Z,1532969429.548 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:29.736Z,1532969429.736 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081600 psu
2018-07-30T16:50:29.740Z,1532969429.740 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:29.803Z,1532969429.803 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:30.045Z,1532969430.045 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084200 psu
2018-07-30T16:50:30.050Z,1532969430.050 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:30.247Z,1532969430.247 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087100 psu
2018-07-30T16:50:30.252Z,1532969430.252 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:30.456Z,1532969430.456 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.065700 psu
2018-07-30T16:50:30.467Z,1532969430.467 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:30.652Z,1532969430.652 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082900 psu
2018-07-30T16:50:30.657Z,1532969430.657 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:30.863Z,1532969430.863 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084300 psu
2018-07-30T16:50:30.871Z,1532969430.871 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:31.056Z,1532969431.056 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075200 psu
2018-07-30T16:50:31.060Z,1532969431.060 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:31.268Z,1532969431.268 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082200 psu
2018-07-30T16:50:31.292Z,1532969431.292 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:31.464Z,1532969431.464 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084900 psu
2018-07-30T16:50:31.468Z,1532969431.468 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:31.672Z,1532969431.672 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077900 psu
2018-07-30T16:50:31.691Z,1532969431.691 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:31.868Z,1532969431.868 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079000 psu
2018-07-30T16:50:31.872Z,1532969431.872 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:32.075Z,1532969432.075 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082500 psu
2018-07-30T16:50:32.081Z,1532969432.081 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:32.276Z,1532969432.276 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.068800 psu
2018-07-30T16:50:32.280Z,1532969432.280 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:32.476Z,1532969432.476 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087700 psu
2018-07-30T16:50:32.488Z,1532969432.488 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:32.559Z,1532969432.559 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:32.684Z,1532969432.684 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076200 psu
2018-07-30T16:50:32.690Z,1532969432.690 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:32.884Z,1532969432.884 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079100 psu
2018-07-30T16:50:32.889Z,1532969432.889 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:33.099Z,1532969433.099 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072500 psu
2018-07-30T16:50:33.104Z,1532969433.104 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:33.299Z,1532969433.299 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079300 psu
2018-07-30T16:50:33.304Z,1532969433.304 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:33.499Z,1532969433.499 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086500 psu
2018-07-30T16:50:33.504Z,1532969433.504 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:33.696Z,1532969433.696 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079100 psu
2018-07-30T16:50:33.703Z,1532969433.703 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:33.908Z,1532969433.908 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071500 psu
2018-07-30T16:50:33.921Z,1532969433.921 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:34.112Z,1532969434.112 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085800 psu
2018-07-30T16:50:34.116Z,1532969434.116 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:34.311Z,1532969434.311 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080200 psu
2018-07-30T16:50:34.320Z,1532969434.320 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:34.516Z,1532969434.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081200 psu
2018-07-30T16:50:34.520Z,1532969434.520 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:34.719Z,1532969434.719 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077700 psu
2018-07-30T16:50:34.724Z,1532969434.724 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:34.915Z,1532969434.915 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077400 psu
2018-07-30T16:50:34.921Z,1532969434.921 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:35.127Z,1532969435.127 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071100 psu
2018-07-30T16:50:35.132Z,1532969435.132 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:35.320Z,1532969435.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072700 psu
2018-07-30T16:50:35.324Z,1532969435.324 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:35.387Z,1532969435.387 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:35.531Z,1532969435.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076600 psu
2018-07-30T16:50:35.536Z,1532969435.536 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:35.731Z,1532969435.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083400 psu
2018-07-30T16:50:35.736Z,1532969435.736 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:35.940Z,1532969435.940 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076600 psu
2018-07-30T16:50:35.948Z,1532969435.948 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:36.136Z,1532969436.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077800 psu
2018-07-30T16:50:36.140Z,1532969436.140 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:36.336Z,1532969436.336 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077000 psu
2018-07-30T16:50:36.341Z,1532969436.341 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:36.404Z,1532969436.404 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:36.647Z,1532969436.647 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080900 psu
2018-07-30T16:50:36.652Z,1532969436.652 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:36.856Z,1532969436.856 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084900 psu
2018-07-30T16:50:36.872Z,1532969436.872 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:37.052Z,1532969437.052 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078200 psu
2018-07-30T16:50:37.057Z,1532969437.057 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:37.248Z,1532969437.248 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078900 psu
2018-07-30T16:50:37.257Z,1532969437.257 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:37.323Z,1532969437.323 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:37.373Z,1532969437.373 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009646 psu
2018-07-30T16:50:37.374Z,1532969437.374 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:37.462Z,1532969437.462 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082700 psu
2018-07-30T16:50:37.468Z,1532969437.468 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:37.657Z,1532969437.657 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076400 psu
2018-07-30T16:50:37.664Z,1532969437.664 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:37.864Z,1532969437.864 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083900 psu
2018-07-30T16:50:37.876Z,1532969437.876 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:38.065Z,1532969438.065 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075800 psu
2018-07-30T16:50:38.081Z,1532969438.081 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:38.264Z,1532969438.264 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080300 psu
2018-07-30T16:50:38.269Z,1532969438.269 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:38.476Z,1532969438.476 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085800 psu
2018-07-30T16:50:38.481Z,1532969438.481 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:38.676Z,1532969438.676 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072300 psu
2018-07-30T16:50:38.685Z,1532969438.685 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:38.877Z,1532969438.877 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082900 psu
2018-07-30T16:50:38.886Z,1532969438.886 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:38.895Z,1532969438.895 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009638 psu
2018-07-30T16:50:38.897Z,1532969438.897 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:39.080Z,1532969439.080 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077700 psu
2018-07-30T16:50:39.084Z,1532969439.084 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:39.284Z,1532969439.284 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079400 psu
2018-07-30T16:50:39.288Z,1532969439.288 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:39.369Z,1532969439.369 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009655 psu
2018-07-30T16:50:39.371Z,1532969439.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:39.484Z,1532969439.484 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085200 psu
2018-07-30T16:50:39.489Z,1532969439.489 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:39.688Z,1532969439.688 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073400 psu
2018-07-30T16:50:39.694Z,1532969439.694 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:39.896Z,1532969439.896 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079800 psu
2018-07-30T16:50:39.904Z,1532969439.904 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:40.100Z,1532969440.100 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075700 psu
2018-07-30T16:50:40.107Z,1532969440.107 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:40.303Z,1532969440.303 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085700 psu
2018-07-30T16:50:40.308Z,1532969440.308 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:40.500Z,1532969440.500 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084100 psu
2018-07-30T16:50:40.505Z,1532969440.505 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:40.567Z,1532969440.567 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:40.711Z,1532969440.711 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081400 psu
2018-07-30T16:50:40.718Z,1532969440.718 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:40.915Z,1532969440.915 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009651 psu
2018-07-30T16:50:40.917Z,1532969440.917 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:40.918Z,1532969440.918 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081000 psu
2018-07-30T16:50:40.921Z,1532969440.921 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:41.116Z,1532969441.116 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076800 psu
2018-07-30T16:50:41.120Z,1532969441.120 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:41.316Z,1532969441.316 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074000 psu
2018-07-30T16:50:41.321Z,1532969441.321 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:41.369Z,1532969441.369 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009626 psu
2018-07-30T16:50:41.371Z,1532969441.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:41.516Z,1532969441.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079700 psu
2018-07-30T16:50:41.520Z,1532969441.520 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:41.720Z,1532969441.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081000 psu
2018-07-30T16:50:41.725Z,1532969441.725 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:41.931Z,1532969441.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078700 psu
2018-07-30T16:50:41.936Z,1532969441.936 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:42.136Z,1532969442.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084100 psu
2018-07-30T16:50:42.143Z,1532969442.143 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:42.328Z,1532969442.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079300 psu
2018-07-30T16:50:42.333Z,1532969442.333 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:42.539Z,1532969442.539 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077600 psu
2018-07-30T16:50:42.544Z,1532969442.544 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:42.572Z,1532969442.572 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009640 psu
2018-07-30T16:50:42.574Z,1532969442.574 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:42.740Z,1532969442.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079300 psu
2018-07-30T16:50:42.744Z,1532969442.744 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:42.948Z,1532969442.948 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078900 psu
2018-07-30T16:50:42.953Z,1532969442.953 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:43.243Z,1532969443.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081000 psu
2018-07-30T16:50:43.252Z,1532969443.252 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:43.444Z,1532969443.444 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073700 psu
2018-07-30T16:50:43.455Z,1532969443.455 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:43.647Z,1532969443.647 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082800 psu
2018-07-30T16:50:43.654Z,1532969443.654 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:43.859Z,1532969443.859 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085100 psu
2018-07-30T16:50:43.864Z,1532969443.864 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:44.056Z,1532969444.056 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077400 psu
2018-07-30T16:50:44.080Z,1532969444.080 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:44.164Z,1532969444.164 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009726 psu
2018-07-30T16:50:44.166Z,1532969444.166 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:44.267Z,1532969444.267 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081100 psu
2018-07-30T16:50:44.272Z,1532969444.272 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:44.468Z,1532969444.468 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071400 psu
2018-07-30T16:50:44.483Z,1532969444.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=7605, MT Status=0, MTMSN=0
2018-07-30T16:50:44.483Z,1532969444.483 [NAL9602](INFO): No messages in MT queue
2018-07-30T16:50:44.484Z,1532969444.484 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:44.577Z,1532969444.577 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009619 psu
2018-07-30T16:50:44.579Z,1532969444.579 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:44.665Z,1532969444.665 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079900 psu
2018-07-30T16:50:44.670Z,1532969444.670 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:44.875Z,1532969444.875 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080200 psu
2018-07-30T16:50:44.889Z,1532969444.889 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:45.076Z,1532969445.076 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079500 psu
2018-07-30T16:50:45.081Z,1532969445.081 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:45.280Z,1532969445.280 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084200 psu
2018-07-30T16:50:45.287Z,1532969445.287 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:45.370Z,1532969445.370 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009675 psu
2018-07-30T16:50:45.371Z,1532969445.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:45.480Z,1532969445.480 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079800 psu
2018-07-30T16:50:45.486Z,1532969445.486 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:45.681Z,1532969445.681 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.090000 psu
2018-07-30T16:50:45.688Z,1532969445.688 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:45.690Z,1532969445.690 [NAL9602](INFO): GPS fix at 20180730T165044: (36.802327, -121.810103)
2018-07-30T16:50:45.801Z,1532969445.801 [sci2:NeedComms:C] Stopped
2018-07-30T16:50:45.801Z,1532969445.801 [sci2:NeedComms:D] Running Loop=1
2018-07-30T16:50:45.884Z,1532969445.884 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083600 psu
2018-07-30T16:50:45.888Z,1532969445.888 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:46.088Z,1532969446.088 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080400 psu
2018-07-30T16:50:46.095Z,1532969446.095 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:46.207Z,1532969446.207 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-30T16:50:46.299Z,1532969446.299 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081400 psu
2018-07-30T16:50:46.305Z,1532969446.305 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:46.500Z,1532969446.500 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075300 psu
2018-07-30T16:50:46.507Z,1532969446.507 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:46.580Z,1532969446.580 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009611 psu
2018-07-30T16:50:46.582Z,1532969446.582 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:46.703Z,1532969446.703 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083400 psu
2018-07-30T16:50:46.708Z,1532969446.708 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:46.903Z,1532969446.903 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080500 psu
2018-07-30T16:50:46.916Z,1532969446.916 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:47.112Z,1532969447.112 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080000 psu
2018-07-30T16:50:47.117Z,1532969447.117 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:47.311Z,1532969447.311 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073400 psu
2018-07-30T16:50:47.326Z,1532969447.326 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:47.374Z,1532969447.374 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009630 psu
2018-07-30T16:50:47.375Z,1532969447.375 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:47.508Z,1532969447.508 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073900 psu
2018-07-30T16:50:47.514Z,1532969447.514 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:47.579Z,1532969447.579 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:47.715Z,1532969447.715 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083600 psu
2018-07-30T16:50:47.720Z,1532969447.720 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:47.916Z,1532969447.916 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073600 psu
2018-07-30T16:50:47.923Z,1532969447.923 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:48.120Z,1532969448.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084300 psu
2018-07-30T16:50:48.125Z,1532969448.125 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:48.324Z,1532969448.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080300 psu
2018-07-30T16:50:48.328Z,1532969448.328 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:48.531Z,1532969448.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083100 psu
2018-07-30T16:50:48.536Z,1532969448.536 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:48.728Z,1532969448.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085200 psu
2018-07-30T16:50:48.732Z,1532969448.732 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:48.795Z,1532969448.795 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:48.827Z,1532969448.827 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009627 psu
2018-07-30T16:50:48.829Z,1532969448.829 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:48.933Z,1532969448.933 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082200 psu
2018-07-30T16:50:48.936Z,1532969448.936 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:49.144Z,1532969449.144 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087300 psu
2018-07-30T16:50:49.177Z,1532969449.177 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:49.370Z,1532969449.370 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009627 psu
2018-07-30T16:50:49.371Z,1532969449.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:49.440Z,1532969449.440 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078500 psu
2018-07-30T16:50:49.444Z,1532969449.444 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:49.507Z,1532969449.507 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:49.652Z,1532969449.652 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.088400 psu
2018-07-30T16:50:49.672Z,1532969449.672 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:49.856Z,1532969449.856 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.107900 psu
2018-07-30T16:50:49.881Z,1532969449.881 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:50.060Z,1532969450.060 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076800 psu
2018-07-30T16:50:50.067Z,1532969450.067 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:50.256Z,1532969450.256 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079900 psu
2018-07-30T16:50:50.260Z,1532969450.260 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:50.460Z,1532969450.460 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079000 psu
2018-07-30T16:50:50.464Z,1532969450.464 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:50.672Z,1532969450.672 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083900 psu
2018-07-30T16:50:50.679Z,1532969450.679 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:50.836Z,1532969450.836 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009605 psu
2018-07-30T16:50:50.838Z,1532969450.838 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:50.876Z,1532969450.876 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085700 psu
2018-07-30T16:50:50.880Z,1532969450.880 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:51.075Z,1532969451.075 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077500 psu
2018-07-30T16:50:51.080Z,1532969451.080 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:51.279Z,1532969451.279 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075000 psu
2018-07-30T16:50:51.284Z,1532969451.284 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:51.369Z,1532969451.369 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009601 psu
2018-07-30T16:50:51.371Z,1532969451.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:51.483Z,1532969451.483 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081200 psu
2018-07-30T16:50:51.488Z,1532969451.488 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:51.681Z,1532969451.681 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077800 psu
2018-07-30T16:50:51.684Z,1532969451.684 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:51.884Z,1532969451.884 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077200 psu
2018-07-30T16:50:51.891Z,1532969451.891 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:52.092Z,1532969452.092 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083100 psu
2018-07-30T16:50:52.096Z,1532969452.096 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:52.292Z,1532969452.292 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077100 psu
2018-07-30T16:50:52.296Z,1532969452.296 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:52.503Z,1532969452.503 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075700 psu
2018-07-30T16:50:52.508Z,1532969452.508 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:52.703Z,1532969452.703 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076400 psu
2018-07-30T16:50:52.708Z,1532969452.708 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:52.843Z,1532969452.843 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009597 psu
2018-07-30T16:50:52.845Z,1532969452.845 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:52.907Z,1532969452.907 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077300 psu
2018-07-30T16:50:52.910Z,1532969452.910 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:53.104Z,1532969453.104 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081200 psu
2018-07-30T16:50:53.109Z,1532969453.109 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:53.262Z,1532969453.262 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180730T155143/Courier0025.lzma
2018-07-30T16:50:53.308Z,1532969453.308 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081700 psu
2018-07-30T16:50:53.313Z,1532969453.313 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:53.369Z,1532969453.369 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009594 psu
2018-07-30T16:50:53.371Z,1532969453.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:53.519Z,1532969453.519 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077900 psu
2018-07-30T16:50:53.524Z,1532969453.524 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:53.720Z,1532969453.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086100 psu
2018-07-30T16:50:53.741Z,1532969453.741 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:53.929Z,1532969453.929 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078000 psu
2018-07-30T16:50:53.947Z,1532969453.947 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:54.065Z,1532969454.065 [DataOverHttps](INFO): Moved sent file to Logs/20180730T155143/Courier0025.lzma.bak
2018-07-30T16:50:54.066Z,1532969454.066 [DataOverHttps](IMPORTANT): SBD MOMSN=8385549, MTMSN=20180730T165053
2018-07-30T16:50:54.132Z,1532969454.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084300 psu
2018-07-30T16:50:54.147Z,1532969454.147 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:54.331Z,1532969454.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083400 psu
2018-07-30T16:50:54.336Z,1532969454.336 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:54.539Z,1532969454.539 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081300 psu
2018-07-30T16:50:54.544Z,1532969454.544 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:54.739Z,1532969454.739 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078800 psu
2018-07-30T16:50:54.749Z,1532969454.749 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:54.871Z,1532969454.871 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009589 psu
2018-07-30T16:50:54.873Z,1532969454.873 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:54.947Z,1532969454.947 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079800 psu
2018-07-30T16:50:54.953Z,1532969454.953 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:55.244Z,1532969455.244 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081700 psu
2018-07-30T16:50:55.272Z,1532969455.272 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:55.370Z,1532969455.370 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009590 psu
2018-07-30T16:50:55.371Z,1532969455.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:55.451Z,1532969455.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080600 psu
2018-07-30T16:50:55.457Z,1532969455.457 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:55.652Z,1532969455.652 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081900 psu
2018-07-30T16:50:55.658Z,1532969455.658 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:55.861Z,1532969455.861 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083800 psu
2018-07-30T16:50:55.864Z,1532969455.864 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:56.064Z,1532969456.064 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078300 psu
2018-07-30T16:50:56.077Z,1532969456.077 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:56.264Z,1532969456.264 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076500 psu
2018-07-30T16:50:56.275Z,1532969456.275 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:56.469Z,1532969456.469 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083200 psu
2018-07-30T16:50:56.474Z,1532969456.474 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:56.573Z,1532969456.573 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009588 psu
2018-07-30T16:50:56.574Z,1532969456.574 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:56.664Z,1532969456.664 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078600 psu
2018-07-30T16:50:56.669Z,1532969456.669 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:56.731Z,1532969456.731 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:56.877Z,1532969456.877 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081700 psu
2018-07-30T16:50:56.880Z,1532969456.880 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:57.077Z,1532969457.077 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082800 psu
2018-07-30T16:50:57.082Z,1532969457.082 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:57.276Z,1532969457.276 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081100 psu
2018-07-30T16:50:57.295Z,1532969457.295 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:57.481Z,1532969457.481 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075900 psu
2018-07-30T16:50:57.486Z,1532969457.486 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:57.687Z,1532969457.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079500 psu
2018-07-30T16:50:57.691Z,1532969457.691 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:57.884Z,1532969457.884 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094000 psu
2018-07-30T16:50:57.889Z,1532969457.889 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:57.955Z,1532969457.955 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:58.095Z,1532969458.095 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084000 psu
2018-07-30T16:50:58.100Z,1532969458.100 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:58.164Z,1532969458.164 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009589 psu
2018-07-30T16:50:58.168Z,1532969458.168 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:58.300Z,1532969458.300 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076200 psu
2018-07-30T16:50:58.307Z,1532969458.307 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:58.504Z,1532969458.504 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082800 psu
2018-07-30T16:50:58.528Z,1532969458.528 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:58.578Z,1532969458.578 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009588 psu
2018-07-30T16:50:58.580Z,1532969458.580 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:58.703Z,1532969458.703 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086200 psu
2018-07-30T16:50:58.708Z,1532969458.708 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:58.900Z,1532969458.900 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080300 psu
2018-07-30T16:50:58.904Z,1532969458.904 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:58.967Z,1532969458.967 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:59.111Z,1532969459.111 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082100 psu
2018-07-30T16:50:59.116Z,1532969459.116 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:59.316Z,1532969459.316 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086700 psu
2018-07-30T16:50:59.320Z,1532969459.320 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:59.369Z,1532969459.369 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009590 psu
2018-07-30T16:50:59.371Z,1532969459.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:50:59.512Z,1532969459.512 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080400 psu
2018-07-30T16:50:59.516Z,1532969459.516 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:59.712Z,1532969459.712 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076700 psu
2018-07-30T16:50:59.716Z,1532969459.716 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:59.779Z,1532969459.779 [CTD_NeilBrown](DEBUG): serial timeout
2018-07-30T16:50:59.917Z,1532969459.917 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080600 psu
2018-07-30T16:50:59.921Z,1532969459.921 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:50:59.966Z,1532969459.966 [DataOverHttps](INFO): Received command:restart app
2018-07-30T16:50:59.983Z,1532969459.983 [CommandLine](IMPORTANT): got command restart application
2018-07-30T16:51:00.128Z,1532969460.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082200 psu
2018-07-30T16:51:00.134Z,1532969460.134 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:51:00.331Z,1532969460.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081600 psu
2018-07-30T16:51:00.399Z,1532969460.399 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:51:00.536Z,1532969460.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.088400 psu
2018-07-30T16:51:00.543Z,1532969460.543 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:51:00.599Z,1532969460.599 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009586 psu
2018-07-30T16:51:00.601Z,1532969460.601 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:51:00.735Z,1532969460.735 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085800 psu
2018-07-30T16:51:00.743Z,1532969460.743 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:51:00.936Z,1532969460.936 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078300 psu
2018-07-30T16:51:00.941Z,1532969460.941 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:51:00.987Z,1532969460.987 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2018-07-30T16:51:00.988Z,1532969460.988 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:00.988Z,1532969460.988 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.057Z,1532969461.057 [MissionManager](INFO): MissionManager is completed.
2018-07-30T16:51:01.057Z,1532969461.057 [MissionManager](INFO): Uninitializing Mission sci2
2018-07-30T16:51:01.057Z,1532969461.057 [sci2] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2](DEBUG): Aggregate::uninitialize sci2
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:A] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:C] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:D] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:G] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:Read_Oil] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:PeakDetectChl] Stopped
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2018-07-30T16:51:01.058Z,1532969461.058 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-07-30T16:51:01.063Z,1532969461.063 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-30T16:51:01.063Z,1532969461.063 [sci2:NeedComms] Stopped
2018-07-30T16:51:01.063Z,1532969461.063 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2018-07-30T16:51:01.063Z,1532969461.063 [sci2:NeedComms:B.GoToSurface] Stopped
2018-07-30T16:51:01.063Z,1532969461.063 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:NeedComms:D] Stopped
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes] Stopped
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-07-30T16:51:01.064Z,1532969461.064 [sci2:StartingMission] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:I.Pitch] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:J.Buoyancy] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:K.SetSpeed] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:L.DepthEnvelope] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:M.AltitudeEnvelope] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:N.YoYo] Stopped
2018-07-30T16:51:01.065Z,1532969461.065 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-07-30T16:51:01.127Z,1532969461.127 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-07-30T16:51:01.128Z,1532969461.128 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.129Z,1532969461.129 [CommandLine](INFO): Join timeout helper Thread ID is 1172
2018-07-30T16:51:01.136Z,1532969461.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079200 psu
2018-07-30T16:51:01.149Z,1532969461.149 [CTD_NeilBrown](INFO): some bad data, not updating bins
2018-07-30T16:51:01.159Z,1532969461.159 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-07-30T16:51:01.159Z,1532969461.159 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.160Z,1532969461.160 [NavChartDb](INFO): Join timeout helper Thread ID is 1173
2018-07-30T16:51:01.370Z,1532969461.370 [CTD_Seabird](ERROR): Salinity reading out of range: 0.009584 psu
2018-07-30T16:51:01.371Z,1532969461.371 [CTD_Seabird](INFO): some bad data, not updating bins
2018-07-30T16:51:01.408Z,1532969461.408 [WetLabsBB2FL](INFO): Powering down
2018-07-30T16:51:01.427Z,1532969461.427 [MissionManager](IMPORTANT): Started mission Default
2018-07-30T16:51:01.428Z,1532969461.428 [Default] Running Loop=1
2018-07-30T16:51:01.428Z,1532969461.428 [Default](DEBUG): Aggregate::initialize Default
2018-07-30T16:51:01.428Z,1532969461.428 [Default:B.GoToSurface] Running Loop=1
2018-07-30T16:51:01.428Z,1532969461.428 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T16:51:01.428Z,1532969461.428 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-30T16:51:01.429Z,1532969461.429 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-30T16:51:01.429Z,1532969461.429 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-30T16:51:01.429Z,1532969461.429 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T16:51:01.430Z,1532969461.430 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T16:51:01.430Z,1532969461.430 [Default:A.Wait] Running Loop=1
2018-07-30T16:51:01.430Z,1532969461.430 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-30T16:51:01.539Z,1532969461.539 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:01.539Z,1532969461.539 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.555Z,1532969461.555 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-07-30T16:51:01.555Z,1532969461.555 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.555Z,1532969461.555 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1174
2018-07-30T16:51:01.663Z,1532969461.663 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:01.663Z,1532969461.663 [WetLabsBB2FL](INFO): Powering down
2018-07-30T16:51:01.664Z,1532969461.664 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.675Z,1532969461.675 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-07-30T16:51:01.675Z,1532969461.675 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:01.675Z,1532969461.675 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1175
2018-07-30T16:51:01.766Z,1532969461.766 [Aanderaa_O2](INFO): Powering down
2018-07-30T16:51:01.775Z,1532969461.775 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:02.190Z,1532969462.190 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-07-30T16:51:02.190Z,1532969462.190 [CTD_Seabird](INFO): Powering down
2018-07-30T16:51:02.191Z,1532969462.191 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.195Z,1532969462.195 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-07-30T16:51:02.195Z,1532969462.195 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.196Z,1532969462.196 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1177
2018-07-30T16:51:02.363Z,1532969462.363 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:02.363Z,1532969462.363 [CTD_NeilBrown](INFO): Powering down
2018-07-30T16:51:02.364Z,1532969462.364 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.376Z,1532969462.376 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-07-30T16:51:02.376Z,1532969462.376 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.376Z,1532969462.376 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1178
2018-07-30T16:51:02.391Z,1532969462.391 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:02.391Z,1532969462.391 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-07-30T16:51:02.570Z,1532969462.570 [Rowe_600LCM](INFO): Powering down
2018-07-30T16:51:02.572Z,1532969462.572 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.580Z,1532969462.580 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-07-30T16:51:02.580Z,1532969462.580 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.580Z,1532969462.580 [Radio_Surface](INFO): Join timeout helper Thread ID is 1180
2018-07-30T16:51:02.971Z,1532969462.971 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:02.971Z,1532969462.971 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.976Z,1532969462.976 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-07-30T16:51:02.976Z,1532969462.976 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:02.976Z,1532969462.976 [logger](INFO): Join timeout helper Thread ID is 1181
2018-07-30T16:51:03.003Z,1532969463.003 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:03.004Z,1532969463.004 [logger ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:03.008Z,1532969463.008 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-07-30T16:51:03.008Z,1532969463.008 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:03.009Z,1532969463.009 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-07-30T16:51:03.009Z,1532969463.009 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:03.009Z,1532969463.009 [controlThread](INFO): Join timeout helper Thread ID is 1182
2018-07-30T16:51:04.994Z,1532969464.994 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-07-30T16:51:04.994Z,1532969464.994 [controlThread](DEBUG): Uninitializing ControlThread
2018-07-30T16:51:05.002Z,1532969465.002 [NAL9602](INFO): Powering down
2018-07-30T16:51:05.004Z,1532969465.004 [PNI_TCM](INFO): Powering down
2018-07-30T16:51:05.092Z,1532969465.092 [Aanderaa_O2](INFO): Powering down
2018-07-30T16:51:05.093Z,1532969465.093 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-30T16:51:05.094Z,1532969465.094 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-07-30T16:51:05.095Z,1532969465.095 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-07-30T16:51:05.096Z,1532969465.096 [MissionManager](INFO): Uninitializing Mission Default
2018-07-30T16:51:05.096Z,1532969465.096 [Default] Stopped
2018-07-30T16:51:05.096Z,1532969465.096 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-30T16:51:05.096Z,1532969465.096 [Default:A.Wait] Stopped
2018-07-30T16:51:05.096Z,1532969465.096 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T16:51:05.096Z,1532969465.096 [Default:B.GoToSurface] Stopped
2018-07-30T16:51:05.096Z,1532969465.096 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T16:51:05.101Z,1532969465.101 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-07-30T16:51:05.101Z,1532969465.101 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-07-30T16:51:05.101Z,1532969465.101 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-07-30T16:51:05.102Z,1532969465.102 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-07-30T16:51:05.102Z,1532969465.102 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-30T16:51:05.102Z,1532969465.102 [BuoyancyServo](INFO): Powering down
2018-07-30T16:51:05.115Z,1532969465.115 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-07-30T16:51:05.115Z,1532969465.115 [ElevatorServo](INFO): Powering down
2018-07-30T16:51:05.116Z,1532969465.116 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-07-30T16:51:05.116Z,1532969465.116 [MassServo](INFO): Powering down
2018-07-30T16:51:05.117Z,1532969465.117 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-07-30T16:51:05.117Z,1532969465.117 [RudderServo](INFO): Powering down
2018-07-30T16:51:05.118Z,1532969465.118 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-30T16:51:05.119Z,1532969465.119 [ThrusterServo](INFO): Powering down
2018-07-30T16:51:05.120Z,1532969465.120 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-07-30T16:51:05.120Z,1532969465.120 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-07-30T16:51:05.120Z,1532969465.120 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-07-30T16:51:05.121Z,1532969465.121 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.363Z,1532969465.363 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.369Z,1532969465.369 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.406Z,1532969465.406 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.410Z,1532969465.410 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.412Z,1532969465.412 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.439Z,1532969465.439 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-07-30T16:51:05.501Z,1532969465.501 [logger ThreadHandler](INFO): Thread cancelled.