2017-10-10T21:40:25.320Z,1507671625.320 [Supervisor](DEBUG): Initializing supervisor.
2017-10-10T21:40:25.324Z,1507671625.324 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-10-10T21:40:25.325Z,1507671625.325 [SyncHandler](INFO): Protected caller Thread ID is 1585
2017-10-10T21:40:25.325Z,1507671625.325 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-10-10T21:40:25.326Z,1507671625.326 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-10-10T21:40:25.327Z,1507671625.327 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1586
2017-10-10T21:40:25.331Z,1507671625.331 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-10-10T21:40:25.345Z,1507671625.345 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-10-10T21:40:25.346Z,1507671625.346 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-10-10T21:40:25.346Z,1507671625.346 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1587
2017-10-10T21:40:25.347Z,1507671625.347 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-10-10T21:40:25.348Z,1507671625.348 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-10-10T21:40:25.349Z,1507671625.349 [logger ThreadHandler](INFO): Protected caller Thread ID is 1588
2017-10-10T21:40:25.351Z,1507671625.351 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-10-10T21:40:25.352Z,1507671625.352 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-10-10T21:40:25.354Z,1507671625.354 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-10-10T21:40:25.499Z,1507671625.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-10-10T21:40:25.500Z,1507671625.500 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-10-10T21:40:25.628Z,1507671625.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-10-10T21:40:25.628Z,1507671625.628 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-10-10T21:40:25.853Z,1507671625.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-10-10T21:40:25.854Z,1507671625.854 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-10-10T21:40:26.189Z,1507671626.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-10-10T21:40:26.189Z,1507671626.189 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-10-10T21:40:26.593Z,1507671626.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-10-10T21:40:26.593Z,1507671626.593 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-10-10T21:40:27.176Z,1507671627.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-10-10T21:40:27.177Z,1507671627.177 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-10-10T21:40:27.666Z,1507671627.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-10-10T21:40:27.667Z,1507671627.667 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-10-10T21:40:28.401Z,1507671628.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-10-10T21:40:28.402Z,1507671628.402 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-10-10T21:40:28.575Z,1507671628.575 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-10-10T21:40:28.950Z,1507671628.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-10-10T21:40:28.951Z,1507671628.951 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-10-10T21:40:29.111Z,1507671629.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-10-10T21:40:29.112Z,1507671629.112 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-10-10T21:40:29.375Z,1507671629.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-10-10T21:40:29.376Z,1507671629.376 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-10-10T21:40:29.632Z,1507671629.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-10-10T21:40:29.633Z,1507671629.633 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-10-10T21:40:29.819Z,1507671629.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-10-10T21:40:29.819Z,1507671629.819 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-10-10T21:40:30.059Z,1507671630.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-10-10T21:40:30.061Z,1507671630.061 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-10-10T21:40:30.062Z,1507671630.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-10-10T21:40:30.375Z,1507671630.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-10-10T21:40:30.376Z,1507671630.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-10-10T21:40:30.472Z,1507671630.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-10-10T21:40:30.642Z,1507671630.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-10-10T21:40:30.817Z,1507671630.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-10-10T21:40:31.020Z,1507671631.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-10-10T21:40:31.315Z,1507671631.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-10-10T21:40:31.592Z,1507671631.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-10-10T21:40:31.775Z,1507671631.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-10-10T21:40:31.964Z,1507671631.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-10-10T21:40:32.161Z,1507671632.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-10-10T21:40:32.267Z,1507671632.267 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2017-10-10T21:40:32.267Z,1507671632.267 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-10-10T21:40:32.275Z,1507671632.275 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-10-10T21:40:32.378Z,1507671632.378 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-10-10T21:40:32.379Z,1507671632.379 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-10-10T21:40:32.393Z,1507671632.393 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-10-10T21:40:32.393Z,1507671632.393 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-10-10T21:40:32.420Z,1507671632.420 [DepthRateCalculator] Loaded
2017-10-10T21:40:32.420Z,1507671632.420 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-10-10T21:40:32.428Z,1507671632.428 [PitchRateCalculator] Loaded
2017-10-10T21:40:32.428Z,1507671632.428 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-10-10T21:40:32.447Z,1507671632.447 [SpeedCalculator] Loaded
2017-10-10T21:40:32.447Z,1507671632.447 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-10-10T21:40:32.472Z,1507671632.472 [TempGradientCalculator] Loaded
2017-10-10T21:40:32.472Z,1507671632.472 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-10-10T21:40:32.491Z,1507671632.491 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-10-10T21:40:32.491Z,1507671632.491 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-10-10T21:40:32.498Z,1507671632.498 [YawRateCalculator] Loaded
2017-10-10T21:40:32.498Z,1507671632.498 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-10-10T21:40:32.498Z,1507671632.498 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-10-10T21:40:32.499Z,1507671632.499 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-10-10T21:40:32.524Z,1507671632.524 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-10-10T21:40:32.525Z,1507671632.525 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-10-10T21:40:32.810Z,1507671632.810 [DataOverHttps] Loaded
2017-10-10T21:40:32.810Z,1507671632.810 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-10-10T21:40:32.841Z,1507671632.841 [Depth_Keller] Loaded
2017-10-10T21:40:32.842Z,1507671632.842 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-10-10T21:40:32.861Z,1507671632.861 [Onboard] Loaded
2017-10-10T21:40:32.862Z,1507671632.862 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-10-10T21:40:32.874Z,1507671632.874 [Radio_Surface] Loaded
2017-10-10T21:40:32.874Z,1507671632.874 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-10-10T21:40:32.875Z,1507671632.875 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2017-10-10T21:40:32.876Z,1507671632.876 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1667
2017-10-10T21:40:32.935Z,1507671632.935 [PNI_TCM] Loaded
2017-10-10T21:40:32.935Z,1507671632.935 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-10-10T21:40:33.009Z,1507671633.009 [Rowe_600LCM] Loaded
2017-10-10T21:40:33.009Z,1507671633.009 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-10-10T21:40:33.010Z,1507671633.010 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2017-10-10T21:40:33.011Z,1507671633.011 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1668
2017-10-10T21:40:33.011Z,1507671633.011 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-10-10T21:40:33.012Z,1507671633.012 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-10-10T21:40:33.144Z,1507671633.144 [SBIT](DEBUG): Construct Startup Built In Test.
2017-10-10T21:40:33.176Z,1507671633.176 [SBIT] Loaded
2017-10-10T21:40:33.177Z,1507671633.177 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-10-10T21:40:33.178Z,1507671633.178 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-10-10T21:40:33.204Z,1507671633.204 [IBIT] Loaded
2017-10-10T21:40:33.204Z,1507671633.204 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-10-10T21:40:33.208Z,1507671633.208 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-10-10T21:40:33.400Z,1507671633.400 [CBIT] Loaded
2017-10-10T21:40:33.400Z,1507671633.400 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-10-10T21:40:33.401Z,1507671633.401 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-10-10T21:40:33.401Z,1507671633.401 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-10-10T21:40:33.548Z,1507671633.548 [Aanderaa_O2] Loaded
2017-10-10T21:40:33.549Z,1507671633.549 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-10-10T21:40:33.651Z,1507671633.651 [CTD_NeilBrown] Loaded
2017-10-10T21:40:33.651Z,1507671633.651 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-10-10T21:40:33.653Z,1507671633.653 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409F84E0
2017-10-10T21:40:33.653Z,1507671633.653 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1669
2017-10-10T21:40:33.674Z,1507671633.674 [ESPComponent] Loaded
2017-10-10T21:40:33.674Z,1507671633.674 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-10-10T21:40:33.692Z,1507671633.692 [PAR_Licor] Loaded
2017-10-10T21:40:33.692Z,1507671633.692 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-10-10T21:40:33.749Z,1507671633.749 [WetLabsBB2FL] Loaded
2017-10-10T21:40:33.749Z,1507671633.749 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-10-10T21:40:33.751Z,1507671633.751 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A284E0
2017-10-10T21:40:33.751Z,1507671633.751 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1670
2017-10-10T21:40:33.752Z,1507671633.752 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-10-10T21:40:33.752Z,1507671633.752 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-10-10T21:40:33.998Z,1507671633.998 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-10-10T21:40:33.998Z,1507671633.998 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-10-10T21:40:34.070Z,1507671634.070 [VerticalControl](DEBUG): Construct VerticalControl.
2017-10-10T21:40:34.267Z,1507671634.267 [VerticalControl] Loaded
2017-10-10T21:40:34.267Z,1507671634.267 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-10-10T21:40:34.268Z,1507671634.268 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-10-10T21:40:34.343Z,1507671634.343 [HorizontalControl] Loaded
2017-10-10T21:40:34.344Z,1507671634.344 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-10-10T21:40:34.345Z,1507671634.345 [SpeedControl](DEBUG): Construct SpeedControl.
2017-10-10T21:40:34.351Z,1507671634.351 [SpeedControl] Loaded
2017-10-10T21:40:34.351Z,1507671634.351 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-10-10T21:40:34.352Z,1507671634.352 [LoopControl](DEBUG): Construct LoopControl.
2017-10-10T21:40:34.353Z,1507671634.353 [LoopControl] Loaded
2017-10-10T21:40:34.353Z,1507671634.353 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-10-10T21:40:34.354Z,1507671634.354 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-10-10T21:40:34.354Z,1507671634.354 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-10-10T21:40:34.440Z,1507671634.440 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-10-10T21:40:34.440Z,1507671634.440 [StratificationFrontDetector](DEBUG): (re)initializing
2017-10-10T21:40:34.440Z,1507671634.440 [StratificationFrontDetector] Loaded
2017-10-10T21:40:34.440Z,1507671634.440 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-10-10T21:40:34.441Z,1507671634.441 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-10-10T21:40:34.442Z,1507671634.442 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-10-10T21:40:34.545Z,1507671634.545 [BuoyancyServo] Loaded
2017-10-10T21:40:34.545Z,1507671634.546 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-10-10T21:40:34.560Z,1507671634.560 [ElevatorServo] Loaded
2017-10-10T21:40:34.560Z,1507671634.560 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-10-10T21:40:34.574Z,1507671634.574 [RudderServo] Loaded
2017-10-10T21:40:34.575Z,1507671634.575 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-10-10T21:40:34.588Z,1507671634.588 [ThrusterServo] Loaded
2017-10-10T21:40:34.588Z,1507671634.588 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-10-10T21:40:34.589Z,1507671634.589 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-10-10T21:40:34.589Z,1507671634.589 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-10-10T21:40:34.703Z,1507671634.703 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-10-10T21:40:34.704Z,1507671634.704 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-10-10T21:40:34.771Z,1507671634.771 [DeadReckonUsingSpeedCalculator] Loaded
2017-10-10T21:40:34.772Z,1507671634.772 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-10-10T21:40:34.792Z,1507671634.792 [NavChart] Loaded
2017-10-10T21:40:34.792Z,1507671634.792 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-10-10T21:40:34.797Z,1507671634.797 [UniversalFixResidualReporter] Loaded
2017-10-10T21:40:34.798Z,1507671634.798 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-10-10T21:40:34.798Z,1507671634.798 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-10-10T21:40:34.803Z,1507671634.803 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-10-10T21:40:34.804Z,1507671634.804 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-10-10T21:40:34.814Z,1507671634.814 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-10-10T21:40:34.815Z,1507671634.815 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCF4E0
2017-10-10T21:40:34.815Z,1507671634.815 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1671
2017-10-10T21:40:34.833Z,1507671634.833 [Supervisor](INFO): Main Thread ID is 1090
2017-10-10T21:40:34.833Z,1507671634.833 [Supervisor](DEBUG): Running supervisor.
2017-10-10T21:40:34.834Z,1507671634.834 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1672
2017-10-10T21:40:34.836Z,1507671634.836 [controlThread ThreadHandler](INFO): Handler Thread ID is 1673
2017-10-10T21:40:34.836Z,1507671634.836 [controlThread](DEBUG): Initializing ControlThread
2017-10-10T21:40:34.837Z,1507671634.837 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-10T21:40:34.837Z,1507671634.837 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-10-10T21:40:34.837Z,1507671634.837 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-10-10T21:40:34.838Z,1507671634.838 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-10-10T21:40:34.838Z,1507671634.838 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-10-10T21:40:34.839Z,1507671634.839 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-10-10T21:40:34.844Z,1507671634.844 [SBIT](INFO): Initialize SBIT Component.
2017-10-10T21:40:34.845Z,1507671634.845 [SBIT](IMPORTANT): git: 2017-09-28-3-g55dd8f5
2017-10-10T21:40:34.845Z,1507671634.845 [SBIT](INFO): git hash: 55dd8f5e54974716983ce11a9eec5e3f616af0f6
2017-10-10T21:40:34.845Z,1507671634.845 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-10-10T21:40:34.847Z,1507671634.847 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Tue Oct 3 09:53:58 PDT 2017
2017-10-10T21:40:34.848Z,1507671634.848 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-10-10T21:40:34.848Z,1507671634.848 [IBIT](INFO): Initialize IBIT Component.
2017-10-10T21:40:34.849Z,1507671634.849 [CBIT](DEBUG): Initialize CBIT Component.
2017-10-10T21:40:34.849Z,1507671634.849 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2017-10-10T21:40:34.852Z,1507671634.852 [logger ThreadHandler](INFO): Handler Thread ID is 1674
2017-10-10T21:40:34.854Z,1507671634.854 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1675
2017-10-10T21:40:34.863Z,1507671634.863 [Radio_Surface](INFO): Powering up
2017-10-10T21:40:34.875Z,1507671634.875 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1676
2017-10-10T21:40:34.876Z,1507671634.876 [Rowe_600LCM](INFO): Initializing
2017-10-10T21:40:34.877Z,1507671634.877 [Rowe_600LCM](INFO): Checking LCM
2017-10-10T21:40:34.885Z,1507671634.885 [Rowe_600LCM](INFO): LCM OK
2017-10-10T21:40:34.885Z,1507671634.885 [Rowe_600LCM](INFO): Powering up
2017-10-10T21:40:34.892Z,1507671634.892 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1677
2017-10-10T21:40:34.893Z,1507671634.893 [CTD_NeilBrown](INFO): Powering down
2017-10-10T21:40:34.920Z,1507671634.920 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1678
2017-10-10T21:40:34.921Z,1507671634.921 [WetLabsBB2FL](INFO): Powering down
2017-10-10T21:40:34.935Z,1507671634.935 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-10-10T21:40:34.937Z,1507671634.937 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-10-10T21:40:34.938Z,1507671634.938 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-10-10T21:40:34.943Z,1507671634.943 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-10-10T21:40:34.945Z,1507671634.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-10T21:40:34.946Z,1507671634.946 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-10T21:40:34.946Z,1507671634.946 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-10T21:40:34.952Z,1507671634.952 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-10T21:40:34.953Z,1507671634.953 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-10T21:40:34.953Z,1507671634.953 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-10T21:40:34.954Z,1507671634.954 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-10-10T21:40:34.954Z,1507671634.954 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-10-10T21:40:34.964Z,1507671634.964 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-10-10T21:40:34.976Z,1507671634.976 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1679
2017-10-10T21:40:34.980Z,1507671634.980 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-10-10T21:40:34.980Z,1507671634.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-10-10T21:40:34.981Z,1507671634.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-10-10T21:40:34.981Z,1507671634.981 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-10-10T21:40:34.981Z,1507671634.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-10-10T21:40:34.981Z,1507671634.981 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-10-10T21:40:34.981Z,1507671634.981 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-10-10T21:40:34.981Z,1507671634.981 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-10-10T21:40:34.982Z,1507671634.982 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-10-10T21:40:34.982Z,1507671634.982 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-10-10T21:40:34.982Z,1507671634.982 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-10-10T21:40:34.982Z,1507671634.982 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-10-10T21:40:34.982Z,1507671634.982 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-10-10T21:40:34.983Z,1507671634.983 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-10-10T21:40:34.983Z,1507671634.983 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-10-10T21:40:34.983Z,1507671634.983 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-10-10T21:40:35.023Z,1507671635.023 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-10T21:40:35.069Z,1507671635.069 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2017-10-10T21:40:35.070Z,1507671635.070 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-10-10T21:40:35.140Z,1507671635.140 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-10-10T21:40:35.141Z,1507671635.141 [Default:A.Wait](DEBUG): Construct Wait.
2017-10-10T21:40:35.157Z,1507671635.157 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-10T21:40:35.188Z,1507671635.188 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-10-10T21:40:35.191Z,1507671635.191 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-10-10T21:40:35.206Z,1507671635.206 [Default:E.Execute](DEBUG): Construct Execute.
2017-10-10T21:40:35.214Z,1507671635.214 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2017-10-10T21:40:35.220Z,1507671635.220 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,Onboard,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-10-10T21:40:35.273Z,1507671635.273 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-10-10T21:40:35.562Z,1507671635.562 [ESPComponent](INFO): powering down ESP
2017-10-10T21:40:36.200Z,1507671636.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:36.223Z,1507671636.223 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:36.341Z,1507671636.341 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-10-10T21:40:36.348Z,1507671636.348 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-10-10T21:40:36.386Z,1507671636.386 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-10-10T21:40:36.391Z,1507671636.391 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-10-10T21:40:36.411Z,1507671636.411 [RudderServo](DEBUG): Initializing EZServoServo.
2017-10-10T21:40:36.419Z,1507671636.419 [RudderServo](DEBUG): Initializing RudderServo.
2017-10-10T21:40:36.426Z,1507671636.426 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-10-10T21:40:36.431Z,1507671636.431 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-10-10T21:40:36.494Z,1507671636.494 [CommandLine](FAULT): Scheduling is paused
2017-10-10T21:40:36.495Z,1507671636.495 [CBIT](INFO): Critical error at 20171010T214034
2017-10-10T21:40:36.495Z,1507671636.495 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-10-10T21:40:36.752Z,1507671636.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:36.753Z,1507671636.753 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:39.198Z,1507671639.198 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-10T21:40:39.358Z,1507671639.358 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-10T21:40:39.402Z,1507671639.402 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-10T21:40:39.417Z,1507671639.417 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-10T21:40:39.418Z,1507671639.418 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-10T21:40:46.370Z,1507671646.370 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:40:46.370Z,1507671646.370 [Aanderaa_O2] Data Fault, FailCount= 1
2017-10-10T21:40:46.370Z,1507671646.370 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:40:46.390Z,1507671646.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:46.391Z,1507671646.391 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:46.688Z,1507671646.688 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:40:46.730Z,1507671646.730 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:40:46.828Z,1507671646.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:46.829Z,1507671646.829 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:47.180Z,1507671647.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:47.181Z,1507671647.181 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:47.500Z,1507671647.500 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004091
2017-10-10T21:40:47.532Z,1507671647.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:47.534Z,1507671647.534 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:47.615Z,1507671647.615 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:40:47.615Z,1507671647.615 [Aanderaa_O2] No Fault, FailCount= 1
2017-10-10T21:40:47.957Z,1507671647.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:47.958Z,1507671647.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:48.406Z,1507671648.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:48.415Z,1507671648.415 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-10T21:40:49.570Z,1507671649.570 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:40:49.570Z,1507671649.570 [Aanderaa_O2] Data Fault, FailCount= 2
2017-10-10T21:40:49.570Z,1507671649.570 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:40:49.839Z,1507671649.839 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:40:49.937Z,1507671649.937 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:40:50.920Z,1507671650.920 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:40:50.920Z,1507671650.920 [Aanderaa_O2] No Fault, FailCount= 2
2017-10-10T21:40:52.746Z,1507671652.746 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:40:52.746Z,1507671652.746 [Aanderaa_O2] Data Fault, FailCount= 3
2017-10-10T21:40:52.746Z,1507671652.746 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:40:52.812Z,1507671652.812 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:40:52.813Z,1507671652.813 [CBIT](FAULT): Data Fault in component: Aanderaa_O2
2017-10-10T21:40:53.127Z,1507671653.127 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:41:42.645Z,1507671702.645 [SBIT](IMPORTANT): Beginning Startup BIT
2017-10-10T21:41:42.658Z,1507671702.658 [CBIT](IMPORTANT): Beginning ground fault scan
2017-10-10T21:41:53.032Z,1507671713.032 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006273
CHAN A1 (24V): 0.005255
CHAN A2 (12V): -0.005267
CHAN A3 (5V): -0.002287
CHAN B0 (3.3V): -0.001096
CHAN B1 (3.15aV): -0.001288
CHAN B2 (3.15bV): -0.001447
CHAN B3 (GND): 0.000120
OPEN: 0.004581
Full Scale Calc: 4.765 mA, -1.589 mA
2017-10-10T21:41:55.794Z,1507671715.794 [SBIT](ERROR): Could not read massPosReader_.
2017-10-10T21:42:12.265Z,1507671732.265 [CommandLine](IMPORTANT): got command show stack
2017-10-10T21:42:12.265Z,1507671732.265 [CommandLine](IMPORTANT): Behavior Stack:

2017-10-10T21:42:12.265Z,1507671732.265 [MissionManager](IMPORTANT): Mission loaded, but not running.
2017-10-10T21:42:22.588Z,1507671742.588 [SBIT](ERROR): Could not read massPosReader_.
2017-10-10T21:42:22.588Z,1507671742.588 [SBIT](FAULT): Control surface position failure.
2017-10-10T21:42:34.899Z,1507671754.899 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-10T21:42:34.899Z,1507671754.899 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-10-10T21:42:34.899Z,1507671754.899 [Rowe_600LCM](ERROR): Communications Fault
2017-10-10T21:42:35.021Z,1507671755.021 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-10T21:42:35.311Z,1507671755.311 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-10T21:42:35.521Z,1507671755.521 [Rowe_600LCM](INFO): Powering down
2017-10-10T21:42:36.171Z,1507671756.171 [SBIT](ERROR): Could not read massPosReader_.
2017-10-10T21:42:36.171Z,1507671756.171 [SBIT](FAULT): Control surface position failure.
2017-10-10T21:42:39.518Z,1507671759.518 [SBIT](CRITICAL): SBIT FAILED
2017-10-10T21:42:39.520Z,1507671759.520 [CBIT](INFO): Critical error at 20171010T214239
2017-10-10T21:42:39.522Z,1507671759.522 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-10T21:42:39.522Z,1507671759.522 [Rowe_600LCM] No Fault, FailCount= 1
2017-10-10T21:42:39.584Z,1507671759.584 [Rowe_600LCM](INFO): Initializing
2017-10-10T21:42:39.585Z,1507671759.585 [Rowe_600LCM](INFO): Checking LCM
2017-10-10T21:42:39.585Z,1507671759.585 [Rowe_600LCM](INFO): LCM OK
2017-10-10T21:42:39.585Z,1507671759.585 [Rowe_600LCM](INFO): Powering up
2017-10-10T21:42:39.649Z,1507671759.649 [MissionManager](IMPORTANT): Started mission Startup
2017-10-10T21:42:39.649Z,1507671759.649 [Startup] Running Loop=1
2017-10-10T21:42:39.650Z,1507671759.650 [Startup](DEBUG): Aggregate::initialize Startup
2017-10-10T21:42:39.650Z,1507671759.650 [Startup:A.GoToSurface] Running Loop=1
2017-10-10T21:42:39.650Z,1507671759.650 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-10-10T21:42:39.659Z,1507671759.659 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-10-10T21:42:39.660Z,1507671759.660 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-10-10T21:42:39.660Z,1507671759.660 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-10-10T21:42:39.660Z,1507671759.660 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-10-10T21:42:39.661Z,1507671759.661 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-10-10T21:42:39.680Z,1507671759.680 [Startup:StartupSatComms] Running Loop=1
2017-10-10T21:42:39.681Z,1507671759.681 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-10-10T21:42:39.681Z,1507671759.681 [Startup:StartupSatComms:A] Running Loop=1
2017-10-10T21:42:39.988Z,1507671759.988 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-10-10T21:42:43.731Z,1507671763.731 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-10T21:42:43.798Z,1507671763.798 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-10T21:42:43.799Z,1507671763.799 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-10T21:42:43.800Z,1507671763.800 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-10T21:42:43.801Z,1507671763.801 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-10T21:42:54.856Z,1507671774.856 [CommandLine](IMPORTANT): got command show stack
2017-10-10T21:42:54.856Z,1507671774.856 [CommandLine](IMPORTANT): Behavior Stack:

2017-10-10T21:42:54.856Z,1507671774.856 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2017-10-10T21:42:54.857Z,1507671774.857 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2017-10-10T21:43:03.193Z,1507671783.193 [CommandLine](IMPORTANT): got command show stack
2017-10-10T21:43:03.193Z,1507671783.193 [CommandLine](IMPORTANT): Behavior Stack:

2017-10-10T21:43:03.193Z,1507671783.193 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2017-10-10T21:43:03.194Z,1507671783.194 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2017-10-10T21:43:22.809Z,1507671802.809 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:43:22.809Z,1507671802.809 [Aanderaa_O2] No Fault, FailCount= 3
2017-10-10T21:43:24.772Z,1507671804.772 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:43:24.772Z,1507671804.772 [Aanderaa_O2] Data Fault, FailCount= 1
2017-10-10T21:43:24.772Z,1507671804.772 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:43:24.841Z,1507671804.841 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:43:25.276Z,1507671805.276 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:43:26.106Z,1507671806.106 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:43:26.106Z,1507671806.106 [Aanderaa_O2] No Fault, FailCount= 1
2017-10-10T21:43:27.965Z,1507671807.965 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:43:27.965Z,1507671807.965 [Aanderaa_O2] Data Fault, FailCount= 2
2017-10-10T21:43:27.965Z,1507671807.965 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:43:28.012Z,1507671808.012 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:43:28.483Z,1507671808.483 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:43:29.199Z,1507671809.199 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:43:29.199Z,1507671809.199 [Aanderaa_O2] No Fault, FailCount= 2
2017-10-10T21:43:31.163Z,1507671811.163 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:43:31.163Z,1507671811.163 [Aanderaa_O2] Data Fault, FailCount= 3
2017-10-10T21:43:31.163Z,1507671811.163 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:43:31.224Z,1507671811.224 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:43:31.225Z,1507671811.225 [CBIT](FAULT): Data Fault in component: Aanderaa_O2
2017-10-10T21:43:31.691Z,1507671811.691 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:43:35.183Z,1507671815.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-10T21:43:35.183Z,1507671815.183 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-10-10T21:43:35.183Z,1507671815.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-10-10T21:43:35.185Z,1507671815.185 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-10T21:43:35.185Z,1507671815.185 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-10-10T21:43:35.185Z,1507671815.185 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-10-10T21:43:35.235Z,1507671815.235 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-10-10T21:43:35.236Z,1507671815.236 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-10-10T21:43:35.602Z,1507671815.602 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-10-10T21:43:35.602Z,1507671815.602 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-10-10T21:43:35.619Z,1507671815.619 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-10-10T21:43:35.619Z,1507671815.619 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-10-10T21:43:35.994Z,1507671815.994 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-10T21:43:35.994Z,1507671815.994 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-10T21:43:35.995Z,1507671815.995 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-10T21:43:35.996Z,1507671815.996 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-10T21:43:35.997Z,1507671815.997 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-10T21:43:35.997Z,1507671815.997 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-10T21:43:39.847Z,1507671819.847 [Startup:StartupSatComms:A](INFO): Timed out from 2017-10-10T21:42:39.7Z
2017-10-10T21:43:39.847Z,1507671819.847 [Startup:StartupSatComms:A] Stopped
2017-10-10T21:43:39.847Z,1507671819.847 [Startup:StartupSatComms:B] Running Loop=1
2017-10-10T21:43:40.033Z,1507671820.033 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-10-10T21:43:44.588Z,1507671824.588 [CommandLine](IMPORTANT): got command show stack
2017-10-10T21:43:44.588Z,1507671824.588 [CommandLine](IMPORTANT): Behavior Stack:

2017-10-10T21:43:44.588Z,1507671824.588 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2017-10-10T21:43:44.589Z,1507671824.589 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2017-10-10T21:43:45.426Z,1507671825.426 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171010T212614/Courier0004.lzma
2017-10-10T21:43:46.224Z,1507671826.224 [DataOverHttps](INFO): Moved sent file to Logs/20171010T212614/Courier0004.lzma.bak
2017-10-10T21:43:46.224Z,1507671826.224 [DataOverHttps](INFO): SBD MOMSN=5127207
2017-10-10T21:43:51.341Z,1507671831.341 [CommandLine](IMPORTANT): got command show stack
2017-10-10T21:43:51.341Z,1507671831.341 [CommandLine](IMPORTANT): Behavior Stack:

2017-10-10T21:43:51.341Z,1507671831.341 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2017-10-10T21:43:51.342Z,1507671831.342 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2017-10-10T21:43:55.923Z,1507671835.923 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20171010T214025/Courier0000.lzma
2017-10-10T21:43:56.720Z,1507671836.720 [DataOverHttps](INFO): Moved sent file to Logs/20171010T214025/Courier0000.lzma.bak
2017-10-10T21:43:56.720Z,1507671836.720 [DataOverHttps](INFO): SBD MOMSN=5127209
2017-10-10T21:44:06.533Z,1507671846.533 [DataOverHttps](INFO): Sending 607 bytes from file Logs/20171010T212614/Express0005.lzma
2017-10-10T21:44:07.342Z,1507671847.342 [DataOverHttps](INFO): Moved sent file to Logs/20171010T212614/Express0005.lzma.bak
2017-10-10T21:44:07.342Z,1507671847.342 [DataOverHttps](INFO): SBD MOMSN=5127214
2017-10-10T21:44:11.460Z,1507671851.460 [CommandLine](IMPORTANT): got command show stack
2017-10-10T21:44:11.460Z,1507671851.460 [CommandLine](IMPORTANT): Behavior Stack:

2017-10-10T21:44:11.460Z,1507671851.460 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2017-10-10T21:44:11.461Z,1507671851.461 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2017-10-10T21:44:16.957Z,1507671856.957 [DataOverHttps](INFO): Sending 859 bytes from file Logs/20171010T214025/Express0001.lzma
2017-10-10T21:44:17.777Z,1507671857.777 [DataOverHttps](INFO): Moved sent file to Logs/20171010T214025/Express0001.lzma.bak
2017-10-10T21:44:17.777Z,1507671857.777 [DataOverHttps](INFO): SBD MOMSN=5127245
2017-10-10T21:44:18.346Z,1507671858.346 [Startup:StartupSatComms:B] Stopped
2017-10-10T21:44:18.346Z,1507671858.346 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-10-10T21:44:18.346Z,1507671858.346 [Startup:StartupSatComms] Stopped
2017-10-10T21:44:18.346Z,1507671858.346 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-10-10T21:44:18.356Z,1507671858.356 [Startup](INFO): Completed Startup
2017-10-10T21:44:18.356Z,1507671858.356 [MissionManager](INFO): Startup is completed.
2017-10-10T21:44:18.356Z,1507671858.356 [MissionManager](INFO): Uninitializing Mission Startup
2017-10-10T21:44:18.356Z,1507671858.356 [Startup] Stopped
2017-10-10T21:44:18.356Z,1507671858.356 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-10-10T21:44:18.356Z,1507671858.356 [Startup:A.GoToSurface] Stopped
2017-10-10T21:44:18.356Z,1507671858.356 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-10-10T21:44:18.621Z,1507671858.621 [MissionManager](IMPORTANT): Started mission Default
2017-10-10T21:44:18.621Z,1507671858.621 [Default] Running Loop=1
2017-10-10T21:44:18.622Z,1507671858.622 [Default](DEBUG): Aggregate::initialize Default
2017-10-10T21:44:18.622Z,1507671858.622 [Default:B.GoToSurface] Running Loop=1
2017-10-10T21:44:18.622Z,1507671858.622 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-10-10T21:44:18.622Z,1507671858.622 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-10-10T21:44:18.622Z,1507671858.622 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-10-10T21:44:18.623Z,1507671858.623 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-10-10T21:44:18.623Z,1507671858.623 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-10-10T21:44:18.624Z,1507671858.624 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-10-10T21:44:18.624Z,1507671858.624 [Default:A.Wait] Running Loop=1
2017-10-10T21:44:18.624Z,1507671858.624 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-10-10T21:44:31.886Z,1507671871.886 [Default:A.Wait](INFO): Done Waiting.
2017-10-10T21:44:31.887Z,1507671871.887 [Default:A.Wait] Stopped
2017-10-10T21:44:31.887Z,1507671871.887 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-10-10T21:44:32.271Z,1507671872.271 [Default:CheckIn] Running Loop=1
2017-10-10T21:44:32.271Z,1507671872.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-10-10T21:44:32.271Z,1507671872.271 [Default:CheckIn:Read_GPS] Running Loop=1
2017-10-10T21:44:32.680Z,1507671872.680 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-10-10T21:44:39.991Z,1507671879.991 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-10T21:44:39.991Z,1507671879.991 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-10-10T21:44:39.991Z,1507671879.991 [Rowe_600LCM](ERROR): Communications Fault
2017-10-10T21:44:40.333Z,1507671880.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2017-10-10T21:44:40.395Z,1507671880.395 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-10T21:44:40.447Z,1507671880.447 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-10T21:44:40.573Z,1507671880.573 [Rowe_600LCM](INFO): Powering down
2017-10-10T21:44:40.676Z,1507671880.676 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-10-10T21:44:41.075Z,1507671881.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-10T21:44:41.466Z,1507671881.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-10-10T21:44:41.873Z,1507671881.873 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-10-10T21:44:41.888Z,1507671881.888 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-10T21:44:41.888Z,1507671881.888 [Rowe_600LCM] No Fault, FailCount= 2
2017-10-10T21:44:42.187Z,1507671882.187 [Rowe_600LCM](INFO): Initializing
2017-10-10T21:44:42.188Z,1507671882.188 [Rowe_600LCM](INFO): Checking LCM
2017-10-10T21:44:42.188Z,1507671882.188 [Rowe_600LCM](INFO): LCM OK
2017-10-10T21:44:42.188Z,1507671882.188 [Rowe_600LCM](INFO): Powering up
2017-10-10T21:44:42.289Z,1507671882.289 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-10-10T21:44:42.671Z,1507671882.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-10-10T21:44:43.098Z,1507671883.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-10-10T21:44:43.504Z,1507671883.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-10-10T21:44:43.867Z,1507671883.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-10-10T21:44:44.262Z,1507671884.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-10-10T21:44:44.666Z,1507671884.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-10T21:44:45.100Z,1507671885.100 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-10T21:44:45.466Z,1507671885.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-10T21:44:45.866Z,1507671885.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-10T21:44:46.313Z,1507671886.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-10-10T21:44:46.444Z,1507671886.444 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-10T21:44:46.518Z,1507671886.518 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-10T21:44:46.520Z,1507671886.520 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-10T21:44:46.521Z,1507671886.521 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-10T21:44:46.522Z,1507671886.522 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-10T21:44:46.725Z,1507671886.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-10-10T21:44:48.452Z,1507671888.452 [CommandLine](IMPORTANT): got command report mod WetLabsBB2FL.durationOfLastRun
2017-10-10T21:44:48.725Z,1507671888.725 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun no_value
2017-10-10T21:45:17.532Z,1507671917.532 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2017-10-10T21:45:17.532Z,1507671917.532 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2017-10-10T21:45:17.650Z,1507671917.650 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2017-10-10T21:45:17.655Z,1507671917.655 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2017-10-10T21:45:17.698Z,1507671917.698 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2017-10-10T21:45:17.702Z,1507671917.702 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2017-10-10T21:45:17.719Z,1507671917.719 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2017-10-10T21:45:17.723Z,1507671917.723 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2017-10-10T21:45:17.733Z,1507671917.733 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2017-10-10T21:45:17.739Z,1507671917.739 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2017-10-10T21:45:17.743Z,1507671917.743 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2017-10-10T21:45:17.747Z,1507671917.747 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2017-10-10T21:45:17.752Z,1507671917.752 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool
2017-10-10T21:45:17.773Z,1507671917.773 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 1.000000 bool
2017-10-10T21:45:17.778Z,1507671917.778 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 0.000000 bool
2017-10-10T21:45:17.807Z,1507671917.807 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2017-10-10T21:45:17.855Z,1507671917.855 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2017-10-10T21:45:17.860Z,1507671917.860 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2017-10-10T21:45:17.877Z,1507671917.877 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2017-10-10T21:45:17.893Z,1507671917.893 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2017-10-10T21:45:17.928Z,1507671917.928 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2017-10-10T21:45:17.936Z,1507671917.936 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2017-10-10T21:45:17.942Z,1507671917.942 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2017-10-10T21:45:17.947Z,1507671917.947 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2017-10-10T21:45:17.951Z,1507671917.951 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2017-10-10T21:45:17.978Z,1507671917.978 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2017-10-10T21:45:17.982Z,1507671917.982 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2017-10-10T21:45:17.985Z,1507671917.985 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2017-10-10T21:45:18.006Z,1507671918.006 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2017-10-10T21:45:18.010Z,1507671918.010 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2017-10-10T21:45:18.027Z,1507671918.027 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2017-10-10T21:45:18.030Z,1507671918.030 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2017-10-10T21:45:18.034Z,1507671918.034 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2017-10-10T21:45:18.055Z,1507671918.055 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan arcdeg
2017-10-10T21:45:18.083Z,1507671918.083 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-10-10T21:45:18.109Z,1507671918.109 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2017-10-10T21:45:18.161Z,1507671918.161 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-10-10T21:45:18.268Z,1507671918.268 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Aggregate xmlns="Tethys"
       xmlns:Derivation="Tethys/Derivation"
       xmlns:Science="Tethys/Science"
       xmlns:Sensor="Tethys/Sensor"
       xmlns:Trigger="Tethys/Trigger"
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
                           Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                           Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                           Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Science">

    <DefineArg Name="SampleISUS"><Description>
        Set either this or PeakDetectNO3Active to True in order to sample
        Nitrate. EnabledISUS must also be true.
    </Description><False/></DefineArg>

    <DefineArg Name="SampleISUSmaxPitch"><Description>
        Maximum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>60.0</Value></DefineArg>

    <DefineArg Name="SampleISUSminPitch"><Description>
        Minimum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>-60.0</Value></DefineArg>

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledTurbulence_NPS"><Description>
        Automatically set to true if the NPS Turbulence sensor is enabled. Set
        to false to disable reading from the NPS Turbulence sensor.
    </Description><Science:Turbulence_NPS.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledISUS"><Description>
        Automatically set to True if ISUS is enabled. Set to false to disable
        ISUS.
    </Description><Science:ISUS.loadAtStartup/></DefineArg>

<!--
    <DefineArg Name="EnabledSeeStar"><Description>
        Automatically set to True if SeeStar/SCPI is enabled. Set false to
        override.
    </Description><Sensor:SCPI.loadAtStartup/></DefineArg>

    <DefineArg Name="SeeStarTimeout"><Description>
        Timeout to trigger/read trigger for SeeStar camera.
    </Description><Sensor:SCPI.sampleTime/></DefineArg>

    <DefineArg Name="SeeStarTriggerDepth"><Description>
        Trigger the camera above this depth.
    </Description><Units:meter/><Value>40.0</Value></DefineArg>
-->

    <DefineArg Name="LowPassWindowLength"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="PeakChlShallowBound"><Description>
        Shallow depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakChlDeepBound"><Description>
        Deep depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Depth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Latitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Longitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChl"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlDistance"><Units:degree/><Value>NaN</Value></DefineOutput>

    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData>
        <While>
            <Arg Name="EnabledISUS"/>
            <And><Universal:platform_pitch_angle/><Lt><Arg Name="SampleISUSmaxPitch"/></Lt><And><Universal:platform_pitch_angle/><Gt><Arg Name="SampleISUSminPitch"/></Gt><And><Arg Name="SampleISUS"/><Or><Arg Name="PeakDetectNO3Active"/></Or></And></And></And>
        </While>
        <Universal:mole_concentration_of_nitrate_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledTurbulence_NPS"/>
        </While>
        <Science:Turbulence_NPS.MicroCond/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <Aggregate  Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakChlShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakChlDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

<!--
    <Aggregate Id="RollSeeStar">

        <Description>
            Turn on the SeeStar/SCPI camera.
        </Description>

        <When>
            <Arg Name="EnabledSeeStar"/>
            <And><Universal:depth/><Lt><Arg Name="SeeStarTriggerDepth"/></Lt></And>
        </When>

        <ReadDatum Id="TriggerSeeStar">
            <Timeout Duration="SeeStarTimeout"/>
            <Sensor:SCPI.sampleSCPI/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="SeeStarTimeout"/></Setting>
        </Guidance:Wait>

    </Aggregate>
-->

</Aggregate>


2017-10-10T21:45:18.289Z,1507671918.289 [Default] Stopped
2017-10-10T21:45:18.289Z,1507671918.289 [Default](DEBUG): Aggregate::uninitialize Default
2017-10-10T21:45:18.289Z,1507671918.289 [Default:B.GoToSurface] Stopped
2017-10-10T21:45:18.289Z,1507671918.289 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-10-10T21:45:18.290Z,1507671918.290 [Default:CheckIn] Stopped
2017-10-10T21:45:18.290Z,1507671918.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-10-10T21:45:18.290Z,1507671918.290 [Default:CheckIn:Read_GPS] Stopped
2017-10-10T21:45:18.290Z,1507671918.290 [MissionManager](IMPORTANT): Started mission Science
2017-10-10T21:45:18.290Z,1507671918.290 [Science] Running Loop=1
2017-10-10T21:45:18.290Z,1507671918.290 [Science](DEBUG): Aggregate::initialize Science
2017-10-10T21:45:18.290Z,1507671918.290 [Science:A] Running Loop=1
2017-10-10T21:45:18.290Z,1507671918.290 [Science:B] Running Loop=1
2017-10-10T21:45:18.290Z,1507671918.290 [Science:C] Running Loop=1
2017-10-10T21:45:18.291Z,1507671918.291 [Science:D] Running Loop=1
2017-10-10T21:45:18.291Z,1507671918.291 [Science:E] Running Loop=1
2017-10-10T21:45:18.291Z,1507671918.291 [Science:F] Running Loop=1
2017-10-10T21:45:18.291Z,1507671918.291 [Science:PeakDetectChl] Running Loop=1
2017-10-10T21:45:18.292Z,1507671918.292 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2017-10-10T21:45:18.292Z,1507671918.292 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2017-10-10T21:45:18.292Z,1507671918.292 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-10-10T21:45:18.309Z,1507671918.309 [Science:HighestChlPeakReport] Running Loop=1
2017-10-10T21:45:18.309Z,1507671918.309 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2017-10-10T21:45:18.309Z,1507671918.309 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2017-10-10T21:45:18.309Z,1507671918.309 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2017-10-10T21:45:18.311Z,1507671918.311 [Science:PeakDetectNO3] Running Loop=1
2017-10-10T21:45:18.311Z,1507671918.311 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2017-10-10T21:45:18.325Z,1507671918.325 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2017-10-10T21:45:18.329Z,1507671918.329 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2017-10-10T21:45:18.329Z,1507671918.329 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-10-10T21:45:18.332Z,1507671918.332 [Science:PeakDetectNO3] Stopped
2017-10-10T21:45:18.332Z,1507671918.332 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2017-10-10T21:45:18.332Z,1507671918.332 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2017-10-10T21:45:18.333Z,1507671918.333 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-10-10T21:45:18.333Z,1507671918.333 [Science:HighestChlPeakReport] Stopped
2017-10-10T21:45:18.333Z,1507671918.333 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2017-10-10T21:45:18.333Z,1507671918.333 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2017-10-10T21:45:18.333Z,1507671918.333 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2017-10-10T21:45:18.333Z,1507671918.333 [Science:PeakDetectChl] Stopped
2017-10-10T21:45:18.333Z,1507671918.333 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2017-10-10T21:45:18.333Z,1507671918.333 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2017-10-10T21:45:18.333Z,1507671918.333 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-10-10T21:45:18.333Z,1507671918.333 [Science:F] Running Loop=1
2017-10-10T21:45:18.334Z,1507671918.334 [Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2017-10-10T21:45:18.337Z,1507671918.337 [Science:E] Stopped
2017-10-10T21:45:18.337Z,1507671918.337 [Science:D] Stopped
2017-10-10T21:45:18.337Z,1507671918.337 [Science:C] Running Loop=1
2017-10-10T21:45:18.337Z,1507671918.337 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2017-10-10T21:45:18.338Z,1507671918.338 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2017-10-10T21:45:18.341Z,1507671918.341 [Science:B] Stopped
2017-10-10T21:45:18.341Z,1507671918.341 [Science:A] Running Loop=1
2017-10-10T21:45:18.342Z,1507671918.342 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2017-10-10T21:45:18.372Z,1507671918.372 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2017-10-10T21:45:18.372Z,1507671918.372 [WetLabsBB2FL](INFO): Powering up
2017-10-10T21:45:18.396Z,1507671918.396 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2017-10-10T21:45:18.397Z,1507671918.397 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2017-10-10T21:45:22.626Z,1507671922.626 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103684 s
2017-10-10T21:45:22.785Z,1507671922.785 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103577 s
2017-10-10T21:45:23.124Z,1507671923.124 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103595 s
2017-10-10T21:45:23.538Z,1507671923.538 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103491 s
2017-10-10T21:45:23.918Z,1507671923.918 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103610 s
2017-10-10T21:45:24.313Z,1507671924.313 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103139 s
2017-10-10T21:45:24.699Z,1507671924.699 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107609 s
2017-10-10T21:45:25.100Z,1507671925.100 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105481 s
2017-10-10T21:45:25.499Z,1507671925.499 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103710 s
2017-10-10T21:45:25.920Z,1507671925.920 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103620 s
2017-10-10T21:45:26.297Z,1507671926.297 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103470 s
2017-10-10T21:45:26.699Z,1507671926.699 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103571 s
2017-10-10T21:45:27.098Z,1507671927.098 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103933 s
2017-10-10T21:45:27.518Z,1507671927.518 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101389 s
2017-10-10T21:45:27.896Z,1507671927.896 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103401 s
2017-10-10T21:45:28.301Z,1507671928.301 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103503 s
2017-10-10T21:45:28.696Z,1507671928.696 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103359 s
2017-10-10T21:45:29.122Z,1507671929.122 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103374 s
2017-10-10T21:45:29.518Z,1507671929.518 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.109420 s
2017-10-10T21:45:29.924Z,1507671929.924 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103685 s
2017-10-10T21:45:30.322Z,1507671930.322 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103633 s
2017-10-10T21:45:30.732Z,1507671930.732 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111298 s
2017-10-10T21:45:31.124Z,1507671931.124 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103597 s
2017-10-10T21:45:31.525Z,1507671931.525 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103599 s
2017-10-10T21:45:31.935Z,1507671931.935 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107507 s
2017-10-10T21:45:32.298Z,1507671932.298 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104323 s
2017-10-10T21:45:32.699Z,1507671932.699 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103592 s
2017-10-10T21:45:33.138Z,1507671933.138 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103514 s
2017-10-10T21:45:33.497Z,1507671933.497 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103380 s
2017-10-10T21:45:33.906Z,1507671933.906 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.115403 s
2017-10-10T21:45:34.301Z,1507671934.301 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101300 s
2017-10-10T21:45:34.700Z,1507671934.700 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103702 s
2017-10-10T21:45:35.105Z,1507671935.105 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103572 s
2017-10-10T21:45:35.550Z,1507671935.550 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103651 s
2017-10-10T21:45:35.925Z,1507671935.925 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103499 s
2017-10-10T21:45:36.320Z,1507671936.320 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103376 s
2017-10-10T21:45:36.756Z,1507671936.756 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104085 s
2017-10-10T21:45:37.097Z,1507671937.097 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103563 s
2017-10-10T21:45:37.499Z,1507671937.499 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103584 s
2017-10-10T21:45:37.900Z,1507671937.900 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103526 s
2017-10-10T21:45:38.362Z,1507671938.362 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103766 s
2017-10-10T21:45:38.765Z,1507671938.765 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102325 s
2017-10-10T21:45:39.110Z,1507671939.110 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102766 s
2017-10-10T21:45:39.510Z,1507671939.510 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103253 s
2017-10-10T21:45:39.940Z,1507671939.940 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103583 s
2017-10-10T21:45:40.302Z,1507671940.302 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101661 s
2017-10-10T21:45:40.697Z,1507671940.697 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103562 s
2017-10-10T21:45:41.105Z,1507671941.105 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111566 s
2017-10-10T21:45:41.504Z,1507671941.504 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.106348 s
2017-10-10T21:45:41.910Z,1507671941.910 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103520 s
2017-10-10T21:45:42.309Z,1507671942.309 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103584 s
2017-10-10T21:45:42.710Z,1507671942.710 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103530 s
2017-10-10T21:45:43.117Z,1507671943.117 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103137 s
2017-10-10T21:45:43.514Z,1507671943.514 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104149 s
2017-10-10T21:45:43.932Z,1507671943.932 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103695 s
2017-10-10T21:45:44.336Z,1507671944.336 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103683 s
2017-10-10T21:45:44.722Z,1507671944.722 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103415 s
2017-10-10T21:45:45.098Z,1507671945.098 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101736 s
2017-10-10T21:45:45.497Z,1507671945.497 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103385 s
2017-10-10T21:45:45.925Z,1507671945.925 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.109031 s
2017-10-10T21:45:46.317Z,1507671946.317 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104085 s
2017-10-10T21:45:46.698Z,1507671946.698 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103588 s
2017-10-10T21:45:47.136Z,1507671947.136 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103759 s
2017-10-10T21:45:47.278Z,1507671947.278 [CTD_NeilBrown](ERROR): serial buffer full
2017-10-10T21:45:47.279Z,1507671947.279 [CTD_NeilBrown] Communications Fault, FailCount= 1
2017-10-10T21:45:47.279Z,1507671947.279 [CTD_NeilBrown](ERROR): Communications Fault
2017-10-10T21:45:47.280Z,1507671947.280 [CTD_NeilBrown](FAULT): No CTD communication! Re-initializing
2017-10-10T21:45:47.512Z,1507671947.512 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2017-10-10T21:45:47.514Z,1507671947.514 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107873 s
2017-10-10T21:45:47.684Z,1507671947.684 [CTD_NeilBrown](INFO): Powering down
2017-10-10T21:45:47.899Z,1507671947.899 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102193 s
2017-10-10T21:45:48.314Z,1507671948.314 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103101 s
2017-10-10T21:45:48.745Z,1507671948.745 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103919 s
2017-10-10T21:45:49.123Z,1507671949.123 [CBIT](INFO): Clearing failed state for component CTD_NeilBrown
2017-10-10T21:45:49.123Z,1507671949.123 [CTD_NeilBrown] No Fault, FailCount= 1
2017-10-10T21:45:49.126Z,1507671949.126 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103674 s
2017-10-10T21:45:49.299Z,1507671949.299 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2017-10-10T21:45:49.300Z,1507671949.300 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2017-10-10T21:45:49.521Z,1507671949.521 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107481 s
2017-10-10T21:45:49.920Z,1507671949.920 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103620 s
2017-10-10T21:45:50.326Z,1507671950.326 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103627 s
2017-10-10T21:45:50.728Z,1507671950.728 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107716 s
2017-10-10T21:45:51.114Z,1507671951.114 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103873 s
2017-10-10T21:45:51.499Z,1507671951.499 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103804 s
2017-10-10T21:45:51.944Z,1507671951.944 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103591 s
2017-10-10T21:45:52.316Z,1507671952.316 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101858 s
2017-10-10T21:45:52.696Z,1507671952.696 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103581 s
2017-10-10T21:45:53.143Z,1507671953.143 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111561 s
2017-10-10T21:45:53.507Z,1507671953.507 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.109289 s
2017-10-10T21:45:53.900Z,1507671953.900 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103665 s
2017-10-10T21:45:54.306Z,1507671954.306 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103598 s
2017-10-10T21:45:54.705Z,1507671954.705 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103058 s
2017-10-10T21:45:55.124Z,1507671955.124 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104227 s
2017-10-10T21:45:55.509Z,1507671955.509 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103593 s
2017-10-10T21:45:55.940Z,1507671955.940 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102502 s
2017-10-10T21:45:56.314Z,1507671956.314 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103624 s
2017-10-10T21:45:56.714Z,1507671956.714 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103564 s
2017-10-10T21:45:57.122Z,1507671957.122 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103505 s
2017-10-10T21:45:57.518Z,1507671957.518 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107463 s
2017-10-10T21:45:57.920Z,1507671957.920 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103645 s
2017-10-10T21:45:58.408Z,1507671958.408 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103592 s
2017-10-10T21:45:58.718Z,1507671958.718 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103798 s
2017-10-10T21:45:59.124Z,1507671959.124 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103594 s
2017-10-10T21:45:59.522Z,1507671959.522 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103695 s
2017-10-10T21:45:59.910Z,1507671959.910 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111450 s
2017-10-10T21:46:00.312Z,1507671960.312 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103693 s
2017-10-10T21:46:00.711Z,1507671960.711 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103244 s
2017-10-10T21:46:01.097Z,1507671961.097 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103435 s
2017-10-10T21:46:01.501Z,1507671961.501 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:46:01.501Z,1507671961.501 [Aanderaa_O2] No Fault, FailCount= 3
2017-10-10T21:46:01.536Z,1507671961.536 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103412 s
2017-10-10T21:46:01.911Z,1507671961.911 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104025 s
2017-10-10T21:46:02.299Z,1507671962.299 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104086 s
2017-10-10T21:46:02.753Z,1507671962.753 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103230 s
2017-10-10T21:46:03.106Z,1507671963.106 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103587 s
2017-10-10T21:46:03.465Z,1507671963.465 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:46:03.466Z,1507671963.466 [Aanderaa_O2] Data Fault, FailCount= 1
2017-10-10T21:46:03.466Z,1507671963.466 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:46:03.528Z,1507671963.528 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:46:03.530Z,1507671963.530 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103814 s
2017-10-10T21:46:03.983Z,1507671963.983 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:46:04.126Z,1507671964.126 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103641 s
2017-10-10T21:46:04.311Z,1507671964.311 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103469 s
2017-10-10T21:46:04.725Z,1507671964.725 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:46:04.725Z,1507671964.725 [Aanderaa_O2] No Fault, FailCount= 1
2017-10-10T21:46:04.733Z,1507671964.733 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103368 s
2017-10-10T21:46:05.130Z,1507671965.130 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103597 s
2017-10-10T21:46:05.510Z,1507671965.510 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103383 s
2017-10-10T21:46:05.937Z,1507671965.937 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103590 s
2017-10-10T21:46:06.299Z,1507671966.299 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103537 s
2017-10-10T21:46:06.672Z,1507671966.672 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:46:06.673Z,1507671966.673 [Aanderaa_O2] Data Fault, FailCount= 2
2017-10-10T21:46:06.673Z,1507671966.673 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:46:06.697Z,1507671966.697 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:46:06.717Z,1507671966.717 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111458 s
2017-10-10T21:46:07.192Z,1507671967.192 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:46:07.290Z,1507671967.290 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101180 s
2017-10-10T21:46:07.527Z,1507671967.527 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103711 s
2017-10-10T21:46:07.902Z,1507671967.902 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-10-10T21:46:07.902Z,1507671967.902 [Aanderaa_O2] No Fault, FailCount= 2
2017-10-10T21:46:07.936Z,1507671967.936 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103453 s
2017-10-10T21:46:08.315Z,1507671968.315 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103298 s
2017-10-10T21:46:08.732Z,1507671968.732 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104194 s
2017-10-10T21:46:09.121Z,1507671969.121 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.109253 s
2017-10-10T21:46:09.516Z,1507671969.516 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111936 s
2017-10-10T21:46:09.865Z,1507671969.865 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo	4831F	14	Mode	AADI Smart Sensor Terminal Protocol	RS232 Protocol Version	3	Config Version	12

2017-10-10T21:46:09.865Z,1507671969.865 [Aanderaa_O2] Data Fault, FailCount= 3
2017-10-10T21:46:09.865Z,1507671969.865 [Aanderaa_O2](ERROR): Data Fault
2017-10-10T21:46:09.944Z,1507671969.944 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-10-10T21:46:09.944Z,1507671969.944 [CBIT](FAULT): Data Fault in component: Aanderaa_O2
2017-10-10T21:46:09.946Z,1507671969.946 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104054 s
2017-10-10T21:46:10.384Z,1507671970.384 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:46:10.469Z,1507671970.469 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104095 s
2017-10-10T21:46:10.744Z,1507671970.744 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.120849 s
2017-10-10T21:46:11.129Z,1507671971.129 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103587 s
2017-10-10T21:46:11.532Z,1507671971.532 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103411 s
2017-10-10T21:46:11.898Z,1507671971.898 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103576 s
2017-10-10T21:46:12.326Z,1507671972.326 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103631 s
2017-10-10T21:46:12.697Z,1507671972.697 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103563 s
2017-10-10T21:46:13.140Z,1507671973.140 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107483 s
2017-10-10T21:46:13.500Z,1507671973.500 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103631 s
2017-10-10T21:46:13.896Z,1507671973.896 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103447 s
2017-10-10T21:46:14.300Z,1507671974.300 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103578 s
2017-10-10T21:46:14.699Z,1507671974.699 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103449 s
2017-10-10T21:46:15.127Z,1507671975.127 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103852 s
2017-10-10T21:46:15.509Z,1507671975.509 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103531 s
2017-10-10T21:46:15.915Z,1507671975.915 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103182 s
2017-10-10T21:46:16.323Z,1507671976.323 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103602 s
2017-10-10T21:46:16.726Z,1507671976.726 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103550 s
2017-10-10T21:46:17.129Z,1507671977.129 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103154 s
2017-10-10T21:46:17.527Z,1507671977.527 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101804 s
2017-10-10T21:46:17.909Z,1507671977.909 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103627 s
2017-10-10T21:46:18.313Z,1507671978.313 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103879 s
2017-10-10T21:46:18.723Z,1507671978.723 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103573 s
2017-10-10T21:46:19.121Z,1507671979.121 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103435 s
2017-10-10T21:46:22.492Z,1507671982.492 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104530 s
2017-10-10T21:46:22.610Z,1507671982.610 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102608 s
2017-10-10T21:46:22.977Z,1507671982.977 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103499 s
2017-10-10T21:46:23.361Z,1507671983.361 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104706 s
2017-10-10T21:46:23.785Z,1507671983.785 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103509 s
2017-10-10T21:46:24.166Z,1507671984.166 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107311 s
2017-10-10T21:46:24.590Z,1507671984.590 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103778 s
2017-10-10T21:46:24.961Z,1507671984.961 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103637 s
2017-10-10T21:46:25.365Z,1507671985.365 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103458 s
2017-10-10T21:46:25.761Z,1507671985.761 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101796 s
2017-10-10T21:46:26.162Z,1507671986.162 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103026 s
2017-10-10T21:46:26.565Z,1507671986.565 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102943 s
2017-10-10T21:46:26.965Z,1507671986.965 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.109664 s
2017-10-10T21:46:27.361Z,1507671987.361 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103647 s
2017-10-10T21:46:27.765Z,1507671987.765 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103446 s
2017-10-10T21:46:28.196Z,1507671988.196 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103211 s
2017-10-10T21:46:28.563Z,1507671988.563 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103525 s
2017-10-10T21:46:29.026Z,1507671989.026 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103157 s
2017-10-10T21:46:29.369Z,1507671989.369 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103611 s
2017-10-10T21:46:29.766Z,1507671989.766 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103711 s
2017-10-10T21:46:30.166Z,1507671990.166 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103463 s
2017-10-10T21:46:30.573Z,1507671990.573 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103434 s
2017-10-10T21:46:30.970Z,1507671990.970 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103891 s
2017-10-10T21:46:31.373Z,1507671991.373 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.109003 s
2017-10-10T21:46:31.774Z,1507671991.774 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103246 s
2017-10-10T21:46:32.167Z,1507671992.167 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103672 s
2017-10-10T21:46:32.576Z,1507671992.576 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103395 s
2017-10-10T21:46:32.992Z,1507671992.992 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103608 s
2017-10-10T21:46:33.414Z,1507671993.414 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102850 s
2017-10-10T21:46:33.762Z,1507671993.762 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103512 s
2017-10-10T21:46:34.177Z,1507671994.177 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103556 s
2017-10-10T21:46:34.561Z,1507671994.561 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103477 s
2017-10-10T21:46:34.965Z,1507671994.965 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103546 s
2017-10-10T21:46:35.365Z,1507671995.365 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103353 s
2017-10-10T21:46:35.800Z,1507671995.800 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.110939 s
2017-10-10T21:46:36.156Z,1507671996.156 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-10T21:46:36.156Z,1507671996.156 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-10-10T21:46:36.156Z,1507671996.156 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-10-10T21:46:36.158Z,1507671996.158 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-10-10T21:46:36.159Z,1507671996.159 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-10-10T21:46:36.159Z,1507671996.159 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-10-10T21:46:36.173Z,1507671996.173 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-10-10T21:46:36.173Z,1507671996.173 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-10-10T21:46:36.175Z,1507671996.175 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103359 s
2017-10-10T21:46:36.571Z,1507671996.571 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-10-10T21:46:36.571Z,1507671996.571 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-10-10T21:46:36.572Z,1507671996.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-10-10T21:46:36.572Z,1507671996.572 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-10-10T21:46:36.574Z,1507671996.574 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101129 s
2017-10-10T21:46:36.953Z,1507671996.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-10-10T21:46:36.953Z,1507671996.953 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-10-10T21:46:36.954Z,1507671996.954 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-10-10T21:46:36.954Z,1507671996.954 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-10T21:46:36.955Z,1507671996.955 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-10T21:46:36.959Z,1507671996.959 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-10T21:46:36.986Z,1507671996.986 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103831 s
2017-10-10T21:46:37.391Z,1507671997.391 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103539 s
2017-10-10T21:46:37.779Z,1507671997.779 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102666 s
2017-10-10T21:46:38.180Z,1507671998.180 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.114972 s
2017-10-10T21:46:38.576Z,1507671998.576 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104076 s
2017-10-10T21:46:39.039Z,1507671999.039 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103632 s
2017-10-10T21:46:39.366Z,1507671999.366 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103672 s
2017-10-10T21:46:39.765Z,1507671999.765 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103474 s
2017-10-10T21:46:40.167Z,1507672000.167 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103475 s
2017-10-10T21:46:40.567Z,1507672000.567 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111508 s
2017-10-10T21:46:40.968Z,1507672000.968 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101703 s
2017-10-10T21:46:41.375Z,1507672001.375 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103499 s
2017-10-10T21:46:41.767Z,1507672001.767 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103141 s
2017-10-10T21:46:42.226Z,1507672002.226 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103751 s
2017-10-10T21:46:42.573Z,1507672002.573 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103220 s
2017-10-10T21:46:42.575Z,1507672002.575 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-10-10T21:46:42.576Z,1507672002.576 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-10-10T21:46:42.576Z,1507672002.576 [Rowe_600LCM](ERROR): Communications Fault
2017-10-10T21:46:42.980Z,1507672002.980 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-10T21:46:43.014Z,1507672003.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2017-10-10T21:46:43.073Z,1507672003.073 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-10-10T21:46:43.084Z,1507672003.084 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103306 s
2017-10-10T21:46:43.172Z,1507672003.172 [Rowe_600LCM](INFO): Powering down
2017-10-10T21:46:43.349Z,1507672003.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-10-10T21:46:43.390Z,1507672003.390 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104491 s
2017-10-10T21:46:43.757Z,1507672003.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-10-10T21:46:43.790Z,1507672003.790 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103606 s
2017-10-10T21:46:44.145Z,1507672004.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-10-10T21:46:44.179Z,1507672004.179 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103080 s
2017-10-10T21:46:44.552Z,1507672004.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-10-10T21:46:44.591Z,1507672004.591 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-10-10T21:46:44.592Z,1507672004.592 [Rowe_600LCM] No Fault, FailCount= 3
2017-10-10T21:46:44.594Z,1507672004.594 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103465 s
2017-10-10T21:46:44.787Z,1507672004.787 [Rowe_600LCM](INFO): Initializing
2017-10-10T21:46:44.789Z,1507672004.789 [Rowe_600LCM](INFO): Checking LCM
2017-10-10T21:46:44.789Z,1507672004.789 [Rowe_600LCM](INFO): LCM OK
2017-10-10T21:46:44.789Z,1507672004.789 [Rowe_600LCM](INFO): Powering up
2017-10-10T21:46:44.952Z,1507672004.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-10-10T21:46:45.350Z,1507672005.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-10-10T21:46:45.384Z,1507672005.384 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111810 s
2017-10-10T21:46:45.750Z,1507672005.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-10-10T21:46:45.767Z,1507672005.767 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105024 s
2017-10-10T21:46:46.146Z,1507672006.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-10-10T21:46:46.164Z,1507672006.164 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103604 s
2017-10-10T21:46:46.553Z,1507672006.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-10-10T21:46:46.590Z,1507672006.590 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103623 s
2017-10-10T21:46:46.954Z,1507672006.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-10-10T21:46:46.972Z,1507672006.972 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103581 s
2017-10-10T21:46:47.365Z,1507672007.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-10-10T21:46:47.408Z,1507672007.408 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111811 s
2017-10-10T21:46:47.757Z,1507672007.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-10-10T21:46:47.774Z,1507672007.774 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103826 s
2017-10-10T21:46:48.159Z,1507672008.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-10-10T21:46:48.177Z,1507672008.177 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103612 s
2017-10-10T21:46:48.556Z,1507672008.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-10-10T21:46:48.618Z,1507672008.618 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103068 s
2017-10-10T21:46:48.972Z,1507672008.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-10-10T21:46:49.028Z,1507672009.028 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-10-10T21:46:49.047Z,1507672009.047 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103304 s
2017-10-10T21:46:49.114Z,1507672009.114 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-10-10T21:46:49.115Z,1507672009.115 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-10-10T21:46:49.117Z,1507672009.117 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-10-10T21:46:49.117Z,1507672009.117 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-10-10T21:46:49.410Z,1507672009.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-10-10T21:46:49.449Z,1507672009.449 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103514 s
2017-10-10T21:46:49.779Z,1507672009.779 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107428 s
2017-10-10T21:46:50.193Z,1507672010.193 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101307 s
2017-10-10T21:46:50.568Z,1507672010.568 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103528 s
2017-10-10T21:46:50.989Z,1507672010.989 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103924 s
2017-10-10T21:46:51.373Z,1507672011.373 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103630 s
2017-10-10T21:46:51.814Z,1507672011.814 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103787 s
2017-10-10T21:46:52.170Z,1507672012.170 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111736 s
2017-10-10T21:46:52.580Z,1507672012.580 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104191 s
2017-10-10T21:46:52.997Z,1507672012.997 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105303 s
2017-10-10T21:46:53.430Z,1507672013.430 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104290 s
2017-10-10T21:46:53.793Z,1507672013.793 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103751 s
2017-10-10T21:46:54.180Z,1507672014.180 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103474 s
2017-10-10T21:46:54.580Z,1507672014.580 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103596 s
2017-10-10T21:46:55.025Z,1507672015.025 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103549 s
2017-10-10T21:46:55.393Z,1507672015.393 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103387 s
2017-10-10T21:46:55.764Z,1507672015.764 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103629 s
2017-10-10T21:46:56.166Z,1507672016.166 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103477 s
2017-10-10T21:46:56.569Z,1507672016.569 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103342 s
2017-10-10T21:46:56.995Z,1507672016.995 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111743 s
2017-10-10T21:46:57.372Z,1507672017.372 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103814 s
2017-10-10T21:46:57.776Z,1507672017.776 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103901 s
2017-10-10T21:46:58.163Z,1507672018.163 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103805 s
2017-10-10T21:46:58.585Z,1507672018.585 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103630 s
2017-10-10T21:46:58.983Z,1507672018.983 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103453 s
2017-10-10T21:46:59.396Z,1507672019.396 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107524 s
2017-10-10T21:46:59.799Z,1507672019.799 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103898 s
2017-10-10T21:47:00.178Z,1507672020.178 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103517 s
2017-10-10T21:47:00.574Z,1507672020.574 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103607 s
2017-10-10T21:47:00.965Z,1507672020.965 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103304 s
2017-10-10T21:47:01.076Z,1507672021.076 [CTD_NeilBrown](ERROR): serial buffer full
2017-10-10T21:47:01.076Z,1507672021.076 [CTD_NeilBrown] Communications Fault, FailCount= 2
2017-10-10T21:47:01.076Z,1507672021.076 [CTD_NeilBrown](ERROR): Communications Fault
2017-10-10T21:47:01.078Z,1507672021.078 [CTD_NeilBrown](FAULT): No CTD communication! Re-initializing
2017-10-10T21:47:01.423Z,1507672021.423 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2017-10-10T21:47:01.433Z,1507672021.433 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103602 s
2017-10-10T21:47:01.484Z,1507672021.484 [CTD_NeilBrown](INFO): Powering down
2017-10-10T21:47:01.781Z,1507672021.781 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.118543 s
2017-10-10T21:47:02.201Z,1507672022.201 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103892 s
2017-10-10T21:47:02.582Z,1507672022.582 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103620 s
2017-10-10T21:47:02.959Z,1507672022.959 [CBIT](INFO): Clearing failed state for component CTD_NeilBrown
2017-10-10T21:47:02.959Z,1507672022.959 [CTD_NeilBrown] No Fault, FailCount= 2
2017-10-10T21:47:02.978Z,1507672022.978 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103757 s
2017-10-10T21:47:03.100Z,1507672023.100 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2017-10-10T21:47:03.100Z,1507672023.100 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2017-10-10T21:47:03.365Z,1507672023.365 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103426 s
2017-10-10T21:47:03.762Z,1507672023.762 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103497 s
2017-10-10T21:47:04.183Z,1507672024.183 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103281 s
2017-10-10T21:47:04.564Z,1507672024.564 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.100607 s
2017-10-10T21:47:04.959Z,1507672024.959 [CTD_NeilBrown](DEBUG): serial timeout
2017-10-10T21:47:04.994Z,1507672024.994 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103375 s
2017-10-10T21:47:05.372Z,1507672025.372 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103091 s
2017-10-10T21:47:05.766Z,1507672025.766 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103498 s
2017-10-10T21:47:06.166Z,1507672026.166 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104139 s
2017-10-10T21:47:06.569Z,1507672026.569 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111753 s
2017-10-10T21:47:06.967Z,1507672026.967 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104455 s
2017-10-10T21:47:07.365Z,1507672027.365 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103619 s
2017-10-10T21:47:07.853Z,1507672027.853 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107333 s
2017-10-10T21:47:08.178Z,1507672028.178 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104072 s
2017-10-10T21:47:08.577Z,1507672028.577 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103495 s
2017-10-10T21:47:08.978Z,1507672028.978 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103453 s
2017-10-10T21:47:09.385Z,1507672029.385 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104520 s
2017-10-10T21:47:09.864Z,1507672029.864 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103560 s
2017-10-10T21:47:10.174Z,1507672030.174 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105721 s
2017-10-10T21:47:10.579Z,1507672030.579 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103515 s
2017-10-10T21:47:10.961Z,1507672030.961 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102888 s
2017-10-10T21:47:11.366Z,1507672031.366 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103880 s
2017-10-10T21:47:11.795Z,1507672031.795 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104527 s
2017-10-10T21:47:12.161Z,1507672032.161 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103577 s
2017-10-10T21:47:12.566Z,1507672032.566 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103556 s
2017-10-10T21:47:12.878Z,1507672032.878 [CTD_NeilBrown](DEBUG): serial timeout
2017-10-10T21:47:12.878Z,1507672032.878 [CTD_NeilBrown](FAULT): No CTD communication! Re-initializing
2017-10-10T21:47:12.878Z,1507672032.878 [CTD_NeilBrown] Communications Fault, FailCount= 3
2017-10-10T21:47:12.878Z,1507672032.878 [CTD_NeilBrown](ERROR): Communications Fault
2017-10-10T21:47:13.021Z,1507672033.021 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2017-10-10T21:47:13.022Z,1507672033.022 [CBIT](FAULT): Communications Fault in component: CTD_NeilBrown
2017-10-10T21:47:13.028Z,1507672033.028 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103437 s
2017-10-10T21:47:13.288Z,1507672033.288 [CTD_NeilBrown](INFO): Powering down
2017-10-10T21:47:13.412Z,1507672033.412 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103563 s
2017-10-10T21:47:13.798Z,1507672033.798 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.100330 s
2017-10-10T21:47:14.168Z,1507672034.168 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104407 s
2017-10-10T21:47:14.592Z,1507672034.592 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103441 s
2017-10-10T21:47:15.373Z,1507672035.373 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103538 s
2017-10-10T21:47:15.781Z,1507672035.781 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101934 s
2017-10-10T21:47:16.162Z,1507672036.162 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.110888 s
2017-10-10T21:47:16.564Z,1507672036.564 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101311 s
2017-10-10T21:47:16.962Z,1507672036.962 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103606 s
2017-10-10T21:47:17.437Z,1507672037.437 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103348 s
2017-10-10T21:47:17.765Z,1507672037.765 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103378 s
2017-10-10T21:47:18.184Z,1507672038.184 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103078 s
2017-10-10T21:47:18.589Z,1507672038.589 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.110099 s
2017-10-10T21:47:18.964Z,1507672038.964 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103509 s
2017-10-10T21:47:19.367Z,1507672039.367 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104085 s
2017-10-10T21:47:22.777Z,1507672042.777 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103555 s
2017-10-10T21:47:22.930Z,1507672042.930 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103525 s
2017-10-10T21:47:23.264Z,1507672043.264 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107470 s
2017-10-10T21:47:23.666Z,1507672043.666 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102080 s
2017-10-10T21:47:24.048Z,1507672044.048 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103607 s
2017-10-10T21:47:24.470Z,1507672044.470 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103615 s
2017-10-10T21:47:24.853Z,1507672044.853 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103592 s
2017-10-10T21:47:25.249Z,1507672045.249 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103492 s
2017-10-10T21:47:25.652Z,1507672045.652 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.115775 s
2017-10-10T21:47:26.068Z,1507672046.068 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105809 s
2017-10-10T21:47:26.456Z,1507672046.456 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103480 s
2017-10-10T21:47:26.852Z,1507672046.852 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103609 s
2017-10-10T21:47:27.293Z,1507672047.293 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104145 s
2017-10-10T21:47:27.678Z,1507672047.678 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103811 s
2017-10-10T21:47:28.065Z,1507672048.065 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101777 s
2017-10-10T21:47:28.460Z,1507672048.460 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103913 s
2017-10-10T21:47:28.871Z,1507672048.871 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104102 s
2017-10-10T21:47:29.281Z,1507672049.281 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103578 s
2017-10-10T21:47:29.648Z,1507672049.648 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103431 s
2017-10-10T21:47:30.131Z,1507672050.131 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103199 s
2017-10-10T21:47:30.472Z,1507672050.472 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103516 s
2017-10-10T21:47:30.872Z,1507672050.872 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103554 s
2017-10-10T21:47:31.270Z,1507672051.270 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103529 s
2017-10-10T21:47:31.668Z,1507672051.668 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103646 s
2017-10-10T21:47:32.069Z,1507672052.069 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103512 s
2017-10-10T21:47:32.476Z,1507672052.476 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111763 s
2017-10-10T21:47:32.878Z,1507672052.878 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103867 s
2017-10-10T21:47:33.306Z,1507672053.306 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107642 s
2017-10-10T21:47:33.654Z,1507672053.654 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103658 s
2017-10-10T21:47:34.060Z,1507672054.060 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103533 s
2017-10-10T21:47:34.454Z,1507672054.454 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101702 s
2017-10-10T21:47:34.856Z,1507672054.856 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.108318 s
2017-10-10T21:47:35.267Z,1507672055.267 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107434 s
2017-10-10T21:47:35.669Z,1507672055.669 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103639 s
2017-10-10T21:47:36.073Z,1507672056.073 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103712 s
2017-10-10T21:47:36.494Z,1507672056.494 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111655 s
2017-10-10T21:47:36.855Z,1507672056.855 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111615 s
2017-10-10T21:47:37.253Z,1507672057.253 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104129 s
2017-10-10T21:47:37.654Z,1507672057.654 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103670 s
2017-10-10T21:47:38.059Z,1507672058.059 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103536 s
2017-10-10T21:47:38.448Z,1507672058.448 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103528 s
2017-10-10T21:47:38.853Z,1507672058.853 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103504 s
2017-10-10T21:47:39.253Z,1507672059.253 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.108511 s
2017-10-10T21:47:39.684Z,1507672059.684 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103680 s
2017-10-10T21:47:40.090Z,1507672060.090 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105444 s
2017-10-10T21:47:40.480Z,1507672060.480 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103532 s
2017-10-10T21:47:40.866Z,1507672060.866 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103743 s
2017-10-10T21:47:41.268Z,1507672061.268 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103506 s
2017-10-10T21:47:41.668Z,1507672061.668 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.106767 s
2017-10-10T21:47:42.066Z,1507672062.066 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104334 s
2017-10-10T21:47:42.482Z,1507672062.482 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103787 s
2017-10-10T21:47:42.896Z,1507672062.896 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103534 s
2017-10-10T21:47:43.245Z,1507672063.245 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103391 s
2017-10-10T21:47:43.654Z,1507672063.654 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.112041 s
2017-10-10T21:47:44.055Z,1507672064.055 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101600 s
2017-10-10T21:47:44.471Z,1507672064.471 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103858 s
2017-10-10T21:47:44.847Z,1507672064.847 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103491 s
2017-10-10T21:47:45.252Z,1507672065.252 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103434 s
2017-10-10T21:47:45.654Z,1507672065.654 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103495 s
2017-10-10T21:47:46.094Z,1507672066.094 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103537 s
2017-10-10T21:47:46.462Z,1507672066.462 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103838 s
2017-10-10T21:47:46.852Z,1507672066.852 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101818 s
2017-10-10T21:47:47.278Z,1507672067.278 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103600 s
2017-10-10T21:47:47.686Z,1507672067.686 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103507 s
2017-10-10T21:47:48.068Z,1507672068.068 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103489 s
2017-10-10T21:47:48.466Z,1507672068.466 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111747 s
2017-10-10T21:47:48.884Z,1507672068.884 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104394 s
2017-10-10T21:47:49.321Z,1507672069.321 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103502 s
2017-10-10T21:47:49.680Z,1507672069.680 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.119873 s
2017-10-10T21:47:50.101Z,1507672070.101 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103728 s
2017-10-10T21:47:50.465Z,1507672070.465 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107630 s
2017-10-10T21:47:50.862Z,1507672070.862 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103872 s
2017-10-10T21:47:51.267Z,1507672071.267 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103507 s
2017-10-10T21:47:51.668Z,1507672071.668 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103623 s
2017-10-10T21:47:52.050Z,1507672072.050 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103492 s
2017-10-10T21:47:52.471Z,1507672072.471 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111135 s
2017-10-10T21:47:52.856Z,1507672072.856 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103793 s
2017-10-10T21:47:53.258Z,1507672073.258 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102724 s
2017-10-10T21:47:53.654Z,1507672073.654 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103550 s
2017-10-10T21:47:54.053Z,1507672074.053 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103628 s
2017-10-10T21:47:54.458Z,1507672074.458 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.106886 s
2017-10-10T21:47:54.861Z,1507672074.861 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107532 s
2017-10-10T21:47:55.256Z,1507672075.256 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103740 s
2017-10-10T21:47:55.700Z,1507672075.700 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.112289 s
2017-10-10T21:47:56.081Z,1507672076.081 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103635 s
2017-10-10T21:47:56.482Z,1507672076.482 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103612 s
2017-10-10T21:47:56.872Z,1507672076.872 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103471 s
2017-10-10T21:47:57.278Z,1507672077.278 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103560 s
2017-10-10T21:47:57.692Z,1507672077.692 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.106001 s
2017-10-10T21:47:58.146Z,1507672078.146 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103124 s
2017-10-10T21:47:58.497Z,1507672078.497 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103470 s
2017-10-10T21:47:58.993Z,1507672078.993 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103502 s
2017-10-10T21:47:59.333Z,1507672079.333 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103042 s
2017-10-10T21:47:59.734Z,1507672079.734 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103402 s
2017-10-10T21:48:00.079Z,1507672080.079 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104154 s
2017-10-10T21:48:00.469Z,1507672080.469 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103445 s
2017-10-10T21:48:00.869Z,1507672080.869 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103520 s
2017-10-10T21:48:01.278Z,1507672081.278 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103301 s
2017-10-10T21:48:01.670Z,1507672081.670 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103494 s
2017-10-10T21:48:02.075Z,1507672082.075 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107652 s
2017-10-10T21:48:02.543Z,1507672082.543 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102715 s
2017-10-10T21:48:02.870Z,1507672082.870 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103618 s
2017-10-10T21:48:03.255Z,1507672083.255 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103599 s
2017-10-10T21:48:03.657Z,1507672083.657 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103635 s
2017-10-10T21:48:04.047Z,1507672084.047 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102114 s
2017-10-10T21:48:04.500Z,1507672084.500 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.111028 s
2017-10-10T21:48:04.919Z,1507672084.919 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103627 s
2017-10-10T21:48:05.324Z,1507672085.324 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103463 s
2017-10-10T21:48:05.757Z,1507672085.757 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103233 s
2017-10-10T21:48:06.214Z,1507672086.214 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.118980 s
2017-10-10T21:48:06.538Z,1507672086.538 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102610 s
2017-10-10T21:48:07.033Z,1507672087.033 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103232 s
2017-10-10T21:48:07.280Z,1507672087.280 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105899 s
2017-10-10T21:48:07.723Z,1507672087.723 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103460 s
2017-10-10T21:48:08.127Z,1507672088.127 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103731 s
2017-10-10T21:48:08.480Z,1507672088.480 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107759 s
2017-10-10T21:48:08.986Z,1507672088.986 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103042 s
2017-10-10T21:48:09.348Z,1507672089.348 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103601 s
2017-10-10T21:48:09.799Z,1507672089.799 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103473 s
2017-10-10T21:48:10.120Z,1507672090.120 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103393 s
2017-10-10T21:48:10.517Z,1507672090.517 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103924 s
2017-10-10T21:48:10.861Z,1507672090.861 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103317 s
2017-10-10T21:48:11.281Z,1507672091.281 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103594 s
2017-10-10T21:48:11.669Z,1507672091.669 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103607 s
2017-10-10T21:48:12.069Z,1507672092.069 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103547 s
2017-10-10T21:48:12.530Z,1507672092.530 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103717 s
2017-10-10T21:48:12.880Z,1507672092.880 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103502 s
2017-10-10T21:48:13.278Z,1507672093.278 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.119049 s
2017-10-10T21:48:13.658Z,1507672093.658 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103507 s
2017-10-10T21:48:14.065Z,1507672094.065 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103559 s
2017-10-10T21:48:14.450Z,1507672094.450 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103648 s
2017-10-10T21:48:14.849Z,1507672094.849 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103520 s
2017-10-10T21:48:15.361Z,1507672095.361 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103437 s
2017-10-10T21:48:15.774Z,1507672095.774 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103531 s
2017-10-10T21:48:16.054Z,1507672096.054 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103405 s
2017-10-10T21:48:16.476Z,1507672096.476 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103654 s
2017-10-10T21:48:16.858Z,1507672096.858 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103402 s
2017-10-10T21:48:17.259Z,1507672097.259 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103506 s
2017-10-10T21:48:17.662Z,1507672097.662 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104683 s
2017-10-10T21:48:18.079Z,1507672098.079 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103514 s
2017-10-10T21:48:18.469Z,1507672098.469 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103553 s
2017-10-10T21:48:18.874Z,1507672098.874 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103597 s
2017-10-10T21:48:19.278Z,1507672099.278 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103510 s
2017-10-10T21:48:19.682Z,1507672099.682 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103661 s
2017-10-10T21:48:23.072Z,1507672103.072 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104233 s
2017-10-10T21:48:23.200Z,1507672103.200 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102719 s
2017-10-10T21:48:23.552Z,1507672103.552 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103617 s
2017-10-10T21:48:23.950Z,1507672103.950 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103626 s
2017-10-10T21:48:24.346Z,1507672104.346 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.102102 s
2017-10-10T21:48:24.572Z,1507672104.572 [CommandLine](IMPORTANT): got command restart application
2017-10-10T21:48:24.755Z,1507672104.755 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.101710 s
2017-10-10T21:48:25.156Z,1507672105.156 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.107506 s
2017-10-10T21:48:25.577Z,1507672105.577 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.104187 s
2017-10-10T21:48:25.592Z,1507672105.592 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-10-10T21:48:25.598Z,1507672105.598 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:25.599Z,1507672105.599 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:25.599Z,1507672105.599 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-10-10T21:48:25.600Z,1507672105.600 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:25.600Z,1507672105.600 [CommandLine](INFO): Join timeout helper Thread ID is 1725
2017-10-10T21:48:25.601Z,1507672105.601 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-10-10T21:48:25.601Z,1507672105.601 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:25.602Z,1507672105.602 [NavChartDb](INFO): Join timeout helper Thread ID is 1726
2017-10-10T21:48:25.939Z,1507672105.939 [MissionManager](INFO): MissionManager is completed.
2017-10-10T21:48:25.943Z,1507672105.943 [MissionManager](INFO): Uninitializing Mission Science
2017-10-10T21:48:25.943Z,1507672105.943 [Science] Stopped
2017-10-10T21:48:25.943Z,1507672105.943 [Science](DEBUG): Aggregate::uninitialize Science
2017-10-10T21:48:25.943Z,1507672105.943 [Science:A] Stopped
2017-10-10T21:48:25.943Z,1507672105.943 [Science:C] Stopped
2017-10-10T21:48:25.943Z,1507672105.943 [Science:F] Stopped
2017-10-10T21:48:25.991Z,1507672105.991 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:25.992Z,1507672105.992 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:25.998Z,1507672105.998 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.105829 s
2017-10-10T21:48:26.007Z,1507672106.007 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-10-10T21:48:26.007Z,1507672106.007 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.008Z,1507672106.008 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1727
2017-10-10T21:48:26.028Z,1507672106.028 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:26.124Z,1507672106.124 [WetLabsBB2FL](INFO): Powering down
2017-10-10T21:48:26.125Z,1507672106.125 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.135Z,1507672106.135 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-10-10T21:48:26.135Z,1507672106.135 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.136Z,1507672106.136 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1728
2017-10-10T21:48:26.329Z,1507672106.329 [MissionManager](IMPORTANT): Started mission Default
2017-10-10T21:48:26.330Z,1507672106.330 [Default] Running Loop=1
2017-10-10T21:48:26.330Z,1507672106.330 [Default](DEBUG): Aggregate::initialize Default
2017-10-10T21:48:26.330Z,1507672106.330 [Default:B.GoToSurface] Running Loop=1
2017-10-10T21:48:26.330Z,1507672106.330 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-10-10T21:48:26.330Z,1507672106.330 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-10-10T21:48:26.330Z,1507672106.330 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-10-10T21:48:26.331Z,1507672106.331 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-10-10T21:48:26.335Z,1507672106.335 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-10-10T21:48:26.336Z,1507672106.336 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-10-10T21:48:26.336Z,1507672106.336 [Default:A.Wait] Running Loop=1
2017-10-10T21:48:26.336Z,1507672106.336 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-10-10T21:48:26.367Z,1507672106.367 [Reporter](INFO): WetLabsBB2FL.durationOfLastRun 0.103611 s
2017-10-10T21:48:26.419Z,1507672106.419 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:26.419Z,1507672106.419 [CTD_NeilBrown](INFO): Powering down
2017-10-10T21:48:26.420Z,1507672106.420 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.435Z,1507672106.435 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-10-10T21:48:26.435Z,1507672106.435 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.436Z,1507672106.436 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1729
2017-10-10T21:48:26.759Z,1507672106.759 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:26.760Z,1507672106.760 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-10-10T21:48:26.886Z,1507672106.886 [Rowe_600LCM](INFO): Powering down
2017-10-10T21:48:26.892Z,1507672106.892 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.896Z,1507672106.896 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-10-10T21:48:26.896Z,1507672106.896 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:26.897Z,1507672106.897 [Radio_Surface](INFO): Join timeout helper Thread ID is 1731
2017-10-10T21:48:27.119Z,1507672107.119 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:27.120Z,1507672107.120 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.136Z,1507672107.136 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-10-10T21:48:27.136Z,1507672107.136 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.137Z,1507672107.137 [logger](INFO): Join timeout helper Thread ID is 1732
2017-10-10T21:48:27.139Z,1507672107.139 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:27.140Z,1507672107.140 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.150Z,1507672107.150 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-10-10T21:48:27.150Z,1507672107.150 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.151Z,1507672107.151 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-10-10T21:48:27.151Z,1507672107.151 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.154Z,1507672107.154 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-10T21:48:27.154Z,1507672107.154 [controlThread](DEBUG): Uninitializing ControlThread
2017-10-10T21:48:27.155Z,1507672107.155 [PNI_TCM](INFO): Powering down
2017-10-10T21:48:27.156Z,1507672107.156 [controlThread](INFO): Join timeout helper Thread ID is 1733
2017-10-10T21:48:27.243Z,1507672107.243 [Aanderaa_O2](INFO): Powering down
2017-10-10T21:48:27.246Z,1507672107.246 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-10T21:48:27.248Z,1507672107.248 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-10-10T21:48:27.249Z,1507672107.249 [MissionManager](INFO): Uninitializing Mission Default
2017-10-10T21:48:27.249Z,1507672107.249 [Default] Stopped
2017-10-10T21:48:27.249Z,1507672107.249 [Default](DEBUG): Aggregate::uninitialize Default
2017-10-10T21:48:27.249Z,1507672107.249 [Default:A.Wait] Stopped
2017-10-10T21:48:27.249Z,1507672107.249 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-10-10T21:48:27.249Z,1507672107.249 [Default:B.GoToSurface] Stopped
2017-10-10T21:48:27.249Z,1507672107.249 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-10-10T21:48:27.253Z,1507672107.253 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-10-10T21:48:27.254Z,1507672107.254 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-10-10T21:48:27.254Z,1507672107.254 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-10-10T21:48:27.255Z,1507672107.255 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-10-10T21:48:27.255Z,1507672107.255 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-10-10T21:48:27.256Z,1507672107.256 [BuoyancyServo](INFO): Powering down
2017-10-10T21:48:27.267Z,1507672107.267 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-10-10T21:48:27.267Z,1507672107.267 [ElevatorServo](INFO): Powering down
2017-10-10T21:48:27.268Z,1507672107.268 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-10-10T21:48:27.268Z,1507672107.268 [RudderServo](INFO): Powering down
2017-10-10T21:48:27.269Z,1507672107.269 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-10-10T21:48:27.269Z,1507672107.269 [ThrusterServo](INFO): Powering down
2017-10-10T21:48:27.270Z,1507672107.270 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-10-10T21:48:27.271Z,1507672107.271 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-10-10T21:48:27.271Z,1507672107.271 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-10-10T21:48:27.272Z,1507672107.272 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.312Z,1507672107.312 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.322Z,1507672107.322 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.360Z,1507672107.360 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.366Z,1507672107.366 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.444Z,1507672107.444 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-10T21:48:27.520Z,1507672107.520 [logger ThreadHandler](INFO): Thread cancelled.