2017-09-05T20:41:38.147Z,1504644098.147 [Supervisor](DEBUG): Initializing supervisor.
2017-09-05T20:41:38.150Z,1504644098.150 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-09-05T20:41:38.151Z,1504644098.151 [SyncHandler](INFO): Protected caller Thread ID is 9474
2017-09-05T20:41:38.151Z,1504644098.151 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-09-05T20:41:38.152Z,1504644098.152 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-09-05T20:41:38.153Z,1504644098.153 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9475
2017-09-05T20:41:38.171Z,1504644098.171 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-09-05T20:41:38.183Z,1504644098.183 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-09-05T20:41:38.184Z,1504644098.184 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-09-05T20:41:38.184Z,1504644098.184 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9476
2017-09-05T20:41:38.185Z,1504644098.185 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-09-05T20:41:38.186Z,1504644098.186 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-09-05T20:41:38.186Z,1504644098.186 [logger ThreadHandler](INFO): Protected caller Thread ID is 9477
2017-09-05T20:41:38.188Z,1504644098.188 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-09-05T20:41:38.189Z,1504644098.189 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-09-05T20:41:38.190Z,1504644098.190 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-09-05T20:41:38.564Z,1504644098.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-09-05T20:41:38.565Z,1504644098.565 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-09-05T20:41:38.696Z,1504644098.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-09-05T20:41:38.696Z,1504644098.696 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-09-05T20:41:39.059Z,1504644099.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-09-05T20:41:39.060Z,1504644099.060 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-09-05T20:41:39.198Z,1504644099.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-09-05T20:41:39.199Z,1504644099.199 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-09-05T20:41:39.283Z,1504644099.283 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-09-05T20:41:39.476Z,1504644099.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-09-05T20:41:39.477Z,1504644099.477 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-09-05T20:41:39.563Z,1504644099.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-09-05T20:41:39.563Z,1504644099.563 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-09-05T20:41:39.842Z,1504644099.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-09-05T20:41:39.843Z,1504644099.843 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-09-05T20:41:40.187Z,1504644100.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-09-05T20:41:40.188Z,1504644100.188 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-09-05T20:41:40.486Z,1504644100.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-09-05T20:41:40.487Z,1504644100.487 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-09-05T20:41:40.979Z,1504644100.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-09-05T20:41:40.980Z,1504644100.980 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-09-05T20:41:41.171Z,1504644101.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-09-05T20:41:41.171Z,1504644101.171 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-09-05T20:41:41.272Z,1504644101.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-09-05T20:41:41.272Z,1504644101.272 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-09-05T20:41:41.791Z,1504644101.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-09-05T20:41:41.792Z,1504644101.792 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-09-05T20:41:44.792Z,1504644104.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-09-05T20:41:44.794Z,1504644104.794 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-09-05T20:41:44.795Z,1504644104.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-09-05T20:41:45.030Z,1504644105.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-09-05T20:41:45.030Z,1504644105.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-09-05T20:41:45.132Z,1504644105.132 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-09-05T20:41:45.225Z,1504644105.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-09-05T20:41:45.328Z,1504644105.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-09-05T20:41:45.418Z,1504644105.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-09-05T20:41:45.543Z,1504644105.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-09-05T20:41:45.695Z,1504644105.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-09-05T20:41:45.793Z,1504644105.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-09-05T20:41:45.888Z,1504644105.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-09-05T20:41:45.975Z,1504644105.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-09-05T20:41:46.148Z,1504644106.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-09-05T20:41:46.229Z,1504644106.229 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2017-09-05T20:41:46.230Z,1504644106.230 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-09-05T20:41:46.244Z,1504644106.244 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-09-05T20:41:46.379Z,1504644106.379 [SBIT](DEBUG): Construct Startup Built In Test.
2017-09-05T20:41:46.409Z,1504644106.409 [SBIT] Loaded
2017-09-05T20:41:46.410Z,1504644106.410 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-09-05T20:41:46.410Z,1504644106.410 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-09-05T20:41:46.438Z,1504644106.438 [IBIT] Loaded
2017-09-05T20:41:46.438Z,1504644106.438 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-09-05T20:41:46.442Z,1504644106.442 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-09-05T20:41:46.591Z,1504644106.591 [CBIT] Loaded
2017-09-05T20:41:46.592Z,1504644106.592 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-09-05T20:41:46.592Z,1504644106.592 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-09-05T20:41:46.593Z,1504644106.593 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-09-05T20:41:46.661Z,1504644106.661 [VerticalControl](DEBUG): Construct VerticalControl.
2017-09-05T20:41:46.758Z,1504644106.758 [VerticalControl] Loaded
2017-09-05T20:41:46.759Z,1504644106.759 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-09-05T20:41:46.759Z,1504644106.759 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-09-05T20:41:46.821Z,1504644106.821 [HorizontalControl] Loaded
2017-09-05T20:41:46.821Z,1504644106.821 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-09-05T20:41:46.822Z,1504644106.822 [SpeedControl](DEBUG): Construct SpeedControl.
2017-09-05T20:41:46.828Z,1504644106.828 [SpeedControl] Loaded
2017-09-05T20:41:46.828Z,1504644106.828 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-09-05T20:41:46.829Z,1504644106.829 [LoopControl](DEBUG): Construct LoopControl.
2017-09-05T20:41:46.829Z,1504644106.829 [LoopControl] Loaded
2017-09-05T20:41:46.829Z,1504644106.829 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-09-05T20:41:46.830Z,1504644106.830 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-09-05T20:41:46.830Z,1504644106.830 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-09-05T20:41:46.857Z,1504644106.857 [DepthRateCalculator] Loaded
2017-09-05T20:41:46.857Z,1504644106.857 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-09-05T20:41:46.862Z,1504644106.862 [PitchRateCalculator] Loaded
2017-09-05T20:41:46.862Z,1504644106.862 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-09-05T20:41:46.878Z,1504644106.878 [SpeedCalculator] Loaded
2017-09-05T20:41:46.878Z,1504644106.878 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-09-05T20:41:46.899Z,1504644106.899 [TempGradientCalculator] Loaded
2017-09-05T20:41:46.900Z,1504644106.900 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-09-05T20:41:46.915Z,1504644106.915 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-09-05T20:41:46.915Z,1504644106.915 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-09-05T20:41:46.920Z,1504644106.920 [YawRateCalculator] Loaded
2017-09-05T20:41:46.920Z,1504644106.920 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-09-05T20:41:46.921Z,1504644106.921 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-09-05T20:41:46.922Z,1504644106.922 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-09-05T20:41:47.005Z,1504644107.005 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-09-05T20:41:47.005Z,1504644107.005 [StratificationFrontDetector](DEBUG): (re)initializing
2017-09-05T20:41:47.005Z,1504644107.005 [StratificationFrontDetector] Loaded
2017-09-05T20:41:47.006Z,1504644107.006 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-09-05T20:41:47.006Z,1504644107.006 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-09-05T20:41:47.007Z,1504644107.007 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-09-05T20:41:47.240Z,1504644107.240 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-09-05T20:41:47.241Z,1504644107.241 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-09-05T20:41:49.474Z,1504644109.474 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-09-05T20:41:49.474Z,1504644109.474 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-09-05T20:41:49.528Z,1504644109.528 [DeadReckonUsingSpeedCalculator] Loaded
2017-09-05T20:41:49.528Z,1504644109.528 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-09-05T20:41:49.545Z,1504644109.545 [NavChart] Loaded
2017-09-05T20:41:49.545Z,1504644109.545 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-09-05T20:41:49.549Z,1504644109.549 [UniversalFixResidualReporter] Loaded
2017-09-05T20:41:49.550Z,1504644109.550 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-09-05T20:41:49.550Z,1504644109.550 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-09-05T20:41:49.551Z,1504644109.551 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-09-05T20:41:49.564Z,1504644109.564 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-09-05T20:41:49.565Z,1504644109.565 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-09-05T20:41:49.710Z,1504644109.710 [Aanderaa_O2] Loaded
2017-09-05T20:41:49.711Z,1504644109.711 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-09-05T20:41:49.727Z,1504644109.727 [CANONSampler] Loaded
2017-09-05T20:41:49.727Z,1504644109.727 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2017-09-05T20:41:49.807Z,1504644109.807 [CTD_NeilBrown] Loaded
2017-09-05T20:41:49.807Z,1504644109.807 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-09-05T20:41:49.808Z,1504644109.808 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407834E0
2017-09-05T20:41:49.809Z,1504644109.809 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9560
2017-09-05T20:41:49.842Z,1504644109.842 [CTD_Seabird] Loaded
2017-09-05T20:41:49.842Z,1504644109.842 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-09-05T20:41:49.844Z,1504644109.844 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407B34E0
2017-09-05T20:41:49.844Z,1504644109.844 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9561
2017-09-05T20:41:49.858Z,1504644109.858 [PAR_Licor] Loaded
2017-09-05T20:41:49.859Z,1504644109.859 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-09-05T20:41:49.907Z,1504644109.907 [WetLabsBB2FL] Loaded
2017-09-05T20:41:49.908Z,1504644109.908 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-09-05T20:41:49.909Z,1504644109.909 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407E34E0
2017-09-05T20:41:49.909Z,1504644109.909 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9562
2017-09-05T20:41:49.909Z,1504644109.909 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-09-05T20:41:49.910Z,1504644109.910 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-09-05T20:41:50.669Z,1504644110.669 [DataOverHttps] Loaded
2017-09-05T20:41:50.669Z,1504644110.669 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-09-05T20:41:50.682Z,1504644110.682 [Depth_Keller] Loaded
2017-09-05T20:41:50.682Z,1504644110.682 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-09-05T20:41:50.687Z,1504644110.687 [DropWeight] Loaded
2017-09-05T20:41:50.688Z,1504644110.688 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-09-05T20:41:50.778Z,1504644110.778 [NAL9602] Loaded
2017-09-05T20:41:50.778Z,1504644110.778 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-09-05T20:41:50.787Z,1504644110.787 [Onboard] Loaded
2017-09-05T20:41:50.787Z,1504644110.787 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-09-05T20:41:50.794Z,1504644110.794 [Radio_Surface] Loaded
2017-09-05T20:41:50.795Z,1504644110.795 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-09-05T20:41:50.796Z,1504644110.796 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A2E4E0
2017-09-05T20:41:50.796Z,1504644110.796 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9563
2017-09-05T20:41:51.030Z,1504644111.030 [PNI_TCM] Loaded
2017-09-05T20:41:51.030Z,1504644111.030 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-09-05T20:41:51.031Z,1504644111.031 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-09-05T20:41:51.032Z,1504644111.032 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-09-05T20:41:51.129Z,1504644111.129 [BuoyancyServo] Loaded
2017-09-05T20:41:51.129Z,1504644111.129 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-09-05T20:41:51.141Z,1504644111.141 [ElevatorServo] Loaded
2017-09-05T20:41:51.141Z,1504644111.141 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-09-05T20:41:51.152Z,1504644111.152 [MassServo] Loaded
2017-09-05T20:41:51.153Z,1504644111.153 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-09-05T20:41:51.164Z,1504644111.164 [RudderServo] Loaded
2017-09-05T20:41:51.164Z,1504644111.164 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-09-05T20:41:51.175Z,1504644111.175 [ThrusterServo] Loaded
2017-09-05T20:41:51.175Z,1504644111.175 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-09-05T20:41:51.176Z,1504644111.176 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-09-05T20:41:51.176Z,1504644111.176 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-09-05T20:41:51.274Z,1504644111.274 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-09-05T20:41:51.274Z,1504644111.274 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-09-05T20:41:51.299Z,1504644111.299 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-09-05T20:41:51.303Z,1504644111.303 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-09-05T20:41:51.304Z,1504644111.304 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-09-05T20:41:51.311Z,1504644111.311 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-09-05T20:41:51.312Z,1504644111.312 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2017-09-05T20:41:51.313Z,1504644111.313 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9564
2017-09-05T20:41:51.318Z,1504644111.318 [Supervisor](INFO): Main Thread ID is 9473
2017-09-05T20:41:51.318Z,1504644111.318 [Supervisor](DEBUG): Running supervisor.
2017-09-05T20:41:51.319Z,1504644111.319 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9565
2017-09-05T20:41:51.322Z,1504644111.322 [controlThread ThreadHandler](INFO): Handler Thread ID is 9566
2017-09-05T20:41:51.322Z,1504644111.322 [controlThread](DEBUG): Initializing ControlThread
2017-09-05T20:41:51.323Z,1504644111.323 [SBIT](INFO): Initialize SBIT Component.
2017-09-05T20:41:51.324Z,1504644111.324 [SBIT](IMPORTANT): git: 2017-08-02
2017-09-05T20:41:51.324Z,1504644111.324 [SBIT](INFO): git hash: b8bd19726fcc84d3133bb9e1cbad184cc93bb52c
2017-09-05T20:41:51.325Z,1504644111.325 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-09-05T20:41:51.326Z,1504644111.326 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-09-05T20:41:51.327Z,1504644111.327 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2017-09-05T20:41:51.327Z,1504644111.327 [IBIT](INFO): Initialize IBIT Component.
2017-09-05T20:41:51.328Z,1504644111.328 [CBIT](DEBUG): Initialize CBIT Component.
2017-09-05T20:41:51.328Z,1504644111.328 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-09-05T20:41:51.329Z,1504644111.329 [logger ThreadHandler](INFO): Handler Thread ID is 9567
2017-09-05T20:41:51.354Z,1504644111.354 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9568
2017-09-05T20:41:51.355Z,1504644111.355 [CTD_NeilBrown](INFO): Powering down
2017-09-05T20:41:51.383Z,1504644111.383 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9569
2017-09-05T20:41:51.384Z,1504644111.384 [CTD_Seabird](INFO): Initializing
2017-09-05T20:41:51.419Z,1504644111.419 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-09-05T20:41:51.422Z,1504644111.422 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-09-05T20:41:51.423Z,1504644111.423 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-09-05T20:41:51.424Z,1504644111.424 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-09-05T20:41:51.424Z,1504644111.424 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-05T20:41:51.424Z,1504644111.424 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-09-05T20:41:51.425Z,1504644111.425 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-09-05T20:41:51.425Z,1504644111.425 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-09-05T20:41:51.426Z,1504644111.426 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-09-05T20:41:51.427Z,1504644111.427 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-09-05T20:41:51.428Z,1504644111.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-05T20:41:51.429Z,1504644111.429 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-05T20:41:51.429Z,1504644111.429 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-05T20:41:51.429Z,1504644111.429 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-05T20:41:51.430Z,1504644111.430 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-05T20:41:51.430Z,1504644111.430 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-05T20:41:51.431Z,1504644111.431 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-09-05T20:41:51.431Z,1504644111.431 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-09-05T20:41:51.443Z,1504644111.443 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9571
2017-09-05T20:41:51.444Z,1504644111.444 [WetLabsBB2FL](INFO): Powering down
2017-09-05T20:41:51.468Z,1504644111.468 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-09-05T20:41:51.487Z,1504644111.487 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9572
2017-09-05T20:41:51.493Z,1504644111.493 [Radio_Surface](INFO): Powering up
2017-09-05T20:41:51.504Z,1504644111.504 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-05T20:41:51.511Z,1504644111.511 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9573
2017-09-05T20:41:51.518Z,1504644111.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-09-05T20:41:51.518Z,1504644111.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-09-05T20:41:51.519Z,1504644111.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-09-05T20:41:51.519Z,1504644111.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-09-05T20:41:51.519Z,1504644111.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-09-05T20:41:51.519Z,1504644111.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-09-05T20:41:51.519Z,1504644111.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-09-05T20:41:51.519Z,1504644111.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-09-05T20:41:51.520Z,1504644111.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-09-05T20:41:51.520Z,1504644111.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-09-05T20:41:51.520Z,1504644111.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-09-05T20:41:51.520Z,1504644111.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-09-05T20:41:51.520Z,1504644111.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-09-05T20:41:51.521Z,1504644111.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-09-05T20:41:51.521Z,1504644111.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-09-05T20:41:51.521Z,1504644111.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-09-05T20:41:51.574Z,1504644111.574 [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-09-05T20:41:51.575Z,1504644111.575 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-09-05T20:41:51.665Z,1504644111.665 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-09-05T20:41:51.667Z,1504644111.667 [Default:A.Wait](DEBUG): Construct Wait.
2017-09-05T20:41:51.669Z,1504644111.669 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-05T20:41:51.715Z,1504644111.715 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-09-05T20:41:51.715Z,1504644111.715 [CTD_Seabird](INFO): Powering down
2017-09-05T20:41:51.744Z,1504644111.744 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-09-05T20:41:51.746Z,1504644111.746 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-09-05T20:41:51.772Z,1504644111.772 [Default:E.Execute](DEBUG): Construct Execute.
2017-09-05T20:41:51.776Z,1504644111.776 [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-09-05T20:41:51.783Z,1504644111.783 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-09-05T20:41:51.857Z,1504644111.857 [CANONSampler](INFO): Powering down
2017-09-05T20:41:52.040Z,1504644112.040 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-09-05T20:41:52.316Z,1504644112.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:52.480Z,1504644112.480 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:52.590Z,1504644112.590 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-05T20:41:52.596Z,1504644112.596 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-05T20:41:52.610Z,1504644112.610 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-09-05T20:41:52.616Z,1504644112.616 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-09-05T20:41:52.634Z,1504644112.634 [MassServo](DEBUG): Initializing EZServoServo.
2017-09-05T20:41:52.640Z,1504644112.640 [MassServo](DEBUG): Initializing MassServo.
2017-09-05T20:41:52.662Z,1504644112.662 [RudderServo](DEBUG): Initializing EZServoServo.
2017-09-05T20:41:52.668Z,1504644112.668 [RudderServo](DEBUG): Initializing RudderServo.
2017-09-05T20:41:52.682Z,1504644112.682 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-09-05T20:41:52.688Z,1504644112.688 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-09-05T20:41:52.974Z,1504644112.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:52.975Z,1504644112.975 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:53.224Z,1504644113.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:53.225Z,1504644113.225 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:53.569Z,1504644113.569 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-09-05T20:41:53.569Z,1504644113.569 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-09-05T20:41:53.570Z,1504644113.570 [BuoyancyServo] Communications Fault, FailCount= 1
2017-09-05T20:41:53.570Z,1504644113.570 [BuoyancyServo](ERROR): Communications Fault
2017-09-05T20:41:53.777Z,1504644113.777 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-09-05T20:41:53.844Z,1504644113.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:53.845Z,1504644113.845 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:53.851Z,1504644113.851 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-05T20:41:53.853Z,1504644113.853 [BuoyancyServo](INFO): Powering down
2017-09-05T20:41:54.240Z,1504644114.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:54.241Z,1504644114.241 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:54.622Z,1504644114.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:54.635Z,1504644114.635 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:55.104Z,1504644115.104 [Aanderaa_O2](INFO): Powering down
2017-09-05T20:41:55.227Z,1504644115.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:55.228Z,1504644115.228 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:55.426Z,1504644115.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:55.427Z,1504644115.427 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-05T20:41:55.795Z,1504644115.795 [NAL9602](INFO): Powering up NAL9602
2017-09-05T20:41:56.010Z,1504644116.010 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-09-05T20:41:56.010Z,1504644116.010 [BuoyancyServo] No Fault, FailCount= 1
2017-09-05T20:41:56.286Z,1504644116.286 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-05T20:41:56.407Z,1504644116.407 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-05T20:42:06.668Z,1504644126.668 [NAL9602](INFO): NAL9602 initialized
2017-09-05T20:43:03.141Z,1504644183.141 [SBIT](IMPORTANT): Beginning Startup BIT
2017-09-05T20:43:03.149Z,1504644183.149 [CBIT](IMPORTANT): Beginning ground fault scan
2017-09-05T20:43:13.697Z,1504644193.697 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.003320
CHAN A1 (24V): 0.127594
CHAN A2 (12V): -0.001920
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001797
CHAN B1 (3.15aV): -0.000207
CHAN B2 (3.15bV): 4.767929
CHAN B3 (GND): 0.027689
OPEN: -0.000483
Full Scale Calc: 4.765 mA, -1.589 mA
2017-09-05T20:43:57.209Z,1504644237.209 [SBIT](IMPORTANT): SBIT PASSED
2017-09-05T20:43:57.574Z,1504644237.574 [MissionManager](IMPORTANT): Started mission Startup
2017-09-05T20:43:57.575Z,1504644237.575 [Startup] Running Loop=1
2017-09-05T20:43:57.575Z,1504644237.575 [Startup](DEBUG): Aggregate::initialize Startup
2017-09-05T20:43:57.575Z,1504644237.575 [Startup:A.GoToSurface] Running Loop=1
2017-09-05T20:43:57.575Z,1504644237.575 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-05T20:43:57.576Z,1504644237.576 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-05T20:43:57.576Z,1504644237.576 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-05T20:43:57.576Z,1504644237.576 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-05T20:43:57.577Z,1504644237.577 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-05T20:43:57.577Z,1504644237.577 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-05T20:43:57.619Z,1504644237.619 [Startup:StartupSatComms] Running Loop=1
2017-09-05T20:43:57.619Z,1504644237.619 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-09-05T20:43:57.619Z,1504644237.619 [Startup:StartupSatComms:A] Running Loop=1
2017-09-05T20:43:57.967Z,1504644237.967 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-09-05T20:44:51.561Z,1504644291.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-05T20:44:51.561Z,1504644291.561 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-09-05T20:44:51.561Z,1504644291.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-05T20:44:51.563Z,1504644291.563 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-05T20:44:51.563Z,1504644291.563 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-09-05T20:44:51.563Z,1504644291.563 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-05T20:44:51.619Z,1504644291.619 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-05T20:44:51.620Z,1504644291.620 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-05T20:44:51.979Z,1504644291.979 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-05T20:44:51.979Z,1504644291.979 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-09-05T20:44:51.992Z,1504644291.992 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-05T20:44:51.992Z,1504644291.992 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-09-05T20:44:52.372Z,1504644292.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-05T20:44:52.373Z,1504644292.373 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-05T20:44:52.373Z,1504644292.373 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-05T20:44:52.373Z,1504644292.373 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-05T20:44:52.374Z,1504644292.374 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-05T20:44:52.374Z,1504644292.374 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-05T20:44:52.763Z,1504644292.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-09-05T20:44:53.177Z,1504644293.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-09-05T20:44:53.573Z,1504644293.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-09-05T20:44:53.965Z,1504644293.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-09-05T20:44:54.368Z,1504644294.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-09-05T20:44:54.774Z,1504644294.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-09-05T20:44:55.163Z,1504644295.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-09-05T20:44:55.564Z,1504644295.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-09-05T20:44:55.974Z,1504644295.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-09-05T20:44:56.369Z,1504644296.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-09-05T20:44:56.773Z,1504644296.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-09-05T20:44:57.170Z,1504644297.170 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-09-05T20:44:57.568Z,1504644297.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-09-05T20:44:57.964Z,1504644297.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-09-05T20:44:57.971Z,1504644297.971 [Startup:StartupSatComms:A](INFO): Timed out from 2017-09-05T20:43:57.6Z
2017-09-05T20:44:57.971Z,1504644297.971 [Startup:StartupSatComms:A] Stopped
2017-09-05T20:44:57.972Z,1504644297.972 [Startup:StartupSatComms:B] Running Loop=1
2017-09-05T20:44:58.375Z,1504644298.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-09-05T20:44:58.379Z,1504644298.379 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-05T20:44:58.772Z,1504644298.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-09-05T20:44:59.171Z,1504644299.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-09-05T20:44:59.573Z,1504644299.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-09-05T20:44:59.968Z,1504644299.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-09-05T20:45:00.369Z,1504644300.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-09-05T20:45:00.771Z,1504644300.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-09-05T20:45:01.176Z,1504644301.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-09-05T20:45:01.573Z,1504644301.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-09-05T20:45:01.969Z,1504644301.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-09-05T20:45:02.385Z,1504644302.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-09-05T20:45:02.780Z,1504644302.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-09-05T20:45:03.173Z,1504644303.173 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-09-05T20:45:03.574Z,1504644303.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-09-05T20:45:03.951Z,1504644303.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-09-05T20:45:04.381Z,1504644304.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-09-05T20:45:04.776Z,1504644304.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-09-05T20:45:05.180Z,1504644305.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-09-05T20:45:05.577Z,1504644305.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-09-05T20:45:05.981Z,1504644305.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-09-05T20:45:06.388Z,1504644306.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-09-05T20:45:06.781Z,1504644306.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-09-05T20:45:07.178Z,1504644307.178 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-09-05T20:45:07.564Z,1504644307.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-09-05T20:45:07.960Z,1504644307.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-09-05T20:45:08.358Z,1504644308.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-09-05T20:45:08.754Z,1504644308.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-09-05T20:45:09.180Z,1504644309.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-09-05T20:45:09.564Z,1504644309.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-09-05T20:45:09.955Z,1504644309.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-09-05T20:45:10.382Z,1504644310.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-09-05T20:45:10.787Z,1504644310.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-09-05T20:45:11.188Z,1504644311.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-09-05T20:45:57.982Z,1504644357.982 [Startup:StartupSatComms:B](INFO): Timed out from 2017-09-05T20:44:57.0Z
2017-09-05T20:45:57.982Z,1504644357.982 [Startup:StartupSatComms:B] Stopped
2017-09-05T20:45:57.982Z,1504644357.982 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-09-05T20:45:57.982Z,1504644357.982 [Startup:StartupSatComms] Stopped
2017-09-05T20:45:57.982Z,1504644357.982 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-09-05T20:45:57.983Z,1504644357.983 [Startup](INFO): Completed Startup
2017-09-05T20:45:57.983Z,1504644357.983 [MissionManager](INFO): Startup is completed.
2017-09-05T20:45:57.983Z,1504644357.983 [MissionManager](INFO): Uninitializing Mission Startup
2017-09-05T20:45:57.983Z,1504644357.983 [Startup] Stopped
2017-09-05T20:45:57.983Z,1504644357.983 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-09-05T20:45:57.988Z,1504644357.988 [Startup:A.GoToSurface] Stopped
2017-09-05T20:45:57.988Z,1504644357.988 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-05T20:45:58.385Z,1504644358.385 [MissionManager](IMPORTANT): Started mission Default
2017-09-05T20:45:58.385Z,1504644358.385 [Default] Running Loop=1
2017-09-05T20:45:58.385Z,1504644358.385 [Default](DEBUG): Aggregate::initialize Default
2017-09-05T20:45:58.385Z,1504644358.385 [Default:B.GoToSurface] Running Loop=1
2017-09-05T20:45:58.385Z,1504644358.385 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-05T20:45:58.386Z,1504644358.386 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-05T20:45:58.386Z,1504644358.386 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-05T20:45:58.386Z,1504644358.386 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-05T20:45:58.387Z,1504644358.387 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-05T20:45:58.387Z,1504644358.387 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-05T20:45:58.387Z,1504644358.387 [Default:A.Wait] Running Loop=1
2017-09-05T20:45:58.387Z,1504644358.387 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-09-05T20:46:11.570Z,1504644371.570 [Default:A.Wait](INFO): Done Waiting.
2017-09-05T20:46:11.570Z,1504644371.570 [Default:A.Wait] Stopped
2017-09-05T20:46:11.570Z,1504644371.570 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-09-05T20:46:11.967Z,1504644371.967 [Default:CheckIn] Running Loop=1
2017-09-05T20:46:11.967Z,1504644371.967 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-05T20:46:11.967Z,1504644371.967 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-05T20:46:12.369Z,1504644372.369 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-09-05T20:46:36.327Z,1504644396.327 [NAL9602](INFO): SBD MO Status=2, MOMSN=419, MT Status=2, MTMSN=0
2017-09-05T20:46:36.328Z,1504644396.328 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:47:09.135Z,1504644429.135 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-09-05T20:47:15.151Z,1504644435.151 [NAL9602](INFO): GPS fix at 20170905T204631: (36.802748, -121.788125)
2017-09-05T20:47:15.221Z,1504644435.221 [Default:CheckIn:Read_GPS] Stopped
2017-09-05T20:47:15.221Z,1504644435.221 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-09-05T20:47:15.598Z,1504644435.598 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-05T20:47:45.922Z,1504644465.922 [NAL9602](FAULT): Queried for signal strength and failed to receive proper response. no error
2017-09-05T20:47:46.443Z,1504644466.443 [NAL9602](ERROR): received: 
2017-09-05T20:47:46.443Z,1504644466.443 [NAL9602] Data Fault, FailCount= 1
2017-09-05T20:47:46.443Z,1504644466.443 [NAL9602](ERROR): Data Fault
2017-09-05T20:47:46.530Z,1504644466.530 [CBIT](ERROR): Data Fault in component: NAL9602
2017-09-05T20:47:46.566Z,1504644466.566 [NAL9602](INFO): Powering down
2017-09-05T20:47:47.415Z,1504644467.415 [CBIT](INFO): Clearing failed state for component NAL9602
2017-09-05T20:47:47.415Z,1504644467.415 [NAL9602] No Fault, FailCount= 1
2017-09-05T20:47:51.757Z,1504644471.757 [NAL9602](INFO): Powering up NAL9602
2017-09-05T20:48:02.951Z,1504644482.951 [NAL9602](INFO): NAL9602 initialized
2017-09-05T20:48:15.485Z,1504644495.485 [PNI_TCM](ERROR): CRC does not match. Expected:0x43696 got:0x7360
2017-09-05T20:48:33.452Z,1504644513.452 [NAL9602](INFO): SBD MO Status=2, MOMSN=419, MT Status=2, MTMSN=0
2017-09-05T20:48:33.452Z,1504644513.452 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:48:52.747Z,1504644532.747 [NAL9602](INFO): SBD MO Status=1, MOMSN=419, MT Status=0, MTMSN=0
2017-09-05T20:48:52.796Z,1504644532.796 [NAL9602](INFO): Sent 25 bytes from file Logs/20170831T190218/Courier0060.lzma
2017-09-05T20:48:52.797Z,1504644532.797 [NAL9602](INFO): Packets left to send: 0
2017-09-05T20:48:52.798Z,1504644532.798 [NAL9602](DEBUG): Stored copy of sent data in Logs/20170831T190218/Courier0060.lzma.parts/0000.sbd
2017-09-05T20:48:52.799Z,1504644532.799 [NAL9602](DEBUG): Completed sending Logs/20170831T190218/Courier0060.lzma
2017-09-05T20:49:08.031Z,1504644548.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:49:08.032Z,1504644548.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:49:25.756Z,1504644565.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:49:25.756Z,1504644565.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:49:33.882Z,1504644573.882 [PNI_TCM](ERROR): CRC does not match. Expected:0x13735 got:0x38565
2017-09-05T20:49:42.654Z,1504644582.654 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:49:42.654Z,1504644582.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:49:43.492Z,1504644583.492 [PNI_TCM](ERROR): CRC does not match. Expected:0x18351 got:0x6948
2017-09-05T20:49:56.375Z,1504644596.375 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:49:56.376Z,1504644596.376 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:50:14.470Z,1504644614.470 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:50:14.470Z,1504644614.470 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:50:42.164Z,1504644642.164 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:50:42.164Z,1504644642.164 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:50:53.495Z,1504644653.495 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-09-05T20:50:55.102Z,1504644655.102 [PNI_TCM](ERROR): CRC does not match. Expected:0x28192 got:0x55084
2017-09-05T20:50:56.699Z,1504644656.699 [PNI_TCM](ERROR): CRC does not match. Expected:0x5805 got:0x51741
2017-09-05T20:50:58.298Z,1504644658.298 [PNI_TCM](ERROR): CRC does not match. Expected:0x37286 got:0x215
2017-09-05T20:50:59.906Z,1504644659.906 [PNI_TCM](ERROR): CRC does not match. Expected:0x7725 got:0x16855
2017-09-05T20:51:03.088Z,1504644663.088 [PNI_TCM](ERROR): CRC does not match. Expected:0x42536 got:0x48764
2017-09-05T20:51:03.498Z,1504644663.498 [PNI_TCM](ERROR): CRC does not match. Expected:0x44169 got:0x7360
2017-09-05T20:51:03.871Z,1504644663.871 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:51:03.872Z,1504644663.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:51:12.722Z,1504644672.722 [PNI_TCM](ERROR): CRC does not match. Expected:0x59345 got:0x17183
2017-09-05T20:51:14.300Z,1504644674.300 [PNI_TCM](ERROR): CRC does not match. Expected:0x43182 got:0x7360
2017-09-05T20:51:15.902Z,1504644675.902 [PNI_TCM](ERROR): CRC does not match. Expected:0x59148 got:0x49381
2017-09-05T20:51:33.978Z,1504644693.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:51:33.978Z,1504644693.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:51:51.658Z,1504644711.658 [CBIT](DEBUG): EFC running - data check-sum false
2017-09-05T20:52:48.554Z,1504644768.554 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/rotate_sampler.xml
2017-09-05T20:52:48.554Z,1504644768.554 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/rotate_sampler.xml
2017-09-05T20:52:48.626Z,1504644768.626 [MissionManager](INFO): DefineArg rotate_sampler.MissionTimeout = 1.000000 h
2017-09-05T20:52:48.630Z,1504644768.630 [MissionManager](INFO): DefineArg rotate_sampler.BuoyancyNeutral = 315.000012 cc
2017-09-05T20:52:48.633Z,1504644768.633 [MissionManager](INFO): DefineArg rotate_sampler.MassDefault = -1.300000 cm
2017-09-05T20:52:48.636Z,1504644768.636 [MissionManager](INFO): DefineArg rotate_sampler.Rotate = 1.000000 bool
2017-09-05T20:52:48.640Z,1504644768.640 [MissionManager](INFO): DefineArg rotate_sampler.Depth01 = 40.000000 m
2017-09-05T20:52:48.643Z,1504644768.643 [MissionManager](INFO): DefineArg rotate_sampler.SettleTime = 0.000000 s
2017-09-05T20:52:48.646Z,1504644768.646 [MissionManager](INFO): DefineArg rotate_sampler.RudderAngle = 13.000000 arcdeg
2017-09-05T20:52:48.649Z,1504644768.649 [MissionManager](INFO): DefineArg rotate_sampler.Speed = 1.000000 m/s
2017-09-05T20:52:48.672Z,1504644768.672 [MissionManager](INFO): DefineArg rotate_sampler.MinAltitude = 5.000000 m
2017-09-05T20:52:48.680Z,1504644768.680 [MissionManager](INFO): DefineArg rotate_sampler.MaxDepth = 225.000000 m
2017-09-05T20:52:48.698Z,1504644768.698 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2017-09-05T20:52:49.030Z,1504644769.030 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUS = 0 bool
2017-09-05T20:52:49.033Z,1504644769.033 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2017-09-05T20:52:49.036Z,1504644769.036 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUSminPitch = -60.000000 arcdeg
2017-09-05T20:52:49.039Z,1504644769.039 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakDetectChlActive = 0 bool
2017-09-05T20:52:49.054Z,1504644769.054 [MissionManager](INFO): DefineArg rotate_sampler:Science.HighestChlPeakReportActive = 0 bool
2017-09-05T20:52:49.056Z,1504644769.056 [MissionManager](INFO): DefineArg rotate_sampler:Science.PatchTracking = 0 bool
2017-09-05T20:52:49.059Z,1504644769.059 [MissionManager](INFO): DefineArg rotate_sampler:Science.FilterWidthHorizontal = 3.000000 count
2017-09-05T20:52:49.062Z,1504644769.062 [MissionManager](INFO): DefineArg rotate_sampler:Science.NumProfilesSlidingwindow = 100.000000 count
2017-09-05T20:52:49.065Z,1504644769.065 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakDetectNO3Active = 0 bool
2017-09-05T20:52:49.081Z,1504644769.081 [MissionManager](INFO): DefineArg rotate_sampler:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2017-09-05T20:52:49.085Z,1504644769.085 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledAanderaaO2 = 1.000000 bool
2017-09-05T20:52:49.089Z,1504644769.089 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledNeilBrown = 1.000000 bool
2017-09-05T20:52:49.113Z,1504644769.113 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledSeabird = 1.000000 bool
2017-09-05T20:52:49.130Z,1504644769.130 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledTurbulence_NPS = 0.000000 bool
2017-09-05T20:52:49.133Z,1504644769.133 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledWetLabsBB2FL = 1.000000 bool
2017-09-05T20:52:49.137Z,1504644769.137 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledISUS = 0.000000 bool
2017-09-05T20:52:49.152Z,1504644769.152 [MissionManager](INFO): DefineArg rotate_sampler:Science.LowPassWindowLength = 20.000000 count
2017-09-05T20:52:49.156Z,1504644769.156 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakChlShallowBound = nan m
2017-09-05T20:52:49.158Z,1504644769.158 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakChlDeepBound = nan m
2017-09-05T20:52:49.161Z,1504644769.161 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChl = 0.000000 ug/l
2017-09-05T20:52:49.213Z,1504644769.213 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlDepth = 0.000000 m
2017-09-05T20:52:49.216Z,1504644769.216 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlLatitude = nan arcdeg
2017-09-05T20:52:49.237Z,1504644769.237 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlLongitude = nan arcdeg
2017-09-05T20:52:49.265Z,1504644769.265 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3 = 0.000000 umol/l
2017-09-05T20:52:49.281Z,1504644769.281 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Depth = 0.000000 m
2017-09-05T20:52:49.290Z,1504644769.290 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Latitude = nan arcdeg
2017-09-05T20:52:49.305Z,1504644769.305 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Longitude = nan arcdeg
2017-09-05T20:52:49.337Z,1504644769.337 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChl = 0.000000 m
2017-09-05T20:52:49.341Z,1504644769.341 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlDepth = 0.000000 m
2017-09-05T20:52:49.344Z,1504644769.344 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlLatitude = nan arcdeg
2017-09-05T20:52:49.360Z,1504644769.360 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlLongitude = nan arcdeg
2017-09-05T20:52:49.365Z,1504644769.365 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlDistance = nan arcdeg
2017-09-05T20:52:49.410Z,1504644769.410 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-09-05T20:52:49.437Z,1504644769.437 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2017-09-05T20:52:49.478Z,1504644769.478 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-09-05T20:52:49.515Z,1504644769.515 [MissionManager](INFO): RedefineArg rotate_sampler:Science.SampleISUS = value:1 bool
2017-09-05T20:52:49.517Z,1504644769.517 [MissionManager](INFO): RedefineArg rotate_sampler:Science.PeakDetectChlActive = value:1 bool
2017-09-05T20:52:49.517Z,1504644769.517 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2017-09-05T20:52:49.655Z,1504644769.655 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.DiveInterval = 3.000000 h
2017-09-05T20:52:49.658Z,1504644769.658 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.AcousticTimeout = 96.000000 h
2017-09-05T20:52:49.665Z,1504644769.665 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.WaitForPitchUp = 10.000000 min
2017-09-05T20:52:49.676Z,1504644769.676 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.SurfacePitch = 20.000000 arcdeg
2017-09-05T20:52:49.680Z,1504644769.680 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.SurfaceSpeed = 1.000000 m/s
2017-09-05T20:52:49.708Z,1504644769.708 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.GPSTimeout = 7.000000 min
2017-09-05T20:52:49.715Z,1504644769.715 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.CommsTimeout = 30.000000 min
2017-09-05T20:52:49.728Z,1504644769.728 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-05T20:52:49.773Z,1504644769.773 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2017-09-05T20:52:49.809Z,1504644769.809 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MinAltitude = 5.000000 m
2017-09-05T20:52:49.820Z,1504644769.820 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MaxDepth = 200.000000 m
2017-09-05T20:52:49.827Z,1504644769.827 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MinOffshore = 2000.000000 m
2017-09-05T20:52:49.828Z,1504644769.828 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2017-09-05T20:52:49.842Z,1504644769.842 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2017-09-05T20:52:49.876Z,1504644769.876 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2017-09-05T20:52:49.895Z,1504644769.895 [rotate_sampler:I.Pitch](DEBUG): Construct.
2017-09-05T20:52:49.930Z,1504644769.930 [rotate_sampler:J.Buoyancy](DEBUG): Construct Buoyancy.
2017-09-05T20:52:49.933Z,1504644769.933 [rotate_sampler:K.SetSpeed](DEBUG): Construct.
2017-09-05T20:52:49.937Z,1504644769.937 [rotate_sampler:M.Point](DEBUG): Construct.
2017-09-05T20:52:49.957Z,1504644769.957 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2017-09-05T20:52:50.106Z,1504644770.106 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.TargetDepth = 5.000000 m
2017-09-05T20:52:50.109Z,1504644770.109 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.SettleTime = 30.000000 s
2017-09-05T20:52:50.127Z,1504644770.127 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.RotateOnly = 0.000000 bool
2017-09-05T20:52:50.130Z,1504644770.130 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.UseCANONSampler = 1.000000 bool
2017-09-05T20:52:50.149Z,1504644770.149 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.UseESP = 0.000000 bool
2017-09-05T20:52:50.152Z,1504644770.152 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2017-09-05T20:52:50.156Z,1504644770.156 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2017-09-05T20:52:50.159Z,1504644770.159 [rotate_sampler:SampleAtDepth:B.Pitch](DEBUG): Construct.
2017-09-05T20:52:50.180Z,1504644770.180 [rotate_sampler:SampleAtDepth:D.Wait](DEBUG): Construct Wait.
2017-09-05T20:52:50.224Z,1504644770.224 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2017-09-05T20:52:50.231Z,1504644770.231 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2017-09-05T20:52:50.246Z,1504644770.246 [MissionManager](INFO): DefineArg rotate_sampler:SampleWithinEnvelopesWrapper.TargetDepth = 1.000000 m
2017-09-05T20:52:50.336Z,1504644770.336 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- Generated by TethysL -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="rotate_sampler"
>

    <Description>
        Rotate the sampler 1x at a specified depth
    </Description>

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

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

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

    <DefineArg Name="Rotate"><Units:bool/><Value>1</Value></DefineArg>

    <DefineArg Name="Depth01"><Description>
        Depth of sample 1.
    </Description><Units:meter/><Value>40</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before triggering sample.
    </Description><Units:second/><Value>0</Value></DefineArg>

    <DefineArg Name="RudderAngle"><Description>
        Rudder angle to use while performing the spiral cast.
    </Description><Units:degree/><Value>13</Value></DefineArg>

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

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

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

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

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

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

    <Assign><Sequence/><Arg Name="NeedComms:SurfaceSpeed"/><Arg Name="Speed"/></Assign>

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

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

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

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

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

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

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

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

    <Guidance:Point>
        <Parallel/>
        <Setting><Guidance:Point.rudderAngle/><Arg Name="RudderAngle"/></Setting>
    </Guidance:Point>

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

    <Assign><Sequence/><Arg Name="SampleAtDepth:SettleTime"/><Arg Name="SettleTime"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:RotateOnly"/><Arg Name="Rotate"/></Assign>

    <Aggregate Id="SampleWithinEnvelopesWrapper">

        <DefineArg Name="TargetDepth"><Units:meter/><Value>1</Value></DefineArg>

        <When>
            <Called/>
        </When>

        <Break>
            <Universal:depth/>
            <Ge><Arg Name="MaxDepth"/></Ge>
            <And><Arg Name="TargetDepth"/><Ge><Arg Name="MaxDepth"/></Ge></And>
            <Or><Universal:height_above_sea_floor/><Le><Arg Name="MinAltitude"/></Le><And><Arg Name="TargetDepth"/><Ge><Universal:depth/></Ge></And></Or>
        </Break>

        <Assign><Sequence/><Arg Name="SampleAtDepth:TargetDepth"/><Arg Name="TargetDepth"/></Assign>

        <Call Id="SampleWithinEnvelopes" RefId="SampleAtDepth"/>

    </Aggregate>

    <Aggregate Id="Cast">

        <Sequence/>

        <Assign><Sequence/><Arg Name="SampleWithinEnvelopesWrapper:TargetDepth"/><Arg Name="Depth01"/></Assign>

        <Call Id="Depth01" RefId="SampleWithinEnvelopesWrapper"/>

    </Aggregate>

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

</Mission>


2017-09-05T20:52:50.339Z,1504644770.339 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/rotate_sampler.xml
2017-09-05T20:52:50.444Z,1504644770.444 [Default] Stopped
2017-09-05T20:52:50.444Z,1504644770.444 [Default](DEBUG): Aggregate::uninitialize Default
2017-09-05T20:52:50.445Z,1504644770.445 [Default:B.GoToSurface] Stopped
2017-09-05T20:52:50.445Z,1504644770.445 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-05T20:52:50.445Z,1504644770.445 [Default:CheckIn] Stopped
2017-09-05T20:52:50.445Z,1504644770.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-09-05T20:52:50.445Z,1504644770.445 [Default:CheckIn:Read_Iridium] Stopped
2017-09-05T20:52:50.445Z,1504644770.445 [MissionManager](IMPORTANT): Started mission rotate_sampler
2017-09-05T20:52:50.445Z,1504644770.445 [rotate_sampler] Running Loop=1
2017-09-05T20:52:50.445Z,1504644770.445 [rotate_sampler](DEBUG): Aggregate::initialize rotate_sampler
2017-09-05T20:52:50.445Z,1504644770.445 [rotate_sampler:Science] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science](DEBUG): Aggregate::initialize rotate_sampler:Science
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:A] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:B] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:C] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:D] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:E] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:F] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:PeakDetectChl] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectChl
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2017-09-05T20:52:50.446Z,1504644770.446 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-09-05T20:52:50.449Z,1504644770.449 [rotate_sampler:Science:HighestChlPeakReport] Running Loop=1
2017-09-05T20:52:50.449Z,1504644770.449 [rotate_sampler:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize rotate_sampler:Science:HighestChlPeakReport
2017-09-05T20:52:50.449Z,1504644770.449 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2017-09-05T20:52:50.449Z,1504644770.449 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2017-09-05T20:52:50.450Z,1504644770.450 [rotate_sampler:Science:PeakDetectNO3] Running Loop=1
2017-09-05T20:52:50.450Z,1504644770.450 [rotate_sampler:Science:PeakDetectNO3](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectNO3
2017-09-05T20:52:50.450Z,1504644770.450 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2017-09-05T20:52:50.450Z,1504644770.450 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-09-05T20:52:50.460Z,1504644770.460 [rotate_sampler:StandardEnvelopes] Running Loop=1
2017-09-05T20:52:50.460Z,1504644770.460 [rotate_sampler:StandardEnvelopes](DEBUG): Aggregate::initialize rotate_sampler:StandardEnvelopes
2017-09-05T20:52:50.460Z,1504644770.460 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2017-09-05T20:52:50.460Z,1504644770.460 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2017-09-05T20:52:50.461Z,1504644770.461 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2017-09-05T20:52:50.461Z,1504644770.461 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2017-09-05T20:52:50.461Z,1504644770.461 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2017-09-05T20:52:50.461Z,1504644770.461 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2017-09-05T20:52:50.461Z,1504644770.461 [rotate_sampler:I.Pitch] Running Loop=1
2017-09-05T20:52:50.461Z,1504644770.461 [rotate_sampler:I.Pitch](DEBUG): Initialize.
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:J.Buoyancy] Running Loop=1
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:K.SetSpeed] Running Loop=1
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:K.SetSpeed](DEBUG): Initialize.
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:M.Point] Running Loop=1
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:M.Point](DEBUG): Initialize.
2017-09-05T20:52:50.462Z,1504644770.462 [rotate_sampler:C] Running Loop=1
2017-09-05T20:52:50.463Z,1504644770.463 [rotate_sampler:M.Point] Running Loop=1
2017-09-05T20:52:50.472Z,1504644770.472 [rotate_sampler:K.SetSpeed] Running Loop=1
2017-09-05T20:52:50.482Z,1504644770.482 [rotate_sampler:J.Buoyancy] Running Loop=1
2017-09-05T20:52:50.510Z,1504644770.510 [rotate_sampler:I.Pitch] Running Loop=1
2017-09-05T20:52:50.519Z,1504644770.519 [rotate_sampler:StandardEnvelopes] Running Loop=1
2017-09-05T20:52:50.519Z,1504644770.519 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2017-09-05T20:52:50.554Z,1504644770.554 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2017-09-05T20:52:50.565Z,1504644770.565 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2017-09-05T20:52:50.581Z,1504644770.581 [rotate_sampler:C] Stopped
2017-09-05T20:52:50.581Z,1504644770.581 [rotate_sampler:D] Running Loop=1
2017-09-05T20:52:50.581Z,1504644770.581 [rotate_sampler:Science] Running Loop=1
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:PeakDetectNO3] Stopped
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize rotate_sampler:Science:PeakDetectNO3
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:HighestChlPeakReport] Stopped
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize rotate_sampler:Science:HighestChlPeakReport
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2017-09-05T20:52:50.597Z,1504644770.597 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2017-09-05T20:52:50.598Z,1504644770.598 [rotate_sampler:Science:PeakDetectChl] Running Loop=1
2017-09-05T20:52:50.606Z,1504644770.606 [rotate_sampler:Science:F] Running Loop=1
2017-09-05T20:52:50.607Z,1504644770.607 [rotate_sampler:Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2017-09-05T20:52:50.609Z,1504644770.609 [rotate_sampler:Science:E] Stopped
2017-09-05T20:52:50.609Z,1504644770.609 [rotate_sampler:Science:D] Stopped
2017-09-05T20:52:50.609Z,1504644770.609 [rotate_sampler:Science:C] Running Loop=1
2017-09-05T20:52:50.610Z,1504644770.610 [rotate_sampler:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2017-09-05T20:52:50.611Z,1504644770.611 [rotate_sampler:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2017-09-05T20:52:50.612Z,1504644770.612 [rotate_sampler:Science:B] Stopped
2017-09-05T20:52:50.612Z,1504644770.612 [rotate_sampler:Science:A] Running Loop=1
2017-09-05T20:52:50.613Z,1504644770.613 [rotate_sampler:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2017-09-05T20:52:50.653Z,1504644770.653 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2017-09-05T20:52:50.655Z,1504644770.655 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2017-09-05T20:52:50.785Z,1504644770.785 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:50.860Z,1504644770.860 [rotate_sampler:D] Stopped
2017-09-05T20:52:50.860Z,1504644770.860 [rotate_sampler:F] Running Loop=1
2017-09-05T20:52:51.013Z,1504644771.013 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2017-09-05T20:52:51.013Z,1504644771.013 [WetLabsBB2FL](INFO): Powering up
2017-09-05T20:52:51.197Z,1504644771.197 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:51.354Z,1504644771.354 [rotate_sampler:F] Stopped
2017-09-05T20:52:51.354Z,1504644771.354 [rotate_sampler:G] Running Loop=1
2017-09-05T20:52:51.610Z,1504644771.610 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:51.673Z,1504644771.673 [rotate_sampler:G] Stopped
2017-09-05T20:52:51.673Z,1504644771.673 [rotate_sampler:StartingMission] Running Loop=1
2017-09-05T20:52:51.674Z,1504644771.674 [rotate_sampler:StartingMission](DEBUG): Aggregate::initialize rotate_sampler:StartingMission
2017-09-05T20:52:52.018Z,1504644772.018 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:52.060Z,1504644772.060 [rotate_sampler:NeedComms] Running Loop=1
2017-09-05T20:52:52.060Z,1504644772.060 [rotate_sampler:NeedComms](DEBUG): Aggregate::initialize rotate_sampler:NeedComms
2017-09-05T20:52:52.060Z,1504644772.060 [rotate_sampler:NeedComms:B.GoToSurface] Running Loop=1
2017-09-05T20:52:52.060Z,1504644772.060 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-05T20:52:52.060Z,1504644772.060 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-05T20:52:52.061Z,1504644772.061 [rotate_sampler:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2017-09-05T20:52:52.061Z,1504644772.061 [rotate_sampler:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2017-09-05T20:52:52.062Z,1504644772.062 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-05T20:52:52.062Z,1504644772.062 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-05T20:52:52.062Z,1504644772.062 [rotate_sampler:NeedComms:A] Running Loop=1
2017-09-05T20:52:52.072Z,1504644772.072 [rotate_sampler:NeedComms:A](INFO): last time_fix was: 1504644391.000000 second since 1970/01/01T00:00:00Z 
2017-09-05T20:52:52.073Z,1504644772.073 [rotate_sampler:NeedComms:A] Stopped
2017-09-05T20:52:52.544Z,1504644772.544 [rotate_sampler:NeedComms:C] Running Loop=1
2017-09-05T20:52:53.040Z,1504644773.040 [rotate_sampler:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-09-05T20:52:56.690Z,1504644776.690 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:56.890Z,1504644776.890 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:59.502Z,1504644779.502 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:52:59.518Z,1504644779.518 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.207		81.363		20.389		30.678		30.678		39.298		8.620		214.5		509.1		84.7	



2017-09-05T20:52:59.519Z,1504644779.519 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:53:00.716Z,1504644780.716 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		228.572		81.138		20.389		30.710		30.710		39.328		8.617		214.5		508.5		84.7	

2017-09-05T20:53:00.716Z,1504644780.716 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:53:02.716Z,1504644782.716 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.591		81.500		20.389		30.658		30.658		39.273		8.615		214.4		507.6		84.7	

2017-09-05T20:53:02.716Z,1504644782.716 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:53:04.274Z,1504644784.274 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:53:07.970Z,1504644787.970 [CommandLine](IMPORTANT): got command show stack
2017-09-05T20:53:07.970Z,1504644787.970 [CommandLine](IMPORTANT): Behavior Stack:

2017-09-05T20:53:07.971Z,1504644787.971 [rotate_sampler:Science](IMPORTANT): Priority 0: rotate_sampler:Science:A
2017-09-05T20:53:07.971Z,1504644787.971 [rotate_sampler:Science](IMPORTANT): Priority 1: rotate_sampler:Science:C
2017-09-05T20:53:07.972Z,1504644787.972 [rotate_sampler:Science](IMPORTANT): Priority 2: rotate_sampler:Science:F
2017-09-05T20:53:07.972Z,1504644787.972 [rotate_sampler:Science:PeakDetectChl](IMPORTANT): Priority 3: rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth
2017-09-05T20:53:07.972Z,1504644787.972 [rotate_sampler:NeedComms](IMPORTANT): Priority 4: rotate_sampler:NeedComms:B.GoToSurface
2017-09-05T20:53:07.973Z,1504644787.973 [rotate_sampler:NeedComms](IMPORTANT): Priority 5: rotate_sampler:NeedComms:C
2017-09-05T20:53:07.973Z,1504644787.973 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 6: rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope
2017-09-05T20:53:07.973Z,1504644787.973 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 7: rotate_sampler:StandardEnvelopes:B.DepthEnvelope
2017-09-05T20:53:07.973Z,1504644787.973 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 8: rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope
2017-09-05T20:53:07.974Z,1504644787.974 [rotate_sampler](IMPORTANT): Priority 9: rotate_sampler:I.Pitch
2017-09-05T20:53:07.974Z,1504644787.974 [rotate_sampler](IMPORTANT): Priority 10: rotate_sampler:J.Buoyancy
2017-09-05T20:53:07.974Z,1504644787.974 [rotate_sampler](IMPORTANT): Priority 11: rotate_sampler:K.SetSpeed
2017-09-05T20:53:07.975Z,1504644787.975 [rotate_sampler](IMPORTANT): Priority 12: rotate_sampler:M.Point
2017-09-05T20:53:15.813Z,1504644795.813 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:53:21.849Z,1504644801.849 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:53:24.866Z,1504644804.866 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:53:27.278Z,1504644807.278 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:53:30.512Z,1504644810.512 [CommandLine](IMPORTANT): got command stop
2017-09-05T20:53:30.512Z,1504644810.512 [CommandLine](IMPORTANT): Scheduling is paused
2017-09-05T20:53:30.513Z,1504644810.513 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2017-09-05T20:53:30.844Z,1504644810.844 [MissionManager](INFO): MissionManager is completed.
2017-09-05T20:53:30.844Z,1504644810.844 [MissionManager](INFO): Uninitializing Mission rotate_sampler
2017-09-05T20:53:30.844Z,1504644810.844 [rotate_sampler] Stopped
2017-09-05T20:53:30.844Z,1504644810.844 [rotate_sampler](DEBUG): Aggregate::uninitialize rotate_sampler
2017-09-05T20:53:30.844Z,1504644810.844 [rotate_sampler:Science] Stopped
2017-09-05T20:53:30.844Z,1504644810.844 [rotate_sampler:Science](DEBUG): Aggregate::uninitialize rotate_sampler:Science
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:A] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:C] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:F] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:PeakDetectChl] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize rotate_sampler:Science:PeakDetectChl
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:NeedComms] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:NeedComms](DEBUG): Aggregate::uninitialize rotate_sampler:NeedComms
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:NeedComms:B.GoToSurface] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:NeedComms:C] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:StandardEnvelopes] Stopped
2017-09-05T20:53:30.845Z,1504644810.845 [rotate_sampler:StandardEnvelopes](DEBUG): Aggregate::uninitialize rotate_sampler:StandardEnvelopes
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StartingMission] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:StartingMission](DEBUG): Aggregate::uninitialize rotate_sampler:StartingMission
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:I.Pitch] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:J.Buoyancy] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:K.SetSpeed] Stopped
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:K.SetSpeed](DEBUG): Uninitialize.
2017-09-05T20:53:30.846Z,1504644810.846 [rotate_sampler:M.Point] Stopped
2017-09-05T20:53:31.211Z,1504644811.211 [MissionManager](IMPORTANT): Started mission Default
2017-09-05T20:53:31.212Z,1504644811.212 [Default] Running Loop=1
2017-09-05T20:53:31.212Z,1504644811.212 [Default](DEBUG): Aggregate::initialize Default
2017-09-05T20:53:31.212Z,1504644811.212 [Default:B.GoToSurface] Running Loop=1
2017-09-05T20:53:31.212Z,1504644811.212 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-05T20:53:31.216Z,1504644811.216 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-05T20:53:31.217Z,1504644811.217 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-05T20:53:31.217Z,1504644811.217 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-05T20:53:31.217Z,1504644811.217 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-05T20:53:31.218Z,1504644811.218 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-05T20:53:31.218Z,1504644811.218 [Default:A.Wait] Running Loop=1
2017-09-05T20:53:31.218Z,1504644811.218 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-09-05T20:53:31.253Z,1504644811.253 [WetLabsBB2FL](INFO): Powering down
2017-09-05T20:53:31.633Z,1504644811.633 [Aanderaa_O2](INFO): Powering down
2017-09-05T20:53:36.326Z,1504644816.326 [NAL9602](INFO): Powering down
2017-09-05T20:53:44.386Z,1504644824.386 [Default:A.Wait](INFO): Done Waiting.
2017-09-05T20:53:44.386Z,1504644824.386 [Default:A.Wait] Stopped
2017-09-05T20:53:44.386Z,1504644824.386 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-09-05T20:53:44.782Z,1504644824.782 [Default:CheckIn] Running Loop=1
2017-09-05T20:53:44.782Z,1504644824.782 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-05T20:53:44.783Z,1504644824.783 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-05T20:53:45.536Z,1504644825.536 [NAL9602](INFO): Powering up
2017-09-05T20:53:56.726Z,1504644836.726 [NAL9602](INFO): NAL9602 initialized
2017-09-05T20:54:08.297Z,1504644848.297 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/rotate_sampler.xml
2017-09-05T20:54:08.298Z,1504644848.298 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/rotate_sampler.xml
2017-09-05T20:54:08.469Z,1504644848.469 [MissionManager](INFO): DefineArg rotate_sampler.MissionTimeout = 1.000000 h
2017-09-05T20:54:08.471Z,1504644848.471 [MissionManager](INFO): DefineArg rotate_sampler.BuoyancyNeutral = 315.000012 cc
2017-09-05T20:54:08.474Z,1504644848.474 [MissionManager](INFO): DefineArg rotate_sampler.MassDefault = -1.300000 cm
2017-09-05T20:54:08.484Z,1504644848.484 [MissionManager](INFO): DefineArg rotate_sampler.Rotate = 1.000000 bool
2017-09-05T20:54:08.486Z,1504644848.486 [MissionManager](INFO): DefineArg rotate_sampler.Depth01 = 40.000000 m
2017-09-05T20:54:08.489Z,1504644848.489 [MissionManager](INFO): DefineArg rotate_sampler.SettleTime = 0.000000 s
2017-09-05T20:54:08.501Z,1504644848.501 [MissionManager](INFO): DefineArg rotate_sampler.RudderAngle = 13.000000 arcdeg
2017-09-05T20:54:08.503Z,1504644848.503 [MissionManager](INFO): DefineArg rotate_sampler.Speed = 1.000000 m/s
2017-09-05T20:54:08.509Z,1504644848.509 [MissionManager](INFO): DefineArg rotate_sampler.MinAltitude = 5.000000 m
2017-09-05T20:54:08.520Z,1504644848.520 [MissionManager](INFO): DefineArg rotate_sampler.MaxDepth = 225.000000 m
2017-09-05T20:54:08.520Z,1504644848.520 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2017-09-05T20:54:08.761Z,1504644848.761 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUS = 0 bool
2017-09-05T20:54:08.763Z,1504644848.763 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2017-09-05T20:54:08.798Z,1504644848.798 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUSminPitch = -60.000000 arcdeg
2017-09-05T20:54:08.804Z,1504644848.804 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakDetectChlActive = 0 bool
2017-09-05T20:54:08.806Z,1504644848.806 [MissionManager](INFO): DefineArg rotate_sampler:Science.HighestChlPeakReportActive = 0 bool
2017-09-05T20:54:08.812Z,1504644848.812 [MissionManager](INFO): DefineArg rotate_sampler:Science.PatchTracking = 0 bool
2017-09-05T20:54:08.814Z,1504644848.814 [MissionManager](INFO): DefineArg rotate_sampler:Science.FilterWidthHorizontal = 3.000000 count
2017-09-05T20:54:08.837Z,1504644848.837 [MissionManager](INFO): DefineArg rotate_sampler:Science.NumProfilesSlidingwindow = 100.000000 count
2017-09-05T20:54:08.850Z,1504644848.850 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakDetectNO3Active = 0 bool
2017-09-05T20:54:08.851Z,1504644848.851 [MissionManager](INFO): DefineArg rotate_sampler:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2017-09-05T20:54:08.854Z,1504644848.854 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledAanderaaO2 = 1.000000 bool
2017-09-05T20:54:08.861Z,1504644848.861 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledNeilBrown = 1.000000 bool
2017-09-05T20:54:08.863Z,1504644848.863 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledSeabird = 1.000000 bool
2017-09-05T20:54:08.878Z,1504644848.878 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledTurbulence_NPS = 0.000000 bool
2017-09-05T20:54:08.881Z,1504644848.881 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledWetLabsBB2FL = 1.000000 bool
2017-09-05T20:54:08.883Z,1504644848.883 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledISUS = 0.000000 bool
2017-09-05T20:54:08.889Z,1504644848.889 [MissionManager](INFO): DefineArg rotate_sampler:Science.LowPassWindowLength = 20.000000 count
2017-09-05T20:54:08.892Z,1504644848.892 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakChlShallowBound = nan m
2017-09-05T20:54:08.898Z,1504644848.898 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakChlDeepBound = nan m
2017-09-05T20:54:08.905Z,1504644848.905 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChl = 0.000000 ug/l
2017-09-05T20:54:08.907Z,1504644848.907 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlDepth = 0.000000 m
2017-09-05T20:54:08.914Z,1504644848.914 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlLatitude = nan arcdeg
2017-09-05T20:54:08.920Z,1504644848.920 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlLongitude = nan arcdeg
2017-09-05T20:54:08.923Z,1504644848.923 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3 = 0.000000 umol/l
2017-09-05T20:54:08.929Z,1504644848.929 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Depth = 0.000000 m
2017-09-05T20:54:08.931Z,1504644848.931 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Latitude = nan arcdeg
2017-09-05T20:54:08.938Z,1504644848.938 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Longitude = nan arcdeg
2017-09-05T20:54:08.940Z,1504644848.940 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChl = 0.000000 m
2017-09-05T20:54:08.942Z,1504644848.942 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlDepth = 0.000000 m
2017-09-05T20:54:08.945Z,1504644848.945 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlLatitude = nan arcdeg
2017-09-05T20:54:08.947Z,1504644848.947 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlLongitude = nan arcdeg
2017-09-05T20:54:08.977Z,1504644848.977 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlDistance = nan arcdeg
2017-09-05T20:54:09.007Z,1504644849.007 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-09-05T20:54:09.040Z,1504644849.040 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2017-09-05T20:54:09.052Z,1504644849.052 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2017-09-05T20:54:09.100Z,1504644849.100 [MissionManager](INFO): RedefineArg rotate_sampler:Science.SampleISUS = value:1 bool
2017-09-05T20:54:09.101Z,1504644849.101 [MissionManager](INFO): RedefineArg rotate_sampler:Science.PeakDetectChlActive = value:1 bool
2017-09-05T20:54:09.102Z,1504644849.102 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2017-09-05T20:54:09.246Z,1504644849.246 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.DiveInterval = 3.000000 h
2017-09-05T20:54:09.248Z,1504644849.248 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.AcousticTimeout = 96.000000 h
2017-09-05T20:54:09.266Z,1504644849.266 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.WaitForPitchUp = 10.000000 min
2017-09-05T20:54:09.268Z,1504644849.268 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.SurfacePitch = 20.000000 arcdeg
2017-09-05T20:54:09.270Z,1504644849.270 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.SurfaceSpeed = 1.000000 m/s
2017-09-05T20:54:09.272Z,1504644849.272 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.GPSTimeout = 7.000000 min
2017-09-05T20:54:09.274Z,1504644849.274 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.CommsTimeout = 30.000000 min
2017-09-05T20:54:09.293Z,1504644849.293 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-05T20:54:09.306Z,1504644849.306 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2017-09-05T20:54:09.395Z,1504644849.395 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MinAltitude = 5.000000 m
2017-09-05T20:54:09.402Z,1504644849.402 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MaxDepth = 200.000000 m
2017-09-05T20:54:09.405Z,1504644849.405 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MinOffshore = 2000.000000 m
2017-09-05T20:54:09.406Z,1504644849.406 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2017-09-05T20:54:09.411Z,1504644849.411 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2017-09-05T20:54:09.437Z,1504644849.437 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2017-09-05T20:54:09.446Z,1504644849.446 [rotate_sampler:I.Pitch](DEBUG): Construct.
2017-09-05T20:54:09.450Z,1504644849.450 [rotate_sampler:J.Buoyancy](DEBUG): Construct Buoyancy.
2017-09-05T20:54:09.452Z,1504644849.452 [rotate_sampler:K.SetSpeed](DEBUG): Construct.
2017-09-05T20:54:09.456Z,1504644849.456 [rotate_sampler:M.Point](DEBUG): Construct.
2017-09-05T20:54:09.481Z,1504644849.481 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml
2017-09-05T20:54:09.627Z,1504644849.627 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.TargetDepth = 5.000000 m
2017-09-05T20:54:09.641Z,1504644849.641 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.SettleTime = 30.000000 s
2017-09-05T20:54:09.644Z,1504644849.644 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.RotateOnly = 0.000000 bool
2017-09-05T20:54:09.654Z,1504644849.654 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.UseCANONSampler = 1.000000 bool
2017-09-05T20:54:09.657Z,1504644849.657 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.UseESP = 0.000000 bool
2017-09-05T20:54:09.667Z,1504644849.667 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min
2017-09-05T20:54:09.678Z,1504644849.678 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.CANONSamplerTimeout = 6.000000 min
2017-09-05T20:54:09.680Z,1504644849.680 [rotate_sampler:SampleAtDepth:B.Pitch](DEBUG): Construct.
2017-09-05T20:54:09.703Z,1504644849.703 [rotate_sampler:SampleAtDepth:D.Wait](DEBUG): Construct Wait.
2017-09-05T20:54:09.727Z,1504644849.727 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute.
2017-09-05T20:54:09.750Z,1504644849.750 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute.
2017-09-05T20:54:09.786Z,1504644849.786 [MissionManager](INFO): DefineArg rotate_sampler:SampleWithinEnvelopesWrapper.TargetDepth = 1.000000 m
2017-09-05T20:54:09.859Z,1504644849.859 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- Generated by TethysL -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="rotate_sampler"
>

    <Description>
        Rotate the sampler 1x at a specified depth
    </Description>

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

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

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

    <DefineArg Name="Rotate"><Units:bool/><Value>1</Value></DefineArg>

    <DefineArg Name="Depth01"><Description>
        Depth of sample 1.
    </Description><Units:meter/><Value>40</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before triggering sample.
    </Description><Units:second/><Value>0</Value></DefineArg>

    <DefineArg Name="RudderAngle"><Description>
        Rudder angle to use while performing the spiral cast.
    </Description><Units:degree/><Value>13</Value></DefineArg>

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

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

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

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

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

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

    <Assign><Sequence/><Arg Name="NeedComms:SurfaceSpeed"/><Arg Name="Speed"/></Assign>

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

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

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

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

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

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

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

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

    <Guidance:Point>
        <Parallel/>
        <Setting><Guidance:Point.rudderAngle/><Arg Name="RudderAngle"/></Setting>
    </Guidance:Point>

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

    <Assign><Sequence/><Arg Name="SampleAtDepth:SettleTime"/><Arg Name="SettleTime"/></Assign>

    <Assign><Sequence/><Arg Name="SampleAtDepth:RotateOnly"/><Arg Name="Rotate"/></Assign>

    <Aggregate Id="SampleWithinEnvelopesWrapper">

        <DefineArg Name="TargetDepth"><Units:meter/><Value>1</Value></DefineArg>

        <When>
            <Called/>
        </When>

        <Break>
            <Universal:depth/>
            <Ge><Arg Name="MaxDepth"/></Ge>
            <And><Arg Name="TargetDepth"/><Ge><Arg Name="MaxDepth"/></Ge></And>
            <Or><Universal:height_above_sea_floor/><Le><Arg Name="MinAltitude"/></Le><And><Arg Name="TargetDepth"/><Ge><Universal:depth/></Ge></And></Or>
        </Break>

        <Assign><Sequence/><Arg Name="SampleAtDepth:TargetDepth"/><Arg Name="TargetDepth"/></Assign>

        <Call Id="SampleWithinEnvelopes" RefId="SampleAtDepth"/>

    </Aggregate>

    <Aggregate Id="Cast">

        <Sequence/>

        <Assign><Sequence/><Arg Name="SampleWithinEnvelopesWrapper:TargetDepth"/><Arg Name="Depth01"/></Assign>

        <Call Id="Depth01" RefId="SampleWithinEnvelopesWrapper"/>

    </Aggregate>

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

</Mission>


2017-09-05T20:54:09.859Z,1504644849.859 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/rotate_sampler.xml
2017-09-05T20:54:50.508Z,1504644890.508 [CommandLine](IMPORTANT): got command run
2017-09-05T20:54:50.510Z,1504644890.510 [CommandLine](IMPORTANT): Running 
2017-09-05T20:54:50.774Z,1504644890.774 [Default] Stopped
2017-09-05T20:54:50.774Z,1504644890.774 [Default](DEBUG): Aggregate::uninitialize Default
2017-09-05T20:54:50.774Z,1504644890.774 [Default:B.GoToSurface] Stopped
2017-09-05T20:54:50.774Z,1504644890.774 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-05T20:54:50.774Z,1504644890.774 [Default:CheckIn] Stopped
2017-09-05T20:54:50.774Z,1504644890.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-09-05T20:54:50.774Z,1504644890.774 [Default:CheckIn:Read_GPS] Stopped
2017-09-05T20:54:50.775Z,1504644890.775 [MissionManager](IMPORTANT): Started mission rotate_sampler
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler] Running Loop=1
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler](DEBUG): Aggregate::initialize rotate_sampler
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science] Running Loop=1
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science](DEBUG): Aggregate::initialize rotate_sampler:Science
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science:A] Running Loop=1
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science:B] Running Loop=1
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science:C] Running Loop=1
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science:D] Running Loop=1
2017-09-05T20:54:50.775Z,1504644890.775 [rotate_sampler:Science:E] Running Loop=1
2017-09-05T20:54:50.776Z,1504644890.776 [rotate_sampler:Science:F] Running Loop=1
2017-09-05T20:54:50.776Z,1504644890.776 [rotate_sampler:Science:PeakDetectChl] Running Loop=1
2017-09-05T20:54:50.776Z,1504644890.776 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectChl
2017-09-05T20:54:50.776Z,1504644890.776 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2017-09-05T20:54:50.776Z,1504644890.776 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-09-05T20:54:50.777Z,1504644890.777 [rotate_sampler:Science:HighestChlPeakReport] Running Loop=1
2017-09-05T20:54:50.777Z,1504644890.777 [rotate_sampler:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize rotate_sampler:Science:HighestChlPeakReport
2017-09-05T20:54:50.777Z,1504644890.777 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2017-09-05T20:54:50.777Z,1504644890.777 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2017-09-05T20:54:50.778Z,1504644890.778 [rotate_sampler:Science:PeakDetectNO3] Running Loop=1
2017-09-05T20:54:50.778Z,1504644890.778 [rotate_sampler:Science:PeakDetectNO3](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectNO3
2017-09-05T20:54:50.778Z,1504644890.778 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2017-09-05T20:54:50.778Z,1504644890.778 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-09-05T20:54:50.779Z,1504644890.779 [rotate_sampler:StandardEnvelopes] Running Loop=1
2017-09-05T20:54:50.779Z,1504644890.779 [rotate_sampler:StandardEnvelopes](DEBUG): Aggregate::initialize rotate_sampler:StandardEnvelopes
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:I.Pitch] Running Loop=1
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:I.Pitch](DEBUG): Initialize.
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:J.Buoyancy] Running Loop=1
2017-09-05T20:54:50.780Z,1504644890.780 [rotate_sampler:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2017-09-05T20:54:50.781Z,1504644890.781 [rotate_sampler:K.SetSpeed] Running Loop=1
2017-09-05T20:54:50.781Z,1504644890.781 [rotate_sampler:K.SetSpeed](DEBUG): Initialize.
2017-09-05T20:54:50.781Z,1504644890.781 [rotate_sampler:M.Point] Running Loop=1
2017-09-05T20:54:50.781Z,1504644890.781 [rotate_sampler:M.Point](DEBUG): Initialize.
2017-09-05T20:54:50.781Z,1504644890.781 [rotate_sampler:C] Running Loop=1
2017-09-05T20:54:50.782Z,1504644890.782 [rotate_sampler:M.Point] Running Loop=1
2017-09-05T20:54:50.783Z,1504644890.783 [rotate_sampler:K.SetSpeed] Running Loop=1
2017-09-05T20:54:50.784Z,1504644890.784 [rotate_sampler:J.Buoyancy] Running Loop=1
2017-09-05T20:54:50.785Z,1504644890.785 [rotate_sampler:I.Pitch] Running Loop=1
2017-09-05T20:54:50.786Z,1504644890.786 [rotate_sampler:StandardEnvelopes] Running Loop=1
2017-09-05T20:54:50.786Z,1504644890.786 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2017-09-05T20:54:50.819Z,1504644890.819 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2017-09-05T20:54:50.820Z,1504644890.820 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2017-09-05T20:54:50.823Z,1504644890.823 [rotate_sampler:C] Stopped
2017-09-05T20:54:50.823Z,1504644890.823 [rotate_sampler:D] Running Loop=1
2017-09-05T20:54:50.823Z,1504644890.823 [rotate_sampler:Science] Running Loop=1
2017-09-05T20:54:50.825Z,1504644890.825 [rotate_sampler:Science:PeakDetectNO3] Stopped
2017-09-05T20:54:50.825Z,1504644890.825 [rotate_sampler:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize rotate_sampler:Science:PeakDetectNO3
2017-09-05T20:54:50.825Z,1504644890.825 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2017-09-05T20:54:50.825Z,1504644890.825 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-09-05T20:54:50.826Z,1504644890.826 [rotate_sampler:Science:HighestChlPeakReport] Stopped
2017-09-05T20:54:50.826Z,1504644890.826 [rotate_sampler:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize rotate_sampler:Science:HighestChlPeakReport
2017-09-05T20:54:50.826Z,1504644890.826 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2017-09-05T20:54:50.826Z,1504644890.826 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2017-09-05T20:54:50.826Z,1504644890.826 [rotate_sampler:Science:PeakDetectChl] Running Loop=1
2017-09-05T20:54:50.831Z,1504644890.831 [rotate_sampler:Science:F] Running Loop=1
2017-09-05T20:54:50.832Z,1504644890.832 [rotate_sampler:Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2017-09-05T20:54:50.832Z,1504644890.832 [rotate_sampler:Science:E] Stopped
2017-09-05T20:54:50.832Z,1504644890.832 [rotate_sampler:Science:D] Stopped
2017-09-05T20:54:50.832Z,1504644890.832 [rotate_sampler:Science:C] Running Loop=1
2017-09-05T20:54:50.833Z,1504644890.833 [rotate_sampler:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2017-09-05T20:54:50.833Z,1504644890.833 [rotate_sampler:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2017-09-05T20:54:50.838Z,1504644890.838 [rotate_sampler:Science:B] Stopped
2017-09-05T20:54:50.838Z,1504644890.838 [rotate_sampler:Science:A] Running Loop=1
2017-09-05T20:54:50.839Z,1504644890.839 [rotate_sampler:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2017-09-05T20:54:51.019Z,1504644891.019 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2017-09-05T20:54:51.019Z,1504644891.019 [WetLabsBB2FL](INFO): Powering up
2017-09-05T20:54:51.093Z,1504644891.093 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2017-09-05T20:54:51.199Z,1504644891.199 [rotate_sampler:D] Stopped
2017-09-05T20:54:51.199Z,1504644891.199 [rotate_sampler:F] Running Loop=1
2017-09-05T20:54:51.215Z,1504644891.215 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:54:51.585Z,1504644891.585 [rotate_sampler:F] Stopped
2017-09-05T20:54:51.585Z,1504644891.585 [rotate_sampler:G] Running Loop=1
2017-09-05T20:54:51.628Z,1504644891.628 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:54:52.005Z,1504644892.005 [rotate_sampler:G] Stopped
2017-09-05T20:54:52.006Z,1504644892.006 [rotate_sampler:StartingMission] Running Loop=1
2017-09-05T20:54:52.006Z,1504644892.006 [rotate_sampler:StartingMission](DEBUG): Aggregate::initialize rotate_sampler:StartingMission
2017-09-05T20:54:52.036Z,1504644892.036 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:54:52.404Z,1504644892.404 [rotate_sampler:NeedComms] Running Loop=1
2017-09-05T20:54:52.404Z,1504644892.404 [rotate_sampler:NeedComms](DEBUG): Aggregate::initialize rotate_sampler:NeedComms
2017-09-05T20:54:52.404Z,1504644892.404 [rotate_sampler:NeedComms:B.GoToSurface] Running Loop=1
2017-09-05T20:54:52.405Z,1504644892.405 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-05T20:54:52.405Z,1504644892.405 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-05T20:54:52.406Z,1504644892.406 [rotate_sampler:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2017-09-05T20:54:52.406Z,1504644892.406 [rotate_sampler:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2017-09-05T20:54:52.406Z,1504644892.406 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-05T20:54:52.406Z,1504644892.406 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-05T20:54:52.407Z,1504644892.407 [rotate_sampler:NeedComms:A] Running Loop=1
2017-09-05T20:54:52.409Z,1504644892.409 [rotate_sampler:NeedComms:A](INFO): last time_fix was: 1504644391.000000 second since 1970/01/01T00:00:00Z 
2017-09-05T20:54:52.410Z,1504644892.410 [rotate_sampler:NeedComms:A] Stopped
2017-09-05T20:54:52.445Z,1504644892.445 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:54:52.850Z,1504644892.850 [rotate_sampler:NeedComms:C] Running Loop=1
2017-09-05T20:54:53.219Z,1504644893.219 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:54:53.330Z,1504644893.330 [rotate_sampler:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-09-05T20:54:54.472Z,1504644894.472 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:54:59.059Z,1504644899.059 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.087		81.433		20.459		30.654		30.654		39.278		8.624		214.0		506.9		82.4	



2017-09-05T20:54:59.059Z,1504644899.059 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:54:59.861Z,1504644899.861 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		228.796		81.330		20.459		30.669		30.669		39.288		8.619		214.0		506.3		82.4	



2017-09-05T20:54:59.861Z,1504644899.861 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:55:00.802Z,1504644900.802 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:01.059Z,1504644901.059 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.272		81.499		20.459		30.645		30.645		39.265		8.620		214.0		505.8		82.4	

2017-09-05T20:55:01.059Z,1504644901.059 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:55:01.852Z,1504644901.852 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:03.057Z,1504644903.057 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		229.191		81.471		20.459		30.649		30.649		39.267		8.618		213.9		504.9		82.4	

2017-09-05T20:55:03.057Z,1504644903.057 [Aanderaa_O2](DEBUG): Error parsing device response
2017-09-05T20:55:03.410Z,1504644903.410 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:04.709Z,1504644904.709 [PNI_TCM](ERROR): CRC does not match. Expected:0x56367 got:0x40732
2017-09-05T20:55:09.528Z,1504644909.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.397728 seconds old.
2017-09-05T20:55:09.530Z,1504644909.530 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.401733 seconds old.
2017-09-05T20:55:09.656Z,1504644909.656 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:09.919Z,1504644909.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.792955 seconds old.
2017-09-05T20:55:09.927Z,1504644909.927 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.798160 seconds old.
2017-09-05T20:55:10.330Z,1504644910.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.203707 seconds old.
2017-09-05T20:55:10.332Z,1504644910.332 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.203400 seconds old.
2017-09-05T20:55:10.732Z,1504644910.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.605541 seconds old.
2017-09-05T20:55:10.734Z,1504644910.734 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.605440 seconds old.
2017-09-05T20:55:11.151Z,1504644911.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.025006 seconds old.
2017-09-05T20:55:11.155Z,1504644911.155 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.025499 seconds old.
2017-09-05T20:55:11.542Z,1504644911.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.415920 seconds old.
2017-09-05T20:55:11.545Z,1504644911.545 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.416420 seconds old.
2017-09-05T20:55:11.944Z,1504644911.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 2.818260 seconds old.
2017-09-05T20:55:11.947Z,1504644911.947 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 2.818653 seconds old.
2017-09-05T20:55:12.040Z,1504644912.040 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:12.338Z,1504644912.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.212381 seconds old.
2017-09-05T20:55:12.341Z,1504644912.341 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.212280 seconds old.
2017-09-05T20:55:12.673Z,1504644912.673 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:12.731Z,1504644912.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 3.604402 seconds old.
2017-09-05T20:55:12.737Z,1504644912.737 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 3.604195 seconds old.
2017-09-05T20:55:13.128Z,1504644913.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.001629 seconds old.
2017-09-05T20:55:13.134Z,1504644913.134 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.005834 seconds old.
2017-09-05T20:55:13.539Z,1504644913.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.412981 seconds old.
2017-09-05T20:55:13.541Z,1504644913.541 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.412674 seconds old.
2017-09-05T20:55:13.602Z,1504644913.602 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:13.932Z,1504644913.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 4.806102 seconds old.
2017-09-05T20:55:13.935Z,1504644913.935 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 4.806601 seconds old.
2017-09-05T20:55:19.640Z,1504644919.640 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:20.894Z,1504644920.894 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:23.070Z,1504644923.070 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:24.840Z,1504644924.840 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:25.524Z,1504644925.524 [PNI_TCM](ERROR): CRC does not match. Expected:0x54482 got:0x6589
2017-09-05T20:55:27.240Z,1504644927.240 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:31.885Z,1504644931.885 [CommandLine](IMPORTANT): got command show stack
2017-09-05T20:55:31.885Z,1504644931.885 [CommandLine](IMPORTANT): Behavior Stack:

2017-09-05T20:55:31.886Z,1504644931.886 [rotate_sampler:Science](IMPORTANT): Priority 0: rotate_sampler:Science:A
2017-09-05T20:55:31.887Z,1504644931.887 [rotate_sampler:Science](IMPORTANT): Priority 1: rotate_sampler:Science:C
2017-09-05T20:55:31.887Z,1504644931.887 [rotate_sampler:Science](IMPORTANT): Priority 2: rotate_sampler:Science:F
2017-09-05T20:55:31.888Z,1504644931.888 [rotate_sampler:Science:PeakDetectChl](IMPORTANT): Priority 3: rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth
2017-09-05T20:55:31.888Z,1504644931.888 [rotate_sampler:NeedComms](IMPORTANT): Priority 4: rotate_sampler:NeedComms:B.GoToSurface
2017-09-05T20:55:31.888Z,1504644931.888 [rotate_sampler:NeedComms](IMPORTANT): Priority 5: rotate_sampler:NeedComms:C
2017-09-05T20:55:31.888Z,1504644931.888 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 6: rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope
2017-09-05T20:55:31.889Z,1504644931.889 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 7: rotate_sampler:StandardEnvelopes:B.DepthEnvelope
2017-09-05T20:55:31.889Z,1504644931.889 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 8: rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope
2017-09-05T20:55:31.890Z,1504644931.890 [rotate_sampler](IMPORTANT): Priority 9: rotate_sampler:I.Pitch
2017-09-05T20:55:31.890Z,1504644931.890 [rotate_sampler](IMPORTANT): Priority 10: rotate_sampler:J.Buoyancy
2017-09-05T20:55:31.892Z,1504644931.892 [rotate_sampler](IMPORTANT): Priority 11: rotate_sampler:K.SetSpeed
2017-09-05T20:55:31.892Z,1504644931.892 [rotate_sampler](IMPORTANT): Priority 12: rotate_sampler:M.Point
2017-09-05T20:55:36.078Z,1504644936.078 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:36.698Z,1504644936.698 [PNI_TCM](ERROR): CRC does not match. Expected:0x43265 got:0x1051
2017-09-05T20:55:37.100Z,1504644937.100 [PNI_TCM](ERROR): CRC does not match. Expected:0x3048 got:0x45319
2017-09-05T20:55:38.319Z,1504644938.319 [PNI_TCM](ERROR): CRC does not match. Expected:0x20276 got:0x40967
2017-09-05T20:55:38.886Z,1504644938.886 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:41.280Z,1504644941.280 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:45.016Z,1504644945.016 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:48.870Z,1504644948.870 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:55:49.514Z,1504644949.514 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287
2017-09-05T20:55:51.508Z,1504644951.508 [PNI_TCM](ERROR): CRC does not match. Expected:0x11358 got:0x7105
2017-09-05T20:55:58.846Z,1504644958.846 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:00.400Z,1504644960.400 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:00.715Z,1504644960.715 [PNI_TCM](ERROR): CRC does not match. Expected:0x11359 got:0x17415
2017-09-05T20:56:05.533Z,1504644965.533 [PNI_TCM](ERROR): CRC does not match. Expected:0x5010 got:0x16855
2017-09-05T20:56:07.992Z,1504644967.992 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:11.646Z,1504644971.646 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:16.706Z,1504644976.706 [PNI_TCM](ERROR): CRC does not match. Expected:0x51728 got:0x48775
2017-09-05T20:56:16.838Z,1504644976.838 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:23.423Z,1504644983.423 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.800003 degree
2017-09-05T20:56:23.424Z,1504644983.424 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2017-09-05T20:56:24.438Z,1504644984.438 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:25.682Z,1504644985.682 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:28.076Z,1504644988.076 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:35.906Z,1504644995.906 [PNI_TCM](ERROR): CRC does not match. Expected:0x26014 got:0x49152
2017-09-05T20:56:37.022Z,1504644997.022 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:40.713Z,1504645000.713 [PNI_TCM](ERROR): CRC does not match. Expected:0x20055 got:0x45851
2017-09-05T20:56:43.269Z,1504645003.269 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:43.911Z,1504645003.911 [PNI_TCM](ERROR): CRC does not match. Expected:0x53602 got:0x49555
2017-09-05T20:56:51.907Z,1504645011.907 [PNI_TCM](ERROR): CRC does not match. Expected:0x39168 got:0x7105
2017-09-05T20:56:55.846Z,1504645015.846 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:56:58.786Z,1504645018.786 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1504644992.000000 second
2017-09-05T20:56:59.149Z,1504645019.149 [rotate_sampler:NeedComms:C] Stopped
2017-09-05T20:56:59.149Z,1504645019.149 [rotate_sampler:NeedComms:D] Running Loop=1
2017-09-05T20:56:59.544Z,1504645019.544 [rotate_sampler:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-05T20:56:59.999Z,1504645019.999 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:02.306Z,1504645022.306 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:06.246Z,1504645026.246 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:15.094Z,1504645035.094 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:15.269Z,1504645035.269 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2017-09-05T20:57:15.284Z,1504645035.284 [rotate_sampler:NeedComms:D] Stopped
2017-09-05T20:57:15.284Z,1504645035.284 [rotate_sampler:NeedComms:E] Running Loop=1
2017-09-05T20:57:15.635Z,1504645035.635 [rotate_sampler:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-09-05T20:57:19.036Z,1504645039.036 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:22.308Z,1504645042.308 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 0.000000 bool
2017-09-05T20:57:22.691Z,1504645042.691 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:23.532Z,1504645043.532 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:57:23.532Z,1504645043.532 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:57:25.082Z,1504645045.082 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:26.637Z,1504645046.637 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:27.682Z,1504645047.682 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:27.891Z,1504645047.891 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:30.076Z,1504645050.076 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2017-09-05T20:57:32.674Z,1504645052.674 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:35.058Z,1504645055.058 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:39.012Z,1504645059.012 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:40.258Z,1504645060.258 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:40.464Z,1504645060.464 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:43.077Z,1504645063.077 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:45.466Z,1504645065.466 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:47.852Z,1504645067.852 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:49.413Z,1504645069.413 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:53.058Z,1504645073.058 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:56.904Z,1504645076.904 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:57:59.623Z,1504645079.623 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 0.000000 bool
2017-09-05T20:57:59.805Z,1504645079.805 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:04.300Z,1504645084.300 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:10.634Z,1504645090.634 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:13.443Z,1504645093.443 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:15.835Z,1504645095.835 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:17.130Z,1504645097.130 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2017-09-05T20:58:17.289Z,1504645097.289 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:19.535Z,1504645099.535 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:58:19.535Z,1504645099.535 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:58:19.689Z,1504645099.689 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:23.418Z,1504645103.418 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:30.305Z,1504645110.305 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:42.464Z,1504645122.464 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:43.528Z,1504645123.528 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:44.860Z,1504645124.860 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:48.920Z,1504645128.920 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:51.310Z,1504645131.310 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:55.040Z,1504645135.040 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:56.511Z,1504645136.511 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:58:59.944Z,1504645139.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=420, MT Status=2, MTMSN=0
2017-09-05T20:58:59.944Z,1504645139.944 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-05T20:59:00.334Z,1504645140.334 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-09-05T20:59:01.079Z,1504645141.079 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:01.294Z,1504645141.294 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:01.541Z,1504645141.541 [NAL9602](INFO): GPS fix at 20170905T205818: (36.802754, -121.788046)
2017-09-05T20:59:01.632Z,1504645141.632 [rotate_sampler:NeedComms:E] Stopped
2017-09-05T20:59:01.633Z,1504645141.633 [rotate_sampler:NeedComms](INFO): Completed rotate_sampler:NeedComms
2017-09-05T20:59:01.633Z,1504645141.633 [rotate_sampler:NeedComms] Stopped
2017-09-05T20:59:01.633Z,1504645141.633 [rotate_sampler:NeedComms](DEBUG): Aggregate::uninitialize rotate_sampler:NeedComms
2017-09-05T20:59:01.633Z,1504645141.633 [rotate_sampler:NeedComms:B.GoToSurface] Stopped
2017-09-05T20:59:01.633Z,1504645141.633 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-05T20:59:02.014Z,1504645142.014 [rotate_sampler:StartingMission](INFO): Completed rotate_sampler:StartingMission
2017-09-05T20:59:02.014Z,1504645142.014 [rotate_sampler:StartingMission] Stopped
2017-09-05T20:59:02.014Z,1504645142.014 [rotate_sampler:StartingMission](DEBUG): Aggregate::uninitialize rotate_sampler:StartingMission
2017-09-05T20:59:02.014Z,1504645142.014 [rotate_sampler:L] Running Loop=1
2017-09-05T20:59:02.417Z,1504645142.417 [rotate_sampler:L] Stopped
2017-09-05T20:59:02.417Z,1504645142.417 [rotate_sampler:O] Running Loop=1
2017-09-05T20:59:02.845Z,1504645142.845 [rotate_sampler:O] Stopped
2017-09-05T20:59:02.846Z,1504645142.846 [rotate_sampler:P] Running Loop=1
2017-09-05T20:59:03.219Z,1504645143.219 [rotate_sampler:P] Stopped
2017-09-05T20:59:03.219Z,1504645143.219 [rotate_sampler:Cast] Running Loop=1
2017-09-05T20:59:03.219Z,1504645143.219 [rotate_sampler:Cast](DEBUG): Aggregate::initialize rotate_sampler:Cast
2017-09-05T20:59:03.219Z,1504645143.219 [rotate_sampler:Cast:A] Running Loop=1
2017-09-05T20:59:03.604Z,1504645143.604 [rotate_sampler:Cast:A] Stopped
2017-09-05T20:59:03.605Z,1504645143.605 [rotate_sampler:Cast:Depth01] Running Loop=1
2017-09-05T20:59:03.605Z,1504645143.605 [rotate_sampler:Cast:Depth01](DEBUG): Aggregate::initialize rotate_sampler:Cast:Depth01
2017-09-05T20:59:06.497Z,1504645146.497 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:08.178Z,1504645148.178 [NAL9602](INFO): Powering down
2017-09-05T20:59:12.620Z,1504645152.620 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:12.822Z,1504645152.822 [CommandLine](IMPORTANT): got command get longitude
2017-09-05T20:59:12.823Z,1504645152.823 [CommandLine](IMPORTANT): longitude -121.788032 arcdeg
2017-09-05T20:59:20.420Z,1504645160.420 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:21.485Z,1504645161.485 [CommandLine](IMPORTANT): got command show stack
2017-09-05T20:59:21.485Z,1504645161.485 [CommandLine](IMPORTANT): Behavior Stack:

2017-09-05T20:59:21.489Z,1504645161.489 [rotate_sampler:Science](IMPORTANT): Priority 0: rotate_sampler:Science:A
2017-09-05T20:59:21.489Z,1504645161.489 [rotate_sampler:Science](IMPORTANT): Priority 1: rotate_sampler:Science:C
2017-09-05T20:59:21.490Z,1504645161.490 [rotate_sampler:Science](IMPORTANT): Priority 2: rotate_sampler:Science:F
2017-09-05T20:59:21.490Z,1504645161.490 [rotate_sampler:Science:PeakDetectChl](IMPORTANT): Priority 3: rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth
2017-09-05T20:59:21.491Z,1504645161.491 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 4: rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope
2017-09-05T20:59:21.491Z,1504645161.491 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 5: rotate_sampler:StandardEnvelopes:B.DepthEnvelope
2017-09-05T20:59:21.491Z,1504645161.491 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 6: rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope
2017-09-05T20:59:21.491Z,1504645161.491 [rotate_sampler](IMPORTANT): Priority 7: rotate_sampler:I.Pitch
2017-09-05T20:59:21.492Z,1504645161.492 [rotate_sampler](IMPORTANT): Priority 8: rotate_sampler:J.Buoyancy
2017-09-05T20:59:21.492Z,1504645161.492 [rotate_sampler](IMPORTANT): Priority 9: rotate_sampler:K.SetSpeed
2017-09-05T20:59:21.492Z,1504645161.492 [rotate_sampler](IMPORTANT): Priority 10: rotate_sampler:M.Point
2017-09-05T20:59:25.316Z,1504645165.316 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:25.617Z,1504645165.617 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:26.660Z,1504645166.660 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:27.060Z,1504645167.060 [Radio_Surface](INFO): Powering down
2017-09-05T20:59:27.517Z,1504645167.517 [Radio_Surface](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated.
2017-09-05T20:59:27.517Z,1504645167.517 [Radio_Surface] Hardware Fault, FailCount= 1
2017-09-05T20:59:27.517Z,1504645167.517 [Radio_Surface](ERROR): Hardware Fault
2017-09-05T20:59:27.647Z,1504645167.647 [CBIT](ERROR): Hardware Fault in component: Radio_Surface
2017-09-05T20:59:28.405Z,1504645168.405 [CBIT](INFO): Clearing failed state for component Radio_Surface
2017-09-05T20:59:28.405Z,1504645168.405 [Radio_Surface] No Fault, FailCount= 1
2017-09-05T20:59:28.722Z,1504645168.722 [Radio_Surface](INFO): Powering up
2017-09-05T20:59:34.389Z,1504645174.389 [Radio_Surface](INFO): Powering down
2017-09-05T20:59:35.717Z,1504645175.717 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:39.254Z,1504645179.254 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:41.679Z,1504645181.679 [CommandLine](IMPORTANT): got command show variable target_Depth
2017-09-05T20:59:44.458Z,1504645184.458 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:45.097Z,1504645185.097 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:46.719Z,1504645186.719 [CommandLine](IMPORTANT): got command show variable TargetDepth
2017-09-05T20:59:46.907Z,1504645186.907 [CommandLine](IMPORTANT): TempGradientCalculator.targetDepth (meter)
2017-09-05T20:59:46.945Z,1504645186.945 [CommandLine](IMPORTANT): rotate_sampler:SampleAtDepth.TargetDepth (meter)
2017-09-05T20:59:46.947Z,1504645186.947 [CommandLine](IMPORTANT): rotate_sampler:SampleWithinEnvelopesWrapper.TargetDepth (meter)
2017-09-05T20:59:48.425Z,1504645188.425 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:54.698Z,1504645194.698 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T20:59:55.361Z,1504645195.361 [CommandLine](IMPORTANT): got command get rotate_sampler:SampleAtDepth.TargetDepth
2017-09-05T20:59:55.362Z,1504645195.362 [CommandLine](IMPORTANT): rotate_sampler:SampleAtDepth.TargetDepth 5.000000 m
2017-09-05T20:59:57.511Z,1504645197.511 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:07.114Z,1504645207.114 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:07.248Z,1504645207.248 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 5.000000 meter
2017-09-05T21:00:07.250Z,1504645207.250 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2017-09-05T21:00:07.549Z,1504645207.549 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-09-05T21:00:07.631Z,1504645207.631 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:08.472Z,1504645208.472 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:09.840Z,1504645209.840 [CommandLine](IMPORTANT): got command get depth
2017-09-05T21:00:09.840Z,1504645209.840 [CommandLine](IMPORTANT): depth 1.206724 m
2017-09-05T21:00:13.681Z,1504645213.681 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:15.881Z,1504645215.881 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:16.632Z,1504645216.632 [CommandLine](IMPORTANT): got command get depth
2017-09-05T21:00:16.633Z,1504645216.633 [CommandLine](IMPORTANT): depth 5.000000 m
2017-09-05T21:00:20.325Z,1504645220.325 [CommandLine](IMPORTANT): got command show stack
2017-09-05T21:00:20.326Z,1504645220.326 [CommandLine](IMPORTANT): Behavior Stack:

2017-09-05T21:00:20.328Z,1504645220.328 [rotate_sampler:Science](IMPORTANT): Priority 0: rotate_sampler:Science:A
2017-09-05T21:00:20.328Z,1504645220.328 [rotate_sampler:Science](IMPORTANT): Priority 1: rotate_sampler:Science:C
2017-09-05T21:00:20.328Z,1504645220.328 [rotate_sampler:Science](IMPORTANT): Priority 2: rotate_sampler:Science:F
2017-09-05T21:00:20.328Z,1504645220.328 [rotate_sampler:Science:PeakDetectChl](IMPORTANT): Priority 3: rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth
2017-09-05T21:00:20.329Z,1504645220.329 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 4: rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope
2017-09-05T21:00:20.329Z,1504645220.329 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 5: rotate_sampler:StandardEnvelopes:B.DepthEnvelope
2017-09-05T21:00:20.329Z,1504645220.329 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 6: rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope
2017-09-05T21:00:20.329Z,1504645220.329 [rotate_sampler](IMPORTANT): Priority 7: rotate_sampler:I.Pitch
2017-09-05T21:00:20.330Z,1504645220.330 [rotate_sampler](IMPORTANT): Priority 8: rotate_sampler:J.Buoyancy
2017-09-05T21:00:20.330Z,1504645220.330 [rotate_sampler](IMPORTANT): Priority 9: rotate_sampler:K.SetSpeed
2017-09-05T21:00:20.330Z,1504645220.330 [rotate_sampler](IMPORTANT): Priority 10: rotate_sampler:M.Point
2017-09-05T21:00:26.834Z,1504645226.834 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:28.300Z,1504645228.300 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:32.254Z,1504645232.254 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:33.101Z,1504645233.101 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:38.320Z,1504645238.320 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:48.132Z,1504645248.132 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:48.460Z,1504645248.460 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:50.314Z,1504645250.314 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:52.302Z,1504645252.302 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:00:52.927Z,1504645252.927 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:04.513Z,1504645264.513 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:10.336Z,1504645270.336 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004206
2017-09-05T21:01:20.471Z,1504645280.471 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:22.241Z,1504645282.241 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:26.328Z,1504645286.328 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:30.443Z,1504645290.443 [PNI_TCM](ERROR): CRC does not match. Expected:0x50993 got:0x49674
2017-09-05T21:01:36.444Z,1504645296.444 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:36.876Z,1504645296.876 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:45.311Z,1504645305.311 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:48.863Z,1504645308.863 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:50.558Z,1504645310.558 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:51.471Z,1504645311.471 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:53.663Z,1504645313.663 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:01:59.724Z,1504645319.724 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:00.446Z,1504645320.446 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:00.643Z,1504645320.643 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:02.537Z,1504645322.537 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:05.249Z,1504645325.249 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:05.874Z,1504645325.874 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:06.000Z,1504645326.000 [CommandLine](IMPORTANT): got command maintain clear
2017-09-05T21:02:06.204Z,1504645326.204 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-09-05T21:02:06.566Z,1504645326.566 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2017-09-05T21:02:06.566Z,1504645326.566 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-09-05T21:02:06.566Z,1504645326.566 [rotate_sampler:Science:PeakDetectChl:B] Running Loop=1
2017-09-05T21:02:06.954Z,1504645326.954 [rotate_sampler:Science:PeakDetectChl:B] Stopped
2017-09-05T21:02:06.954Z,1504645326.954 [rotate_sampler:Science:PeakDetectChl](INFO): Completed rotate_sampler:Science:PeakDetectChl
2017-09-05T21:02:07.365Z,1504645327.365 [rotate_sampler:Science:PeakDetectChl] Running Loop=1
2017-09-05T21:02:07.365Z,1504645327.365 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectChl
2017-09-05T21:02:07.365Z,1504645327.365 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2017-09-05T21:02:07.365Z,1504645327.365 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-09-05T21:02:08.274Z,1504645328.274 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:08.923Z,1504645328.923 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:08.985Z,1504645328.985 [CommandLine](IMPORTANT): got command get depth
2017-09-05T21:02:08.986Z,1504645328.986 [CommandLine](IMPORTANT): depth 1.065909 m
2017-09-05T21:02:10.663Z,1504645330.663 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:13.486Z,1504645333.486 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:18.086Z,1504645338.086 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:18.706Z,1504645338.706 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:20.065Z,1504645340.065 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:24.466Z,1504645344.466 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 40.000000 meter
2017-09-05T21:02:24.467Z,1504645344.467 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2017-09-05T21:02:24.625Z,1504645344.625 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-09-05T21:02:25.790Z,1504645345.790 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2017-09-05T21:02:25.790Z,1504645345.790 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-09-05T21:02:25.790Z,1504645345.790 [rotate_sampler:Science:PeakDetectChl:B] Running Loop=1
2017-09-05T21:02:26.127Z,1504645346.127 [rotate_sampler:Science:PeakDetectChl:B] Stopped
2017-09-05T21:02:26.127Z,1504645346.127 [rotate_sampler:Science:PeakDetectChl](INFO): Completed rotate_sampler:Science:PeakDetectChl
2017-09-05T21:02:26.326Z,1504645346.326 [CTD_NeilBrown](DEBUG): serial timeout
2017-09-05T21:02:26.549Z,1504645346.549 [rotate_sampler:Science:PeakDetectChl] Running Loop=1
2017-09-05T21:02:26.549Z,1504645346.549 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectChl
2017-09-05T21:02:26.550Z,1504645346.550 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2017-09-05T21:02:26.550Z,1504645346.550 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2017-09-05T21:02:30.125Z,1504645350.125 [VerticalControl](CRITICAL): Excessive depth excursion=38.991718 m, failToGoUpDepth_=1.008283 m, depthRate=1.434844 m/s, pitch=29.933489 deg.
2017-09-05T21:02:30.179Z,1504645350.179 [CommandLine](FAULT): Scheduling is paused
2017-09-05T21:02:30.179Z,1504645350.179 [CBIT](INFO): Critical error at 20170905T210230
2017-09-05T21:02:30.179Z,1504645350.179 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-09-05T21:02:30.548Z,1504645350.548 [MissionManager](INFO): MissionManager is completed.
2017-09-05T21:02:30.548Z,1504645350.548 [MissionManager](INFO): Uninitializing Mission rotate_sampler
2017-09-05T21:02:30.549Z,1504645350.549 [rotate_sampler] Stopped
2017-09-05T21:02:30.549Z,1504645350.549 [rotate_sampler](DEBUG): Aggregate::uninitialize rotate_sampler
2017-09-05T21:02:30.549Z,1504645350.549 [rotate_sampler:Science] Stopped
2017-09-05T21:02:30.549Z,1504645350.549 [rotate_sampler:Science](DEBUG): Aggregate::uninitialize rotate_sampler:Science
2017-09-05T21:02:30.553Z,1504645350.553 [rotate_sampler:Science:A] Stopped
2017-09-05T21:02:30.553Z,1504645350.553 [rotate_sampler:Science:C] Stopped
2017-09-05T21:02:30.553Z,1504645350.553 [rotate_sampler:Science:F] Stopped
2017-09-05T21:02:30.553Z,1504645350.553 [rotate_sampler:Science:PeakDetectChl] Stopped
2017-09-05T21:02:30.553Z,1504645350.553 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize rotate_sampler:Science:PeakDetectChl
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes](DEBUG): Aggregate::uninitialize rotate_sampler:StandardEnvelopes
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:I.Pitch] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:J.Buoyancy] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:K.SetSpeed] Stopped
2017-09-05T21:02:30.554Z,1504645350.554 [rotate_sampler:K.SetSpeed](DEBUG): Uninitialize.
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:M.Point] Stopped
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:SampleWithinEnvelopesWrapper] Stopped
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:SampleWithinEnvelopesWrapper](DEBUG): Aggregate::uninitialize rotate_sampler:SampleWithinEnvelopesWrapper
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:Cast] Stopped
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:Cast](DEBUG): Aggregate::uninitialize rotate_sampler:Cast
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:Cast:Depth01] Stopped
2017-09-05T21:02:30.555Z,1504645350.555 [rotate_sampler:Cast:Depth01](DEBUG): Aggregate::uninitialize rotate_sampler:Cast:Depth01
2017-09-05T21:02:30.914Z,1504645350.914 [MissionManager](IMPORTANT): Started mission Default
2017-09-05T21:02:30.914Z,1504645350.914 [Default] Running Loop=1
2017-09-05T21:02:30.914Z,1504645350.914 [Default](DEBUG): Aggregate::initialize Default
2017-09-05T21:02:30.914Z,1504645350.914 [Default:B.GoToSurface] Running Loop=1
2017-09-05T21:02:30.914Z,1504645350.914 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-05T21:02:30.914Z,1504645350.914 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-05T21:02:30.915Z,1504645350.915 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-05T21:02:30.915Z,1504645350.915 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-05T21:02:30.915Z,1504645350.915 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-05T21:02:30.916Z,1504645350.916 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-05T21:02:30.916Z,1504645350.916 [Default:A.Wait] Running Loop=1
2017-09-05T21:02:30.916Z,1504645350.916 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-09-05T21:02:30.917Z,1504645350.917 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:30.954Z,1504645350.954 [WetLabsBB2FL](INFO): Powering down
2017-09-05T21:02:30.975Z,1504645350.975 [CBIT](DEBUG): EFC running - data check-sum false
2017-09-05T21:02:31.215Z,1504645351.215 [Radio_Surface](INFO): Powering up
2017-09-05T21:02:31.347Z,1504645351.347 [Aanderaa_O2](INFO): Powering down
2017-09-05T21:02:31.429Z,1504645351.429 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:31.693Z,1504645351.693 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:32.101Z,1504645352.101 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:32.495Z,1504645352.495 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:33.111Z,1504645353.111 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:33.510Z,1504645353.510 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:33.918Z,1504645353.918 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:34.318Z,1504645354.318 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:34.713Z,1504645354.713 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:35.085Z,1504645355.085 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:35.497Z,1504645355.497 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:35.520Z,1504645355.520 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=38.991718, failToGoUpDepth_=1.008283, depthRate=1.434844 m/s, pitch=29.838945 deg.
2017-09-05T21:02:35.720Z,1504645355.720 [CBIT](INFO): Critical error at 20170905T210235
2017-09-05T21:02:35.927Z,1504645355.927 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:36.296Z,1504645356.296 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:36.713Z,1504645356.713 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:37.112Z,1504645357.112 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:37.502Z,1504645357.502 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:37.910Z,1504645357.910 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:38.325Z,1504645358.325 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:38.716Z,1504645358.716 [Default:B.GoToSurface](ERROR): Altitude too low: -39.38
2017-09-05T21:02:39.122Z,1504645359.122 [Default:B.GoToSurface](ERROR): Altitude too low: -39.58
2017-09-05T21:02:39.530Z,1504645359.530 [Default:B.GoToSurface](ERROR): Altitude too low: -39.58
2017-09-05T21:02:39.929Z,1504645359.929 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:40.305Z,1504645360.305 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:40.714Z,1504645360.714 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:41.109Z,1504645361.109 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:41.527Z,1504645361.527 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:41.911Z,1504645361.911 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:42.320Z,1504645362.320 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:42.709Z,1504645362.709 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:42.923Z,1504645362.923 [CommandLine](IMPORTANT): got command stop
2017-09-05T21:02:42.923Z,1504645362.923 [CommandLine](IMPORTANT): Scheduling is paused
2017-09-05T21:02:43.123Z,1504645363.123 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:43.538Z,1504645363.538 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:43.899Z,1504645363.899 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:44.306Z,1504645364.306 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:44.306Z,1504645364.306 [Default:A.Wait](INFO): Done Waiting.
2017-09-05T21:02:44.307Z,1504645364.307 [Default:A.Wait] Stopped
2017-09-05T21:02:44.307Z,1504645364.307 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-09-05T21:02:44.710Z,1504645364.710 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:45.145Z,1504645365.145 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:45.503Z,1504645365.503 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:45.924Z,1504645365.924 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:46.313Z,1504645366.313 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:46.548Z,1504645366.548 [CommandLine](IMPORTANT): got command maintain clear
2017-09-05T21:02:46.697Z,1504645366.697 [Default:B.GoToSurface](ERROR): Altitude too low: -39.55
2017-09-05T21:02:46.813Z,1504645366.813 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-09-05T21:02:47.128Z,1504645367.128 [Default:CheckIn] Running Loop=1
2017-09-05T21:02:47.128Z,1504645367.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-05T21:02:47.128Z,1504645367.128 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-05T21:02:47.855Z,1504645367.855 [NAL9602](INFO): Powering up
2017-09-05T21:02:59.123Z,1504645379.123 [CommandLine](IMPORTANT): got command restart application
2017-09-05T21:03:00.133Z,1504645380.133 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:00.133Z,1504645380.133 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.172Z,1504645380.172 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-09-05T21:03:00.172Z,1504645380.172 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.173Z,1504645380.173 [CommandLine](INFO): Join timeout helper Thread ID is 9668
2017-09-05T21:03:00.204Z,1504645380.204 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-09-05T21:03:00.204Z,1504645380.204 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.205Z,1504645380.205 [NavChartDb](INFO): Join timeout helper Thread ID is 9669
2017-09-05T21:03:00.429Z,1504645380.429 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:00.429Z,1504645380.429 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.436Z,1504645380.436 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-09-05T21:03:00.437Z,1504645380.437 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.437Z,1504645380.437 [Radio_Surface](INFO): Join timeout helper Thread ID is 9670
2017-09-05T21:03:00.699Z,1504645380.699 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:00.699Z,1504645380.699 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.705Z,1504645380.705 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-09-05T21:03:00.706Z,1504645380.706 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.707Z,1504645380.707 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9671
2017-09-05T21:03:00.926Z,1504645380.926 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:00.926Z,1504645380.926 [WetLabsBB2FL](INFO): Powering down
2017-09-05T21:03:00.926Z,1504645380.926 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.945Z,1504645380.945 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-09-05T21:03:00.945Z,1504645380.945 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:00.946Z,1504645380.946 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9672
2017-09-05T21:03:01.075Z,1504645381.075 [NAL9602](INFO): NAL9602 initialized
2017-09-05T21:03:01.274Z,1504645381.274 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:01.364Z,1504645381.364 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-09-05T21:03:01.365Z,1504645381.365 [CTD_Seabird](INFO): Powering down
2017-09-05T21:03:01.366Z,1504645381.366 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.375Z,1504645381.375 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-09-05T21:03:01.375Z,1504645381.375 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.376Z,1504645381.376 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9674
2017-09-05T21:03:01.719Z,1504645381.719 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:01.719Z,1504645381.719 [CTD_NeilBrown](INFO): Powering down
2017-09-05T21:03:01.720Z,1504645381.720 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.735Z,1504645381.735 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-09-05T21:03:01.735Z,1504645381.735 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.736Z,1504645381.736 [logger](INFO): Join timeout helper Thread ID is 9675
2017-09-05T21:03:01.739Z,1504645381.739 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:01.739Z,1504645381.739 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.755Z,1504645381.755 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-09-05T21:03:01.755Z,1504645381.755 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.756Z,1504645381.756 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-09-05T21:03:01.756Z,1504645381.756 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:01.756Z,1504645381.756 [controlThread](INFO): Join timeout helper Thread ID is 9676
2017-09-05T21:03:01.851Z,1504645381.851 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-05T21:03:01.851Z,1504645381.851 [controlThread](DEBUG): Uninitializing ControlThread
2017-09-05T21:03:01.851Z,1504645381.851 [Aanderaa_O2](INFO): Powering down
2017-09-05T21:03:01.854Z,1504645381.854 [NAL9602](INFO): Powering down
2017-09-05T21:03:01.855Z,1504645381.855 [PNI_TCM](INFO): Powering down
2017-09-05T21:03:01.943Z,1504645381.943 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-05T21:03:01.945Z,1504645381.945 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-09-05T21:03:01.946Z,1504645381.946 [MissionManager](INFO): Uninitializing Mission Default
2017-09-05T21:03:01.946Z,1504645381.946 [Default] Stopped
2017-09-05T21:03:01.946Z,1504645381.946 [Default](DEBUG): Aggregate::uninitialize Default
2017-09-05T21:03:01.946Z,1504645381.946 [Default:B.GoToSurface] Stopped
2017-09-05T21:03:01.946Z,1504645381.946 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-05T21:03:01.946Z,1504645381.946 [Default:CheckIn] Stopped
2017-09-05T21:03:01.947Z,1504645381.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-09-05T21:03:01.947Z,1504645381.947 [Default:CheckIn:Read_GPS] Stopped
2017-09-05T21:03:01.949Z,1504645381.949 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-09-05T21:03:01.950Z,1504645381.950 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-09-05T21:03:01.950Z,1504645381.950 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-09-05T21:03:01.950Z,1504645381.950 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-09-05T21:03:01.951Z,1504645381.951 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-05T21:03:01.952Z,1504645381.952 [BuoyancyServo](INFO): Powering down
2017-09-05T21:03:01.967Z,1504645381.967 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-09-05T21:03:01.967Z,1504645381.967 [ElevatorServo](INFO): Powering down
2017-09-05T21:03:01.968Z,1504645381.968 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-09-05T21:03:01.968Z,1504645381.968 [MassServo](INFO): Powering down
2017-09-05T21:03:01.969Z,1504645381.969 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-09-05T21:03:01.969Z,1504645381.969 [RudderServo](INFO): Powering down
2017-09-05T21:03:01.970Z,1504645381.970 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-09-05T21:03:01.970Z,1504645381.970 [ThrusterServo](INFO): Powering down
2017-09-05T21:03:01.971Z,1504645381.971 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-09-05T21:03:01.972Z,1504645381.972 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-09-05T21:03:01.972Z,1504645381.972 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-09-05T21:03:01.973Z,1504645381.973 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:02.025Z,1504645382.025 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:02.029Z,1504645382.029 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:02.031Z,1504645382.031 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:02.045Z,1504645382.045 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:02.207Z,1504645382.207 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-05T21:03:02.265Z,1504645382.265 [logger ThreadHandler](INFO): Thread cancelled.