2018-05-15T18:37:42.624Z,1526409462.624 [Supervisor](DEBUG): Initializing supervisor.
2018-05-15T18:37:42.627Z,1526409462.627 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-15T18:37:42.628Z,1526409462.628 [SyncHandler](INFO): Protected caller Thread ID is 2180
2018-05-15T18:37:42.628Z,1526409462.628 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-15T18:37:42.629Z,1526409462.629 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-15T18:37:42.629Z,1526409462.629 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2181
2018-05-15T18:37:42.633Z,1526409462.633 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-15T18:37:42.645Z,1526409462.645 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-15T18:37:42.646Z,1526409462.646 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-15T18:37:42.647Z,1526409462.647 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2182
2018-05-15T18:37:42.647Z,1526409462.647 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-15T18:37:42.649Z,1526409462.649 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-15T18:37:42.649Z,1526409462.649 [logger ThreadHandler](INFO): Protected caller Thread ID is 2183
2018-05-15T18:37:42.651Z,1526409462.651 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-15T18:37:42.652Z,1526409462.652 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-15T18:37:42.654Z,1526409462.654 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-15T18:37:42.747Z,1526409462.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-15T18:37:42.747Z,1526409462.747 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-15T18:37:42.845Z,1526409462.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-15T18:37:42.845Z,1526409462.845 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-15T18:37:43.033Z,1526409463.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-15T18:37:43.034Z,1526409463.034 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-15T18:37:43.331Z,1526409463.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-15T18:37:43.332Z,1526409463.332 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-15T18:37:43.644Z,1526409463.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-15T18:37:43.645Z,1526409463.645 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-15T18:37:44.078Z,1526409464.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-15T18:37:44.079Z,1526409464.079 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-15T18:37:44.421Z,1526409464.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-15T18:37:44.422Z,1526409464.422 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-15T18:37:44.838Z,1526409464.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-15T18:37:44.838Z,1526409464.838 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-15T18:37:44.917Z,1526409464.917 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-15T18:37:45.197Z,1526409465.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-15T18:37:45.197Z,1526409465.197 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-15T18:37:45.334Z,1526409465.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-15T18:37:45.334Z,1526409465.334 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-15T18:37:45.535Z,1526409465.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-15T18:37:45.536Z,1526409465.536 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-15T18:37:45.736Z,1526409465.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-15T18:37:45.737Z,1526409465.737 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-15T18:37:45.831Z,1526409465.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-15T18:37:45.831Z,1526409465.831 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-15T18:37:45.966Z,1526409465.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-15T18:37:45.968Z,1526409465.968 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-15T18:37:45.969Z,1526409465.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-15T18:37:46.195Z,1526409466.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-15T18:37:46.195Z,1526409466.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-15T18:37:46.271Z,1526409466.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-15T18:37:46.406Z,1526409466.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-15T18:37:46.496Z,1526409466.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-15T18:37:46.579Z,1526409466.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-15T18:37:46.711Z,1526409466.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-15T18:37:46.976Z,1526409466.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-15T18:37:47.186Z,1526409467.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-15T18:37:47.358Z,1526409467.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-15T18:37:47.516Z,1526409467.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-15T18:37:47.829Z,1526409467.829 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-05-15T18:37:47.829Z,1526409467.829 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-15T18:37:47.843Z,1526409467.843 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-15T18:37:47.940Z,1526409467.940 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-15T18:37:47.940Z,1526409467.940 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-15T18:37:47.954Z,1526409467.954 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-15T18:37:47.955Z,1526409467.955 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-15T18:37:47.995Z,1526409467.995 [DepthRateCalculator] Loaded
2018-05-15T18:37:47.995Z,1526409467.995 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-15T18:37:48.001Z,1526409468.001 [PitchRateCalculator] Loaded
2018-05-15T18:37:48.001Z,1526409468.001 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-15T18:37:48.017Z,1526409468.017 [SpeedCalculator] Loaded
2018-05-15T18:37:48.017Z,1526409468.017 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-15T18:37:48.038Z,1526409468.038 [TempGradientCalculator] Loaded
2018-05-15T18:37:48.038Z,1526409468.038 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-15T18:37:48.053Z,1526409468.053 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-15T18:37:48.053Z,1526409468.053 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-15T18:37:48.059Z,1526409468.059 [YawRateCalculator] Loaded
2018-05-15T18:37:48.059Z,1526409468.059 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-15T18:37:48.097Z,1526409468.097 [ElevatorOffsetCalculator] Loaded
2018-05-15T18:37:48.097Z,1526409468.097 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-15T18:37:48.098Z,1526409468.098 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-15T18:37:48.098Z,1526409468.098 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-15T18:37:48.122Z,1526409468.122 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-15T18:37:48.123Z,1526409468.123 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-15T18:37:48.417Z,1526409468.417 [AHRS_M2] Loaded
2018-05-15T18:37:48.417Z,1526409468.417 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-05-15T18:37:48.459Z,1526409468.459 [AcousticModem_Benthos_ATM900] Loaded
2018-05-15T18:37:48.459Z,1526409468.459 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-15T18:37:48.525Z,1526409468.525 [DataOverHttps] Loaded
2018-05-15T18:37:48.526Z,1526409468.526 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-15T18:37:48.538Z,1526409468.538 [Depth_Keller] Loaded
2018-05-15T18:37:48.538Z,1526409468.538 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-15T18:37:48.539Z,1526409468.539 [Module Loader](CRITICAL): Could not find element GobyModem.loadAtStartup
2018-05-15T18:37:48.632Z,1526409468.632 [NAL9602] Loaded
2018-05-15T18:37:48.633Z,1526409468.633 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-15T18:37:48.648Z,1526409468.648 [Onboard] Loaded
2018-05-15T18:37:48.648Z,1526409468.648 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-15T18:37:48.658Z,1526409468.658 [Radio_Surface] Loaded
2018-05-15T18:37:48.658Z,1526409468.658 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-15T18:37:48.659Z,1526409468.659 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-05-15T18:37:48.659Z,1526409468.659 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2262
2018-05-15T18:37:48.660Z,1526409468.660 [Module Loader](CRITICAL): Could not find element RDI_Pathfinder.loadAtStartup
2018-05-15T18:37:50.353Z,1526409470.353 [BPC1] Loaded
2018-05-15T18:37:50.353Z,1526409470.353 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-15T18:37:50.354Z,1526409470.354 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-15T18:37:50.354Z,1526409470.354 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-15T18:37:50.478Z,1526409470.478 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-15T18:37:50.501Z,1526409470.501 [SBIT] Loaded
2018-05-15T18:37:50.501Z,1526409470.501 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-15T18:37:50.502Z,1526409470.502 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-15T18:37:50.512Z,1526409470.512 [IBIT] Loaded
2018-05-15T18:37:50.513Z,1526409470.513 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-15T18:37:50.516Z,1526409470.516 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-15T18:37:50.660Z,1526409470.660 [CBIT] Loaded
2018-05-15T18:37:50.660Z,1526409470.660 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-15T18:37:50.661Z,1526409470.661 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-15T18:37:50.661Z,1526409470.661 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-15T18:37:50.830Z,1526409470.830 [CTD_Seabird] Loaded
2018-05-15T18:37:50.831Z,1526409470.831 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-15T18:37:50.832Z,1526409470.832 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408774E0
2018-05-15T18:37:50.832Z,1526409470.832 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2263
2018-05-15T18:37:50.848Z,1526409470.848 [ESPComponent] Loaded
2018-05-15T18:37:50.849Z,1526409470.849 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-15T18:37:50.862Z,1526409470.862 [PAR_Licor] Loaded
2018-05-15T18:37:50.862Z,1526409470.862 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-15T18:37:50.863Z,1526409470.863 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-15T18:37:50.864Z,1526409470.864 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-15T18:37:51.094Z,1526409471.094 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-15T18:37:51.095Z,1526409471.095 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-15T18:37:51.160Z,1526409471.160 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-15T18:37:51.251Z,1526409471.251 [VerticalControl] Loaded
2018-05-15T18:37:51.251Z,1526409471.251 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-15T18:37:51.252Z,1526409471.252 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-15T18:37:51.310Z,1526409471.310 [HorizontalControl] Loaded
2018-05-15T18:37:51.311Z,1526409471.311 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-15T18:37:51.311Z,1526409471.311 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-15T18:37:51.316Z,1526409471.316 [SpeedControl] Loaded
2018-05-15T18:37:51.317Z,1526409471.317 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-15T18:37:51.317Z,1526409471.317 [LoopControl](DEBUG): Construct LoopControl.
2018-05-15T18:37:51.318Z,1526409471.318 [LoopControl] Loaded
2018-05-15T18:37:51.318Z,1526409471.318 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-15T18:37:51.318Z,1526409471.318 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-15T18:37:51.319Z,1526409471.319 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-15T18:37:51.400Z,1526409471.400 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-15T18:37:51.400Z,1526409471.400 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-15T18:37:51.400Z,1526409471.400 [StratificationFrontDetector] Loaded
2018-05-15T18:37:51.400Z,1526409471.400 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-15T18:37:51.401Z,1526409471.401 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-15T18:37:51.401Z,1526409471.401 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-15T18:37:51.495Z,1526409471.495 [BuoyancyServo] Loaded
2018-05-15T18:37:51.495Z,1526409471.495 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-15T18:37:51.506Z,1526409471.506 [ElevatorServo] Loaded
2018-05-15T18:37:51.507Z,1526409471.507 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-15T18:37:51.517Z,1526409471.517 [MassServo] Loaded
2018-05-15T18:37:51.518Z,1526409471.518 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-15T18:37:51.528Z,1526409471.528 [RudderServo] Loaded
2018-05-15T18:37:51.529Z,1526409471.529 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-15T18:37:51.539Z,1526409471.539 [ThrusterServo] Loaded
2018-05-15T18:37:51.539Z,1526409471.539 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-15T18:37:51.540Z,1526409471.540 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-15T18:37:51.540Z,1526409471.540 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-15T18:37:51.634Z,1526409471.634 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-05-15T18:37:51.634Z,1526409471.634 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-05-15T18:37:51.687Z,1526409471.687 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-15T18:37:51.687Z,1526409471.687 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-15T18:37:51.703Z,1526409471.703 [NavChart] Loaded
2018-05-15T18:37:51.703Z,1526409471.703 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-15T18:37:51.707Z,1526409471.707 [UniversalFixResidualReporter] Loaded
2018-05-15T18:37:51.707Z,1526409471.707 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-15T18:37:51.708Z,1526409471.708 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-15T18:37:51.711Z,1526409471.711 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-15T18:37:51.713Z,1526409471.713 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-15T18:37:51.719Z,1526409471.719 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-15T18:37:51.720Z,1526409471.720 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2018-05-15T18:37:51.721Z,1526409471.721 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2264
2018-05-15T18:37:51.726Z,1526409471.726 [Supervisor](INFO): Main Thread ID is 2027
2018-05-15T18:37:51.726Z,1526409471.726 [Supervisor](DEBUG): Running supervisor.
2018-05-15T18:37:51.726Z,1526409471.726 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2265
2018-05-15T18:37:51.729Z,1526409471.729 [controlThread ThreadHandler](INFO): Handler Thread ID is 2266
2018-05-15T18:37:51.730Z,1526409471.730 [controlThread](DEBUG): Initializing ControlThread
2018-05-15T18:37:51.731Z,1526409471.731 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-15T18:37:51.731Z,1526409471.731 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-15T18:37:51.731Z,1526409471.731 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-15T18:37:51.732Z,1526409471.732 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-15T18:37:51.732Z,1526409471.732 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-15T18:37:51.733Z,1526409471.733 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-15T18:37:51.734Z,1526409471.734 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-15T18:37:51.738Z,1526409471.738 [SBIT](INFO): Initialize SBIT Component.
2018-05-15T18:37:51.739Z,1526409471.739 [SBIT](IMPORTANT): git: 2018-03-26-63-g035c652
2018-05-15T18:37:51.739Z,1526409471.739 [SBIT](INFO): git hash: 035c652db7f27c72339a0dc8ffe46f005dcbcd78
2018-05-15T18:37:51.739Z,1526409471.739 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-15T18:37:51.740Z,1526409471.740 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #16 PREEMPT Thu Apr 12 16:20:47 PDT 2018
2018-05-15T18:37:51.742Z,1526409471.742 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2018-05-15T18:37:51.742Z,1526409471.742 [IBIT](INFO): Initialize IBIT Component.
2018-05-15T18:37:51.743Z,1526409471.743 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-15T18:37:51.743Z,1526409471.743 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2018-05-15T18:37:51.744Z,1526409471.744 [logger ThreadHandler](INFO): Handler Thread ID is 2267
2018-05-15T18:37:51.761Z,1526409471.761 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2268
2018-05-15T18:37:51.767Z,1526409471.767 [Radio_Surface](INFO): Powering up
2018-05-15T18:37:51.773Z,1526409471.773 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2269
2018-05-15T18:37:51.774Z,1526409471.774 [CTD_Seabird](INFO): Initializing
2018-05-15T18:37:51.774Z,1526409471.774 [CTD_Seabird](INFO): Checking LCM
2018-05-15T18:37:51.783Z,1526409471.783 [CTD_Seabird](INFO): LCM OK
2018-05-15T18:37:51.783Z,1526409471.783 [CTD_Seabird](INFO): Powering up
2018-05-15T18:37:51.789Z,1526409471.789 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2270
2018-05-15T18:37:51.793Z,1526409471.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-15T18:37:51.793Z,1526409471.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-15T18:37:51.793Z,1526409471.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-15T18:37:51.793Z,1526409471.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-15T18:37:51.793Z,1526409471.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-15T18:37:51.794Z,1526409471.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-15T18:37:51.794Z,1526409471.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-15T18:37:51.794Z,1526409471.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-15T18:37:51.794Z,1526409471.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-15T18:37:51.794Z,1526409471.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-15T18:37:51.795Z,1526409471.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-15T18:37:51.795Z,1526409471.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-15T18:37:51.795Z,1526409471.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-15T18:37:51.795Z,1526409471.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-15T18:37:51.795Z,1526409471.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-15T18:37:51.796Z,1526409471.796 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-15T18:37:51.857Z,1526409471.857 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-15T18:37:51.859Z,1526409471.859 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-15T18:37:51.859Z,1526409471.859 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-15T18:37:51.860Z,1526409471.860 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-15T18:37:51.862Z,1526409471.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-05-15T18:37:51.862Z,1526409471.862 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-05-15T18:37:51.863Z,1526409471.863 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-05-15T18:37:51.863Z,1526409471.863 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-15T18:37:51.864Z,1526409471.864 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-15T18:37:51.864Z,1526409471.864 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-15T18:37:51.865Z,1526409471.865 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-15T18:37:51.865Z,1526409471.865 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-15T18:37:51.866Z,1526409471.866 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-15T18:37:51.902Z,1526409471.902 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-15T18:37:51.928Z,1526409471.928 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-05-15T18:37:51.929Z,1526409471.929 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-15T18:37:52.007Z,1526409472.007 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-15T18:37:52.008Z,1526409472.008 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-15T18:37:52.022Z,1526409472.022 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-15T18:37:52.093Z,1526409472.093 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-15T18:37:52.096Z,1526409472.096 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-15T18:37:52.206Z,1526409472.206 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-15T18:37:52.253Z,1526409472.253 [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>


2018-05-15T18:37:52.258Z,1526409472.258 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-15T18:37:52.285Z,1526409472.285 [AHRS_M2](INFO): Start
2018-05-15T18:37:52.285Z,1526409472.285 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-15T18:37:52.357Z,1526409472.357 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-15T18:37:52.357Z,1526409472.357 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-15T18:37:52.383Z,1526409472.383 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-15T18:37:52.647Z,1526409472.647 [ESPComponent](INFO): powering down ESP
2018-05-15T18:37:53.061Z,1526409473.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:53.085Z,1526409473.085 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:53.189Z,1526409473.189 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-15T18:37:53.197Z,1526409473.197 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-15T18:37:53.219Z,1526409473.219 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-15T18:37:53.225Z,1526409473.225 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-15T18:37:53.231Z,1526409473.231 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-15T18:37:53.237Z,1526409473.237 [MassServo](DEBUG): Initializing MassServo.
2018-05-15T18:37:53.262Z,1526409473.262 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-15T18:37:53.269Z,1526409473.269 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-15T18:37:53.282Z,1526409473.282 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-15T18:37:53.289Z,1526409473.289 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-15T18:37:53.327Z,1526409473.327 [CommandLine](FAULT): Scheduling is paused
2018-05-15T18:37:53.328Z,1526409473.328 [CBIT](INFO): Critical error at 20180515T183751
2018-05-15T18:37:53.328Z,1526409473.328 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-15T18:37:53.445Z,1526409473.445 [AHRS_M2](INFO): Starting
2018-05-15T18:37:53.525Z,1526409473.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:53.526Z,1526409473.526 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:53.785Z,1526409473.785 [AHRS_M2](INFO): Starting
2018-05-15T18:37:53.857Z,1526409473.857 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:53.858Z,1526409473.858 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:54.249Z,1526409474.249 [AHRS_M2](INFO): Starting
2018-05-15T18:37:54.317Z,1526409474.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:54.318Z,1526409474.318 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:54.653Z,1526409474.653 [AHRS_M2](INFO): Starting
2018-05-15T18:37:54.753Z,1526409474.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:54.754Z,1526409474.754 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:55.053Z,1526409475.053 [AHRS_M2](INFO): Starting
2018-05-15T18:37:55.121Z,1526409475.121 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:55.122Z,1526409475.122 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:55.453Z,1526409475.453 [AHRS_M2](INFO): Starting
2018-05-15T18:37:55.556Z,1526409475.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:55.558Z,1526409475.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:55.853Z,1526409475.853 [AHRS_M2](INFO): Starting
2018-05-15T18:37:55.944Z,1526409475.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:55.953Z,1526409475.953 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:56.253Z,1526409476.253 [AHRS_M2](INFO): Starting
2018-05-15T18:37:56.337Z,1526409476.337 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:56.338Z,1526409476.338 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:56.653Z,1526409476.653 [AHRS_M2](INFO): Starting
2018-05-15T18:37:56.697Z,1526409476.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:56.698Z,1526409476.698 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:57.065Z,1526409477.065 [AHRS_M2](INFO): Starting
2018-05-15T18:37:57.096Z,1526409477.096 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:57.098Z,1526409477.098 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:57.481Z,1526409477.481 [AHRS_M2](INFO): Starting
2018-05-15T18:37:57.481Z,1526409477.481 [AHRS_M2](FAULT): Failed to initialize
2018-05-15T18:37:57.482Z,1526409477.482 [AHRS_M2] Communications Fault, FailCount= 1
2018-05-15T18:37:57.482Z,1526409477.482 [AHRS_M2](ERROR): Communications Fault
2018-05-15T18:37:57.544Z,1526409477.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:57.554Z,1526409477.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:57.666Z,1526409477.666 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2018-05-15T18:37:57.693Z,1526409477.693 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:37:57.784Z,1526409477.784 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-15T18:37:57.818Z,1526409477.818 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-15T18:37:57.855Z,1526409477.855 [AHRS_M2](INFO): Stop
2018-05-15T18:37:57.856Z,1526409477.856 [AHRS_M2](INFO): uninitialize
2018-05-15T18:37:57.881Z,1526409477.881 [AHRS_M2](INFO): Data-stream disabled.
2018-05-15T18:37:57.882Z,1526409477.882 [AHRS_M2](INFO): Powering down
2018-05-15T18:37:57.919Z,1526409477.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:57.920Z,1526409477.920 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:58.204Z,1526409478.204 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:37:58.205Z,1526409478.205 [CTD_Seabird](INFO): Powering down
2018-05-15T18:37:58.253Z,1526409478.253 [AHRS_M2](INFO): Stopping
2018-05-15T18:37:58.305Z,1526409478.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:58.306Z,1526409478.306 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:58.653Z,1526409478.653 [AHRS_M2](INFO): Stopping
2018-05-15T18:37:58.705Z,1526409478.705 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:58.706Z,1526409478.706 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:59.052Z,1526409479.052 [AHRS_M2](INFO): Stopping
2018-05-15T18:37:59.105Z,1526409479.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:59.106Z,1526409479.106 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:59.453Z,1526409479.453 [AHRS_M2](INFO): Stopped
2018-05-15T18:37:59.529Z,1526409479.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:59.530Z,1526409479.530 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:59.571Z,1526409479.571 [CBIT](INFO): Clearing failed state for component AHRS_M2
2018-05-15T18:37:59.571Z,1526409479.571 [AHRS_M2] No Fault, FailCount= 1
2018-05-15T18:37:59.853Z,1526409479.853 [AHRS_M2](INFO): Start
2018-05-15T18:37:59.853Z,1526409479.853 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-05-15T18:37:59.861Z,1526409479.861 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-05-15T18:37:59.906Z,1526409479.906 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:37:59.907Z,1526409479.907 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:00.253Z,1526409480.253 [AHRS_M2](INFO): Starting
2018-05-15T18:38:00.313Z,1526409480.313 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:00.314Z,1526409480.314 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:00.653Z,1526409480.653 [AHRS_M2](INFO): Starting
2018-05-15T18:38:00.686Z,1526409480.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:00.687Z,1526409480.687 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:01.053Z,1526409481.053 [AHRS_M2](INFO): Starting
2018-05-15T18:38:01.077Z,1526409481.077 [AHRS_M2](INFO): Data-stream active.
2018-05-15T18:38:01.079Z,1526409481.079 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-15T18:38:01.080Z,1526409481.080 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-15T18:38:01.081Z,1526409481.081 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-15T18:38:01.081Z,1526409481.081 [AcousticModem_Benthos_ATM900](DEBUG): Apr  6 2000 14:25:32 
2018-05-15T18:38:01.115Z,1526409481.115 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:01.116Z,1526409481.116 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-15T18:38:01.460Z,1526409481.460 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-05-15T18:38:02.267Z,1526409482.267 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-05-15T18:38:02.655Z,1526409482.655 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-05-15T18:38:02.656Z,1526409482.656 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-05-15T18:38:02.656Z,1526409482.656 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-05-15T18:38:03.055Z,1526409483.055 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-05-15T18:38:03.466Z,1526409483.466 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-05-15T18:38:03.467Z,1526409483.467 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-05-15T18:38:03.856Z,1526409483.856 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-05-15T18:38:24.274Z,1526409504.274 [NAL9602](INFO): No messages in MT queue
2018-05-15T18:38:35.354Z,1526409515.354 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.801998 degree
2018-05-15T18:38:35.355Z,1526409515.355 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2018-05-15T18:38:35.356Z,1526409515.356 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.936996 degree
2018-05-15T18:38:35.358Z,1526409515.358 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2018-05-15T18:38:35.550Z,1526409515.550 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-15T18:38:44.371Z,1526409524.371 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-15T18:38:44.375Z,1526409524.375 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-15T18:38:55.277Z,1526409535.277 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008114
CHAN A1 (24V): -0.011829
CHAN A2 (12V): -0.005470
CHAN A3 (5V): -0.002245
CHAN B0 (3.3V): -0.001181
CHAN B1 (3.15aV): -0.001059
CHAN B2 (3.15bV): -0.001368
CHAN B3 (GND): 0.000118
OPEN: -0.000847
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-15T18:38:58.811Z,1526409538.811 [BPC1](ERROR): Bank B: No match for stick 1 serial number:1717 in the onboard configuration file.
2018-05-15T18:38:58.811Z,1526409538.811 [BPC1](ERROR): Failed to parse Bank B battery data
2018-05-15T18:39:07.038Z,1526409547.038 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/calibrateAHRS_M2.xml
2018-05-15T18:39:07.038Z,1526409547.038 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/calibrateAHRS_M2.xml
2018-05-15T18:39:07.157Z,1526409547.157 [MissionManager](INFO): DefineArg compassCal.MissionTimeout = 60.000000 min
2018-05-15T18:39:07.160Z,1526409547.160 [MissionManager](INFO): DefineArg compassCal.NeedCommsTime = 60.000000 min
2018-05-15T18:39:07.163Z,1526409547.163 [MissionManager](INFO): DefineArg compassCal.YoYoMinDepth = 2.000000 m
2018-05-15T18:39:07.166Z,1526409547.166 [MissionManager](INFO): DefineArg compassCal.YoYoMaxDepth = 200.000000 m
2018-05-15T18:39:07.169Z,1526409547.169 [MissionManager](INFO): DefineArg compassCal.YoYoMinAltitude = 7.000000 m
2018-05-15T18:39:07.172Z,1526409547.172 [MissionManager](INFO): DefineArg compassCal.YoYoPitch = 30.000000 arcdeg
2018-05-15T18:39:07.174Z,1526409547.174 [MissionManager](INFO): DefineArg compassCal.RudderAngle = 13.000000 arcdeg
2018-05-15T18:39:07.177Z,1526409547.177 [MissionManager](INFO): DefineArg compassCal.Speed = 1.000000 m/s
2018-05-15T18:39:07.180Z,1526409547.180 [MissionManager](INFO): DefineArg compassCal.CalibrationInitTimeout = 2.000000 min
2018-05-15T18:39:07.183Z,1526409547.183 [MissionManager](INFO): DefineArg compassCal.MinAltitude = 5.000000 m
2018-05-15T18:39:07.210Z,1526409547.210 [MissionManager](INFO): DefineArg compassCal.MaxDepth = 205.000000 m
2018-05-15T18:39:07.214Z,1526409547.214 [MissionManager](INFO): DefineArg compassCal.MinOffshore = 2.000000 km
2018-05-15T18:39:07.214Z,1526409547.214 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-05-15T18:39:07.580Z,1526409547.580 [MissionManager](INFO): DefineArg compassCal:Science.SampleISUS = 0 bool
2018-05-15T18:39:07.583Z,1526409547.583 [MissionManager](INFO): DefineArg compassCal:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-05-15T18:39:07.586Z,1526409547.586 [MissionManager](INFO): DefineArg compassCal:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-05-15T18:39:07.589Z,1526409547.589 [MissionManager](INFO): DefineArg compassCal:Science.PeakDetectChlActive = 0 bool
2018-05-15T18:39:07.592Z,1526409547.592 [MissionManager](INFO): DefineArg compassCal:Science.HighestChlPeakReportActive = 0 bool
2018-05-15T18:39:07.595Z,1526409547.595 [MissionManager](INFO): DefineArg compassCal:Science.PatchTracking = 0 bool
2018-05-15T18:39:07.598Z,1526409547.598 [MissionManager](INFO): DefineArg compassCal:Science.FilterWidthHorizontal = 3.000000 count
2018-05-15T18:39:07.601Z,1526409547.601 [MissionManager](INFO): DefineArg compassCal:Science.NumProfilesSlidingwindow = 100.000000 count
2018-05-15T18:39:07.603Z,1526409547.603 [MissionManager](INFO): DefineArg compassCal:Science.PeakDetectNO3Active = 0 bool
2018-05-15T18:39:07.626Z,1526409547.626 [MissionManager](INFO): DefineArg compassCal:Science.PeakDetectOilActive = 0 bool
2018-05-15T18:39:07.637Z,1526409547.637 [MissionManager](INFO): DefineArg compassCal:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-05-15T18:39:07.645Z,1526409547.645 [MissionManager](INFO): DefineArg compassCal:Science.EnabledAanderaaO2 = 0.000000 bool
2018-05-15T18:39:07.648Z,1526409547.648 [MissionManager](INFO): DefineArg compassCal:Science.EnabledNeilBrown = 0.000000 bool
2018-05-15T18:39:07.656Z,1526409547.656 [MissionManager](INFO): DefineArg compassCal:Science.EnabledSeabird = 1.000000 bool
2018-05-15T18:39:07.664Z,1526409547.664 [MissionManager](INFO): DefineArg compassCal:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-05-15T18:39:07.668Z,1526409547.668 [MissionManager](INFO): DefineArg compassCal:Science.EnabledWetLabsBB2FL = 0.000000 bool
2018-05-15T18:39:07.692Z,1526409547.692 [MissionManager](INFO): DefineArg compassCal:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2018-05-15T18:39:07.695Z,1526409547.695 [MissionManager](INFO): DefineArg compassCal:Science.EnabledISUS = 0.000000 bool
2018-05-15T18:39:07.698Z,1526409547.698 [MissionManager](INFO): DefineArg compassCal:Science.LowPassWindowLength = 20.000000 count
2018-05-15T18:39:07.709Z,1526409547.709 [MissionManager](INFO): DefineArg compassCal:Science.PeakChlShallowBound = nan m
2018-05-15T18:39:07.718Z,1526409547.718 [MissionManager](INFO): DefineArg compassCal:Science.PeakChlDeepBound = nan m
2018-05-15T18:39:07.729Z,1526409547.729 [MissionManager](INFO): DefineOutput compassCal:Science.PeakChl = 0.000000 ug/l
2018-05-15T18:39:07.733Z,1526409547.733 [MissionManager](INFO): DefineOutput compassCal:Science.PeakChlDepth = 0.000000 m
2018-05-15T18:39:07.736Z,1526409547.736 [MissionManager](INFO): DefineOutput compassCal:Science.PeakChlTemperature = 0.000000 degC
2018-05-15T18:39:07.739Z,1526409547.739 [MissionManager](INFO): DefineOutput compassCal:Science.PeakChlLatitude = nan arcdeg
2018-05-15T18:39:07.794Z,1526409547.794 [MissionManager](INFO): DefineOutput compassCal:Science.PeakChlLongitude = nan arcdeg
2018-05-15T18:39:07.806Z,1526409547.806 [MissionManager](INFO): DefineOutput compassCal:Science.PeakNO3 = 0.000000 umol/l
2018-05-15T18:39:07.830Z,1526409547.830 [MissionManager](INFO): DefineOutput compassCal:Science.PeakNO3Depth = 0.000000 m
2018-05-15T18:39:07.849Z,1526409547.849 [MissionManager](INFO): DefineOutput compassCal:Science.PeakNO3Latitude = nan arcdeg
2018-05-15T18:39:07.864Z,1526409547.864 [MissionManager](INFO): DefineOutput compassCal:Science.PeakNO3Longitude = nan arcdeg
2018-05-15T18:39:07.875Z,1526409547.875 [MissionManager](INFO): DefineOutput compassCal:Science.PatchChl = 0.000000 m
2018-05-15T18:39:07.879Z,1526409547.879 [MissionManager](INFO): DefineOutput compassCal:Science.PatchChlDepth = 0.000000 m
2018-05-15T18:39:07.899Z,1526409547.899 [MissionManager](INFO): DefineOutput compassCal:Science.PatchChlLatitude = nan arcdeg
2018-05-15T18:39:07.903Z,1526409547.903 [MissionManager](INFO): DefineOutput compassCal:Science.PatchChlLongitude = nan arcdeg
2018-05-15T18:39:07.920Z,1526409547.920 [MissionManager](INFO): DefineOutput compassCal:Science.PatchChlDistance = nan arcdeg
2018-05-15T18:39:07.923Z,1526409547.923 [MissionManager](INFO): DefineOutput compassCal:Science.PeakOil = 0.000000 kg/m3
2018-05-15T18:39:07.935Z,1526409547.935 [MissionManager](INFO): DefineOutput compassCal:Science.PeakOilDepth = 0.000000 m
2018-05-15T18:39:07.946Z,1526409547.946 [MissionManager](INFO): DefineOutput compassCal:Science.PeakOilLatitude = nan arcdeg
2018-05-15T18:39:07.955Z,1526409547.955 [MissionManager](INFO): DefineOutput compassCal:Science.PeakOilLongitude = nan arcdeg
2018-05-15T18:39:08.006Z,1526409548.006 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-15T18:39:08.034Z,1526409548.034 [compassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-05-15T18:39:08.064Z,1526409548.064 [compassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-15T18:39:08.093Z,1526409548.093 [compassCal:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-05-15T18:39:08.110Z,1526409548.110 [MissionManager](INFO): RedefineArg compassCal:Science.SampleISUS = value:1 bool
2018-05-15T18:39:08.112Z,1526409548.112 [MissionManager](INFO): RedefineArg compassCal:Science.PeakDetectChlActive = value:1 bool
2018-05-15T18:39:08.117Z,1526409548.117 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-05-15T18:39:08.264Z,1526409548.264 [MissionManager](INFO): DefineArg compassCal:NeedComms.DiveInterval = 3.000000 h
2018-05-15T18:39:08.275Z,1526409548.275 [MissionManager](INFO): DefineArg compassCal:NeedComms.WaitForPitchUp = 10.000000 min
2018-05-15T18:39:08.278Z,1526409548.278 [MissionManager](INFO): DefineArg compassCal:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-05-15T18:39:08.298Z,1526409548.298 [MissionManager](INFO): DefineArg compassCal:NeedComms.SurfaceDepthRate = nan m/s
2018-05-15T18:39:08.309Z,1526409548.309 [MissionManager](INFO): DefineArg compassCal:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-05-15T18:39:08.312Z,1526409548.312 [MissionManager](INFO): DefineArg compassCal:NeedComms.GPSTimeout = 7.000000 min
2018-05-15T18:39:08.323Z,1526409548.323 [MissionManager](INFO): DefineArg compassCal:NeedComms.CommsTimeout = 30.000000 min
2018-05-15T18:39:08.333Z,1526409548.333 [compassCal:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-15T18:39:08.364Z,1526409548.364 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-05-15T18:39:08.413Z,1526409548.413 [MissionManager](INFO): DefineArg compassCal:StandardEnvelopes.MinAltitude = 5.000000 m
2018-05-15T18:39:08.431Z,1526409548.431 [MissionManager](INFO): DefineArg compassCal:StandardEnvelopes.MaxDepth = 200.000000 m
2018-05-15T18:39:08.435Z,1526409548.435 [MissionManager](INFO): DefineArg compassCal:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-05-15T18:39:08.439Z,1526409548.439 [compassCal:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-05-15T18:39:08.451Z,1526409548.451 [compassCal:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-05-15T18:39:08.476Z,1526409548.476 [compassCal:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-05-15T18:39:08.512Z,1526409548.512 [compassCal:I.Pitch](DEBUG): Construct.
2018-05-15T18:39:08.530Z,1526409548.530 [compassCal:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-05-15T18:39:08.534Z,1526409548.534 [compassCal:K.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-05-15T18:39:08.587Z,1526409548.587 [compassCal:L.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-05-15T18:39:08.626Z,1526409548.626 [compassCal:M.YoYo](DEBUG): Construct YoYo.
2018-05-15T18:39:08.658Z,1526409548.658 [compassCal:Calibration:WaitForCalibrationInit:A_Timeout:B.Execute](DEBUG): Construct Execute.
2018-05-15T18:39:08.678Z,1526409548.678 [compassCal:Calibration:AcquireCalibrationPoints:A.Point](DEBUG): Construct.
2018-05-15T18:39:08.684Z,1526409548.684 [compassCal:Calibration:AcquireCalibrationPoints:B.SetSpeed](DEBUG): Construct.
2018-05-15T18:39:08.699Z,1526409548.699 [compassCal:Calibration:D.CalibrateAHRS_M2](DEBUG): Construct CalibrateSparton.
2018-05-15T18:39:08.751Z,1526409548.751 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Control="Tethys/Control"
    xmlns:Derivation="Tethys/Derivation"
    xmlns:Estimation="Tethys/Estimation"
    xmlns:Guidance="Tethys/Guidance"
    xmlns:Sensor="Tethys/Sensor"
    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/Derivation http://okeanids.mbari.org/tethys/Xml/Derivation.xsd
    Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
    Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
    Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
    Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
    Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
    Id="compassCal">
    
    <Description>
        This mission yoyos in a spiral while calibrating the Sparton AHRS-M2 Micro AHRS/IMU with AdaptCal.
    </Description>
    
    <!-- Mission-wide settings -->
    
    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>60</Value></DefineArg>
    
    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>60</Value></DefineArg>
    
    <!-- You probably need to change these. -->
    
    <!-- You probably do not need to change these. -->
    
    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>
    
    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>
    
    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>7</Value></DefineArg>
    
    <DefineArg Name="YoYoPitch"><Description>
        Pitch (plus and minus) for yo-yo behavior.
    </Description><Units:degree/><Value>30</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>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>
    
    <DefineArg Name="CalibrationInitTimeout"><Description>
        Elapsed time allowed for calibration to initialize
    </Description><Units:minute/><Value>2</Value></DefineArg>
    
    <!-- You are even less likely to need to change these. -->
    
    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>
    
    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>
    
    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>
    
    <!-- Missions should almost always start with a timeout -->
    
    <Timeout Duration="MissionTimeout"/>
    
    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>
    
    <!-- Most missions should use the NeedComms aggregate. -->
    
    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>
    
    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>
    
    <!-- Missions should almost always start with standard safety envelopes; most missions should not expose the parameters of these envelopes. -->
    
    <Insert Filename="Insert/StandardEnvelopes.xml"/>
    
    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>
    
    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>
    
    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>
    
    <!-- Check for additional instructions first. -->
    
    <Call Id="StartingMission" RefId="NeedComms"/>
    
    <!-- Many missions will keep mass position and buoyancy volume fixed at defaults. -->
    
    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>
    
    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyNeutral/></Setting>
    </Guidance:Buoyancy>
    
    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:DepthEnvelope>
    
    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:AltitudeEnvelope>
    
    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.pitch/><Arg Name="YoYoPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="Calibration">
        
        <Sequence/>
        
        <ReadDatum Id="WaitForCalibrationInit">
            <Timeout Duration="CalibrationInitTimeout">
                <Syslog Severity="Important">Failed to initialize calibration within timeout. Stopping mission.</Syslog>
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>stop</String></Setting>
                </Guidance:Execute>
            </Timeout>
            <Sensor:AHRS_M2.CompassCalState/>
        </ReadDatum>
        
        <Syslog Severity="Important">AdaptCal initialized. Acquiring calibration points.</Syslog>

        <Aggregate Id="AcquireCalibrationPoints">
            
            <Sequence/>
            
            <Guidance:Point>
                <Parallel/>
                <Setting><Guidance:Point.rudderAngle/><Arg Name="RudderAngle"/></Setting>
            </Guidance:Point>
            
            <Guidance:SetSpeed>
                <Parallel/>
                <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
            </Guidance:SetSpeed>
            
        </Aggregate>
    
        <Sensor:CalibrateAHRS_M2>
            <Parallel/>
        </Sensor:CalibrateAHRS_M2>
    
    </Aggregate>
    
</Mission>


2018-05-15T18:39:08.751Z,1526409548.751 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/calibrateAHRS_M2.xml
2018-05-15T18:39:18.498Z,1526409558.498 [CommandLine](IMPORTANT): got command run
2018-05-15T18:39:18.501Z,1526409558.501 [CommandLine](IMPORTANT): Running 
2018-05-15T18:39:30.927Z,1526409570.927 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409600.000000
2018-05-15T18:39:30.927Z,1526409570.927 [CommandLine](FAULT): Incomplete syntax. Try: help set
2018-05-15T18:39:37.234Z,1526409577.234 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409600.000000 second
2018-05-15T18:39:38.368Z,1526409578.368 [SBIT](IMPORTANT): SBIT PASSED
2018-05-15T18:39:38.434Z,1526409578.434 [CommandLine](IMPORTANT): got command configSet list
2018-05-15T18:39:38.434Z,1526409578.434 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-15T18:39:38.435Z,1526409578.435 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2018-05-15T18:39:38.435Z,1526409578.435 [CommandLine](IMPORTANT): CBIT.abortDepth=300 meter;

2018-05-15T18:39:38.435Z,1526409578.435 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2018-05-15T18:39:38.435Z,1526409578.435 [CommandLine](IMPORTANT): CBIT.stopDepth=285 meter;

2018-05-15T18:39:38.435Z,1526409578.435 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2018-05-15T18:39:38.435Z,1526409578.435 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): NAL9602.simulateHardware=1 bool;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_salinity;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_temperature;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): RudderServo.offsetAngle=0.5 degree;

2018-05-15T18:39:38.436Z,1526409578.436 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=130 cubic_centimeter;

2018-05-15T18:39:38.437Z,1526409578.437 [CommandLine](IMPORTANT): VerticalControl.massDefault=8 millimeter;

2018-05-15T18:39:38.437Z,1526409578.437 [CommandLine](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2018-05-15T18:39:38.756Z,1526409578.756 [MissionManager](IMPORTANT): Started mission Startup
2018-05-15T18:39:38.757Z,1526409578.757 [Startup] Running Loop=1
2018-05-15T18:39:38.757Z,1526409578.757 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-15T18:39:38.757Z,1526409578.757 [Startup:A.GoToSurface] Running Loop=1
2018-05-15T18:39:38.757Z,1526409578.757 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-15T18:39:38.758Z,1526409578.758 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-15T18:39:38.758Z,1526409578.758 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-15T18:39:38.759Z,1526409578.759 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-15T18:39:38.759Z,1526409578.759 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-15T18:39:38.760Z,1526409578.760 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-15T18:39:38.767Z,1526409578.767 [Startup:StartupSatComms] Running Loop=1
2018-05-15T18:39:38.767Z,1526409578.767 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-15T18:39:38.768Z,1526409578.768 [Startup:StartupSatComms:A] Running Loop=1
2018-05-15T18:39:39.152Z,1526409579.152 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-15T18:39:39.158Z,1526409579.158 [Startup:StartupSatComms:A] Stopped
2018-05-15T18:39:39.158Z,1526409579.158 [Startup:StartupSatComms:B] Running Loop=1
2018-05-15T18:39:39.553Z,1526409579.553 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-15T18:39:52.674Z,1526409592.674 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409600.000000 second
2018-05-15T18:40:11.847Z,1526409611.847 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409600.000000 second
2018-05-15T18:40:11.910Z,1526409611.910 [NAL9602](INFO): Sent 43 bytes from file Logs/20180515T182516/Courier0010.lzma
2018-05-15T18:40:11.910Z,1526409611.910 [NAL9602](INFO): Packets left to send: 0
2018-05-15T18:40:11.912Z,1526409611.912 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180515T182516/Courier0010.lzma.parts/0000.sbd
2018-05-15T18:40:11.912Z,1526409611.912 [NAL9602](DEBUG): Completed sending Logs/20180515T182516/Courier0010.lzma
2018-05-15T18:40:26.613Z,1526409626.613 [CommandLine](IMPORTANT): got command set NAL9602.time_fix string 
2018-05-15T18:40:26.613Z,1526409626.613 [CommandLine](FAULT): Incomplete syntax. Try: help set
2018-05-15T18:40:39.546Z,1526409639.546 [Startup:StartupSatComms:B](INFO): Timed out from 2018-05-15T18:39:39.2Z
2018-05-15T18:40:39.546Z,1526409639.546 [Startup:StartupSatComms:B] Stopped
2018-05-15T18:40:39.546Z,1526409639.546 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-05-15T18:40:39.546Z,1526409639.546 [Startup:StartupSatComms] Stopped
2018-05-15T18:40:39.546Z,1526409639.546 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-15T18:40:39.547Z,1526409639.547 [Startup](INFO): Completed Startup
2018-05-15T18:40:39.547Z,1526409639.547 [MissionManager](INFO): Startup is completed.
2018-05-15T18:40:39.547Z,1526409639.547 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-15T18:40:39.548Z,1526409639.548 [Startup] Stopped
2018-05-15T18:40:39.548Z,1526409639.548 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-15T18:40:39.548Z,1526409639.548 [Startup:A.GoToSurface] Stopped
2018-05-15T18:40:39.548Z,1526409639.548 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-15T18:40:39.949Z,1526409639.949 [MissionManager](IMPORTANT): Started mission compassCal
2018-05-15T18:40:39.949Z,1526409639.949 [compassCal] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal](DEBUG): Aggregate::initialize compassCal
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science](DEBUG): Aggregate::initialize compassCal:Science
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:A] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:B] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:C] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:D] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:E] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:F] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:G] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:Read_Oil] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:PeakDetectChl] Running Loop=1
2018-05-15T18:40:39.950Z,1526409639.950 [compassCal:Science:PeakDetectChl](DEBUG): Aggregate::initialize compassCal:Science:PeakDetectChl
2018-05-15T18:40:39.951Z,1526409639.951 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-05-15T18:40:39.951Z,1526409639.951 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:39.953Z,1526409639.953 [compassCal:Science:HighestChlPeakReport] Running Loop=1
2018-05-15T18:40:39.953Z,1526409639.953 [compassCal:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize compassCal:Science:HighestChlPeakReport
2018-05-15T18:40:39.953Z,1526409639.953 [compassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-05-15T18:40:39.953Z,1526409639.953 [compassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-05-15T18:40:39.954Z,1526409639.954 [compassCal:Science:PeakDetectNO3] Running Loop=1
2018-05-15T18:40:39.954Z,1526409639.954 [compassCal:Science:PeakDetectNO3](DEBUG): Aggregate::initialize compassCal:Science:PeakDetectNO3
2018-05-15T18:40:39.954Z,1526409639.954 [compassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-05-15T18:40:39.954Z,1526409639.954 [compassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:39.956Z,1526409639.956 [compassCal:Science:PeakDetectOil] Running Loop=1
2018-05-15T18:40:39.956Z,1526409639.956 [compassCal:Science:PeakDetectOil](DEBUG): Aggregate::initialize compassCal:Science:PeakDetectOil
2018-05-15T18:40:39.956Z,1526409639.956 [compassCal:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-05-15T18:40:39.956Z,1526409639.956 [compassCal:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:39.959Z,1526409639.959 [compassCal:StandardEnvelopes] Running Loop=1
2018-05-15T18:40:39.959Z,1526409639.959 [compassCal:StandardEnvelopes](DEBUG): Aggregate::initialize compassCal:StandardEnvelopes
2018-05-15T18:40:39.959Z,1526409639.959 [compassCal:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-05-15T18:40:39.959Z,1526409639.959 [compassCal:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-05-15T18:40:39.959Z,1526409639.959 [compassCal:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:I.Pitch] Running Loop=1
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:I.Pitch](DEBUG): Initialize.
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:J.Buoyancy] Running Loop=1
2018-05-15T18:40:39.960Z,1526409639.960 [compassCal:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-05-15T18:40:39.969Z,1526409639.969 [compassCal:K.DepthEnvelope] Running Loop=1
2018-05-15T18:40:39.969Z,1526409639.969 [compassCal:K.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-05-15T18:40:39.969Z,1526409639.969 [compassCal:L.AltitudeEnvelope] Running Loop=1
2018-05-15T18:40:39.969Z,1526409639.969 [compassCal:L.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-05-15T18:40:39.970Z,1526409639.970 [compassCal:M.YoYo] Running Loop=1
2018-05-15T18:40:39.970Z,1526409639.970 [compassCal:M.YoYo](DEBUG): Initialize YoYoComponent.
2018-05-15T18:40:39.970Z,1526409639.970 [compassCal:C] Running Loop=1
2018-05-15T18:40:39.971Z,1526409639.971 [compassCal:M.YoYo] Running Loop=1
2018-05-15T18:40:39.976Z,1526409639.976 [compassCal:L.AltitudeEnvelope] Running Loop=1
2018-05-15T18:40:39.989Z,1526409639.989 [compassCal:K.DepthEnvelope] Running Loop=1
2018-05-15T18:40:39.999Z,1526409639.999 [compassCal:J.Buoyancy] Running Loop=1
2018-05-15T18:40:40.007Z,1526409640.007 [compassCal:I.Pitch] Running Loop=1
2018-05-15T18:40:40.012Z,1526409640.012 [compassCal:StandardEnvelopes] Running Loop=1
2018-05-15T18:40:40.012Z,1526409640.012 [compassCal:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-05-15T18:40:40.037Z,1526409640.037 [compassCal:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-05-15T18:40:40.046Z,1526409640.046 [compassCal:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-05-15T18:40:40.057Z,1526409640.057 [compassCal:C] Stopped
2018-05-15T18:40:40.057Z,1526409640.057 [compassCal:E] Running Loop=1
2018-05-15T18:40:40.057Z,1526409640.057 [compassCal:Science] Running Loop=1
2018-05-15T18:40:40.069Z,1526409640.069 [compassCal:Science:PeakDetectOil] Stopped
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize compassCal:Science:PeakDetectOil
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectNO3] Stopped
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize compassCal:Science:PeakDetectNO3
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:HighestChlPeakReport] Stopped
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize compassCal:Science:HighestChlPeakReport
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-05-15T18:40:40.070Z,1526409640.070 [compassCal:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-05-15T18:40:40.071Z,1526409640.071 [compassCal:Science:PeakDetectChl] Running Loop=1
2018-05-15T18:40:40.071Z,1526409640.071 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:40.080Z,1526409640.080 [compassCal:Science:Read_Oil] Stopped
2018-05-15T18:40:40.080Z,1526409640.080 [compassCal:Science:G] Running Loop=1
2018-05-15T18:40:40.081Z,1526409640.081 [compassCal:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-05-15T18:40:40.082Z,1526409640.082 [compassCal:Science:F] Stopped
2018-05-15T18:40:40.082Z,1526409640.082 [compassCal:Science:E] Stopped
2018-05-15T18:40:40.083Z,1526409640.083 [compassCal:Science:D] Running Loop=1
2018-05-15T18:40:40.083Z,1526409640.083 [compassCal:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2018-05-15T18:40:40.084Z,1526409640.084 [compassCal:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2018-05-15T18:40:40.094Z,1526409640.094 [compassCal:Science:C] Running Loop=1
2018-05-15T18:40:40.094Z,1526409640.094 [compassCal:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-05-15T18:40:40.095Z,1526409640.095 [compassCal:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-05-15T18:40:40.101Z,1526409640.101 [compassCal:Science:B] Stopped
2018-05-15T18:40:40.101Z,1526409640.101 [compassCal:Science:A] Stopped
2018-05-15T18:40:40.370Z,1526409640.370 [compassCal:E] Stopped
2018-05-15T18:40:40.371Z,1526409640.371 [compassCal:F] Running Loop=1
2018-05-15T18:40:40.373Z,1526409640.373 [CTD_Seabird](INFO): Initializing
2018-05-15T18:40:40.374Z,1526409640.374 [CTD_Seabird](INFO): Checking LCM
2018-05-15T18:40:40.374Z,1526409640.374 [CTD_Seabird](INFO): LCM OK
2018-05-15T18:40:40.374Z,1526409640.374 [CTD_Seabird](INFO): Powering up
2018-05-15T18:40:40.377Z,1526409640.377 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:40.776Z,1526409640.776 [compassCal:F] Stopped
2018-05-15T18:40:40.777Z,1526409640.777 [compassCal:G] Running Loop=1
2018-05-15T18:40:40.779Z,1526409640.779 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:41.151Z,1526409641.151 [compassCal:G] Stopped
2018-05-15T18:40:41.151Z,1526409641.151 [compassCal:StartingMission] Running Loop=1
2018-05-15T18:40:41.151Z,1526409641.151 [compassCal:StartingMission](DEBUG): Aggregate::initialize compassCal:StartingMission
2018-05-15T18:40:41.153Z,1526409641.153 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:41.556Z,1526409641.556 [compassCal:L.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2018-05-15T18:40:41.559Z,1526409641.559 [compassCal:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2018-05-15T18:40:41.559Z,1526409641.559 [compassCal:NeedComms] Running Loop=1
2018-05-15T18:40:41.559Z,1526409641.559 [compassCal:NeedComms](DEBUG): Aggregate::initialize compassCal:NeedComms
2018-05-15T18:40:41.559Z,1526409641.559 [compassCal:NeedComms:B.GoToSurface] Running Loop=1
2018-05-15T18:40:41.560Z,1526409641.560 [compassCal:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-15T18:40:41.560Z,1526409641.560 [compassCal:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-05-15T18:40:41.560Z,1526409641.560 [compassCal:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-05-15T18:40:41.561Z,1526409641.561 [compassCal:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-05-15T18:40:41.561Z,1526409641.561 [compassCal:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-15T18:40:41.562Z,1526409641.562 [compassCal:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-15T18:40:41.562Z,1526409641.562 [compassCal:NeedComms:A] Running Loop=1
2018-05-15T18:40:41.576Z,1526409641.576 [compassCal:NeedComms:A](INFO): last time_fix was: 1526409600.000000 second since 1970/01/01T00:00:00Z 
2018-05-15T18:40:41.582Z,1526409641.582 [compassCal:NeedComms:A] Stopped
2018-05-15T18:40:41.583Z,1526409641.583 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:41.959Z,1526409641.959 [compassCal:NeedComms:C] Running Loop=1
2018-05-15T18:40:41.960Z,1526409641.960 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:42.369Z,1526409642.369 [compassCal:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-15T18:40:42.372Z,1526409642.372 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:42.765Z,1526409642.765 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:43.110Z,1526409643.110 [NAL9602](INFO): Sent 287 bytes from file Logs/20180515T183742/Courier0000.lzma
2018-05-15T18:40:43.110Z,1526409643.110 [NAL9602](INFO): Packets left to send: 0
2018-05-15T18:40:43.111Z,1526409643.111 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180515T183742/Courier0000.lzma.parts/0000.sbd
2018-05-15T18:40:43.112Z,1526409643.112 [NAL9602](DEBUG): Completed sending Logs/20180515T183742/Courier0000.lzma
2018-05-15T18:40:43.210Z,1526409643.210 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:43.574Z,1526409643.574 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:43.962Z,1526409643.962 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:44.357Z,1526409644.357 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:44.755Z,1526409644.755 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:45.155Z,1526409645.155 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:45.557Z,1526409645.557 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:45.704Z,1526409645.704 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:40:45.773Z,1526409645.773 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-15T18:40:45.774Z,1526409645.774 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-15T18:40:45.996Z,1526409645.996 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:46.366Z,1526409646.366 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:46.760Z,1526409646.760 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:47.158Z,1526409647.158 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:47.559Z,1526409647.559 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:47.958Z,1526409647.958 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:48.362Z,1526409648.362 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:48.761Z,1526409648.761 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:49.162Z,1526409649.162 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:49.566Z,1526409649.566 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:49.958Z,1526409649.958 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:50.357Z,1526409650.357 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:50.759Z,1526409650.759 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:51.165Z,1526409651.165 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:51.561Z,1526409651.561 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:51.814Z,1526409651.814 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409600.000000 second
2018-05-15T18:40:51.963Z,1526409651.963 [compassCal:NeedComms:C] Stopped
2018-05-15T18:40:51.963Z,1526409651.963 [compassCal:NeedComms:D] Running Loop=1
2018-05-15T18:40:51.965Z,1526409651.965 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:52.360Z,1526409652.360 [compassCal:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-15T18:40:52.364Z,1526409652.364 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:52.772Z,1526409652.772 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:53.172Z,1526409653.172 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:53.583Z,1526409653.583 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:56.986Z,1526409656.986 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:57.478Z,1526409657.478 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:57.599Z,1526409657.599 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:57.922Z,1526409657.922 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003936
2018-05-15T18:40:57.989Z,1526409657.989 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:58.659Z,1526409658.659 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:40:58.806Z,1526409658.806 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:01.882Z,1526409661.882 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:01.954Z,1526409661.954 [DataOverHttps](INFO): Sending 43 bytes from file Logs/20180515T183742/Courier0004.lzma
2018-05-15T18:41:02.029Z,1526409662.029 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:02.407Z,1526409662.407 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:02.734Z,1526409662.734 [DataOverHttps](INFO): Moved sent file to Logs/20180515T183742/Courier0004.lzma.bak
2018-05-15T18:41:02.735Z,1526409662.735 [DataOverHttps](INFO): SBD MOMSN=8282604
2018-05-15T18:41:02.799Z,1526409662.799 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:03.781Z,1526409663.781 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:03.935Z,1526409663.935 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:07.001Z,1526409667.001 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:07.145Z,1526409667.145 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:07.496Z,1526409667.496 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:07.910Z,1526409667.910 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:08.299Z,1526409668.299 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:08.695Z,1526409668.695 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:09.113Z,1526409669.113 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:09.520Z,1526409669.520 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:12.641Z,1526409672.641 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:12.714Z,1526409672.714 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20180515T182516/Express0011.lzma
2018-05-15T18:41:12.800Z,1526409672.800 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:13.154Z,1526409673.154 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:13.502Z,1526409673.502 [DataOverHttps](INFO): Moved sent file to Logs/20180515T182516/Express0011.lzma.bak
2018-05-15T18:41:13.503Z,1526409673.503 [DataOverHttps](INFO): SBD MOMSN=8282607
2018-05-15T18:41:13.598Z,1526409673.598 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:14.546Z,1526409674.546 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:14.689Z,1526409674.689 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:16.021Z,1526409676.021 [CTD_Seabird](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-15T18:41:16.021Z,1526409676.021 [CTD_Seabird] Communications Fault, FailCount= 1
2018-05-15T18:41:16.022Z,1526409676.022 [CTD_Seabird](ERROR): Communications Fault
2018-05-15T18:41:16.647Z,1526409676.647 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:41:16.647Z,1526409676.647 [CTD_Seabird](INFO): Powering down
2018-05-15T18:41:18.971Z,1526409678.971 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:19.004Z,1526409679.004 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-05-15T18:41:19.005Z,1526409679.005 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-05-15T18:41:19.005Z,1526409679.005 [CTD_Seabird] No Fault, FailCount= 1
2018-05-15T18:41:19.093Z,1526409679.093 [CTD_Seabird](INFO): Initializing
2018-05-15T18:41:19.093Z,1526409679.093 [CTD_Seabird](INFO): Checking LCM
2018-05-15T18:41:19.093Z,1526409679.093 [CTD_Seabird](INFO): LCM OK
2018-05-15T18:41:19.093Z,1526409679.093 [CTD_Seabird](INFO): Powering up
2018-05-15T18:41:19.215Z,1526409679.215 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:19.511Z,1526409679.511 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:19.909Z,1526409679.909 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:20.286Z,1526409680.286 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:20.686Z,1526409680.686 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:23.793Z,1526409683.793 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:23.878Z,1526409683.878 [DataOverHttps](INFO): Sending 1219 bytes from file Logs/20180515T183742/Express0001.lzma
2018-05-15T18:41:23.946Z,1526409683.946 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:24.311Z,1526409684.311 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:24.465Z,1526409684.465 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:41:24.534Z,1526409684.534 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-15T18:41:24.535Z,1526409684.535 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-15T18:41:24.649Z,1526409684.649 [DataOverHttps](INFO): Moved sent file to Logs/20180515T183742/Express0001.lzma.bak
2018-05-15T18:41:24.649Z,1526409684.649 [DataOverHttps](INFO): SBD MOMSN=8282616
2018-05-15T18:41:24.794Z,1526409684.794 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:25.538Z,1526409685.538 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409728.000000 second
2018-05-15T18:41:25.728Z,1526409685.728 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:25.891Z,1526409685.891 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:28.983Z,1526409688.983 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:29.068Z,1526409689.068 [NAL9602](INFO): Sent 43 bytes from file Logs/20180515T183742/Courier0004.lzma
2018-05-15T18:41:29.068Z,1526409689.068 [NAL9602](INFO): Packets left to send: 0
2018-05-15T18:41:29.069Z,1526409689.069 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180515T183742/Courier0004.lzma.parts/0000.sbd
2018-05-15T18:41:29.069Z,1526409689.069 [NAL9602](DEBUG): Completed sending Logs/20180515T183742/Courier0004.lzma
2018-05-15T18:41:29.132Z,1526409689.132 [compassCal:NeedComms:D] Stopped
2018-05-15T18:41:29.132Z,1526409689.132 [compassCal:NeedComms:E] Running Loop=1
2018-05-15T18:41:29.134Z,1526409689.134 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:29.474Z,1526409689.474 [compassCal:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-15T18:41:29.482Z,1526409689.482 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:29.909Z,1526409689.909 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:30.300Z,1526409690.300 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:30.662Z,1526409690.662 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:33.812Z,1526409693.812 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:33.874Z,1526409693.874 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20180515T183742/Express0005.lzma
2018-05-15T18:41:33.939Z,1526409693.939 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:34.303Z,1526409694.303 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:34.638Z,1526409694.638 [DataOverHttps](INFO): Moved sent file to Logs/20180515T183742/Express0005.lzma.bak
2018-05-15T18:41:34.639Z,1526409694.639 [DataOverHttps](INFO): SBD MOMSN=8282654
2018-05-15T18:41:34.720Z,1526409694.720 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:35.102Z,1526409695.102 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:35.506Z,1526409695.506 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:35.901Z,1526409695.901 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:36.317Z,1526409696.317 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:36.703Z,1526409696.703 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:37.100Z,1526409697.100 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:37.510Z,1526409697.510 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:37.913Z,1526409697.913 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:38.302Z,1526409698.302 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:38.697Z,1526409698.697 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:39.098Z,1526409699.098 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:39.503Z,1526409699.503 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:39.901Z,1526409699.901 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:40.333Z,1526409700.333 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:40.727Z,1526409700.727 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:41.161Z,1526409701.161 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:41.511Z,1526409701.511 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:41.911Z,1526409701.911 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:42.300Z,1526409702.300 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:42.707Z,1526409702.707 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:43.102Z,1526409703.102 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:43.501Z,1526409703.501 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:43.904Z,1526409703.904 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:44.306Z,1526409704.306 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:44.698Z,1526409704.698 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:45.101Z,1526409705.101 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:45.501Z,1526409705.501 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:45.905Z,1526409705.905 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:46.309Z,1526409706.309 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:46.718Z,1526409706.718 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:47.112Z,1526409707.112 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:47.611Z,1526409707.611 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:47.903Z,1526409707.903 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:48.304Z,1526409708.304 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:48.707Z,1526409708.707 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:49.135Z,1526409709.135 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:49.508Z,1526409709.508 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:49.917Z,1526409709.917 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:50.317Z,1526409710.317 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:50.700Z,1526409710.700 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:51.098Z,1526409711.098 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:51.497Z,1526409711.497 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:51.902Z,1526409711.902 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:52.306Z,1526409712.306 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:52.713Z,1526409712.713 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:53.102Z,1526409713.102 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:53.497Z,1526409713.497 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:53.902Z,1526409713.902 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:54.308Z,1526409714.308 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:54.704Z,1526409714.704 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:54.793Z,1526409714.793 [CTD_Seabird](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-15T18:41:54.793Z,1526409714.793 [CTD_Seabird] Communications Fault, FailCount= 2
2018-05-15T18:41:54.793Z,1526409714.793 [CTD_Seabird](ERROR): Communications Fault
2018-05-15T18:41:55.042Z,1526409715.042 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1526409728.000000 second
2018-05-15T18:41:55.104Z,1526409715.104 [compassCal:NeedComms:E] Stopped
2018-05-15T18:41:55.105Z,1526409715.105 [compassCal:NeedComms](INFO): Completed compassCal:NeedComms
2018-05-15T18:41:55.105Z,1526409715.105 [compassCal:NeedComms] Stopped
2018-05-15T18:41:55.105Z,1526409715.105 [compassCal:NeedComms](DEBUG): Aggregate::uninitialize compassCal:NeedComms
2018-05-15T18:41:55.105Z,1526409715.105 [compassCal:NeedComms:B.GoToSurface] Stopped
2018-05-15T18:41:55.105Z,1526409715.105 [compassCal:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-15T18:41:55.106Z,1526409715.106 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:55.126Z,1526409715.126 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-05-15T18:41:55.284Z,1526409715.284 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:41:55.284Z,1526409715.284 [CTD_Seabird](INFO): Powering down
2018-05-15T18:41:55.537Z,1526409715.537 [compassCal:StartingMission](INFO): Completed compassCal:StartingMission
2018-05-15T18:41:55.537Z,1526409715.537 [compassCal:StartingMission] Stopped
2018-05-15T18:41:55.537Z,1526409715.537 [compassCal:StartingMission](DEBUG): Aggregate::uninitialize compassCal:StartingMission
2018-05-15T18:41:55.537Z,1526409715.537 [compassCal:Calibration] Running Loop=1
2018-05-15T18:41:55.538Z,1526409715.538 [compassCal:Calibration](DEBUG): Aggregate::initialize compassCal:Calibration
2018-05-15T18:41:55.538Z,1526409715.538 [compassCal:Calibration:D.CalibrateAHRS_M2] Running Loop=1
2018-05-15T18:41:55.538Z,1526409715.538 [compassCal:Calibration:D.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent.
2018-05-15T18:41:55.538Z,1526409715.538 [compassCal:Calibration:WaitForCalibrationInit] Running Loop=1
2018-05-15T18:41:55.541Z,1526409715.541 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:55.889Z,1526409715.889 [AHRS_M2](INFO): Data-stream disabled.
2018-05-15T18:41:55.913Z,1526409715.913 [AHRS_M2](INFO): Set clearPointCal.
2018-05-15T18:41:55.967Z,1526409715.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.439739 seconds old.
2018-05-15T18:41:55.974Z,1526409715.974 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.444079 seconds old.
2018-05-15T18:41:55.982Z,1526409715.982 [compassCal:Calibration:D.CalibrateAHRS_M2] Running Loop=1
2018-05-15T18:41:55.982Z,1526409715.982 [compassCal:Calibration:D.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent.
2018-05-15T18:41:55.991Z,1526409715.991 [compassCal:Calibration:WaitForCalibrationInit](DEBUG): Initialize ReadDataComponent to sense AHRS_M2.CompassCalState
2018-05-15T18:41:56.007Z,1526409716.007 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:56.253Z,1526409716.253 [AHRS_M2](INFO): Set initCalPointBuffer.
2018-05-15T18:41:56.300Z,1526409716.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.772246 seconds old.
2018-05-15T18:41:56.302Z,1526409716.302 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.772253 seconds old.
2018-05-15T18:41:56.306Z,1526409716.306 [compassCal:Calibration:D.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent.
2018-05-15T18:41:56.311Z,1526409716.311 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:56.653Z,1526409716.653 [AHRS_M2](INFO): Set autoFieldCalActive.
2018-05-15T18:41:56.685Z,1526409716.685 [AHRS_M2](INFO): Data-stream active.
2018-05-15T18:41:56.717Z,1526409716.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 1.190061 seconds old.
2018-05-15T18:41:56.720Z,1526409716.720 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 1.190052 seconds old.
2018-05-15T18:41:56.724Z,1526409716.724 [compassCal:Calibration:D.CalibrateAHRS_M2](INFO): Initialize CalibrateSpartonComponent.
2018-05-15T18:41:56.724Z,1526409716.724 [compassCal:Calibration:WaitForCalibrationInit] Stopped
2018-05-15T18:41:56.724Z,1526409716.724 [compassCal:Calibration:B] Running Loop=1
2018-05-15T18:41:56.757Z,1526409716.757 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:56.834Z,1526409716.834 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-05-15T18:41:56.834Z,1526409716.834 [CTD_Seabird] No Fault, FailCount= 2
2018-05-15T18:41:56.901Z,1526409716.901 [CTD_Seabird](INFO): Initializing
2018-05-15T18:41:56.901Z,1526409716.901 [CTD_Seabird](INFO): Checking LCM
2018-05-15T18:41:56.901Z,1526409716.901 [CTD_Seabird](INFO): LCM OK
2018-05-15T18:41:56.901Z,1526409716.901 [CTD_Seabird](INFO): Powering up
2018-05-15T18:41:57.099Z,1526409717.099 [compassCal:Calibration:B](IMPORTANT): AdaptCal initialized. Acquiring calibration points.
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:B] Stopped
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:AcquireCalibrationPoints] Running Loop=1
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:AcquireCalibrationPoints](DEBUG): Aggregate::initialize compassCal:Calibration:AcquireCalibrationPoints
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:AcquireCalibrationPoints:A.Point] Running Loop=1
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:AcquireCalibrationPoints:A.Point](DEBUG): Initialize.
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:AcquireCalibrationPoints:B.SetSpeed] Running Loop=1
2018-05-15T18:41:57.105Z,1526409717.105 [compassCal:Calibration:AcquireCalibrationPoints:B.SetSpeed](DEBUG): Initialize.
2018-05-15T18:41:57.114Z,1526409717.114 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:57.510Z,1526409717.510 [compassCal:Calibration:AcquireCalibrationPoints:B.SetSpeed] Running Loop=1
2018-05-15T18:41:57.519Z,1526409717.519 [compassCal:Calibration:AcquireCalibrationPoints:A.Point] Running Loop=1
2018-05-15T18:41:57.534Z,1526409717.534 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:57.898Z,1526409717.898 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:58.309Z,1526409718.309 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:58.715Z,1526409718.715 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:59.115Z,1526409719.115 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:59.510Z,1526409719.510 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:41:59.913Z,1526409719.913 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:00.318Z,1526409720.318 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:00.696Z,1526409720.696 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:01.098Z,1526409721.098 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:01.500Z,1526409721.500 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:01.901Z,1526409721.901 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:02.338Z,1526409722.338 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:42:02.376Z,1526409722.376 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:02.475Z,1526409722.475 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-15T18:42:02.477Z,1526409722.477 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-15T18:42:02.732Z,1526409722.732 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:03.108Z,1526409723.108 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:03.524Z,1526409723.524 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:03.896Z,1526409723.896 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:04.297Z,1526409724.297 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:04.701Z,1526409724.701 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:05.112Z,1526409725.112 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:05.503Z,1526409725.503 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:05.908Z,1526409725.908 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:06.319Z,1526409726.319 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:06.712Z,1526409726.712 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:07.096Z,1526409727.096 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:07.501Z,1526409727.501 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:07.903Z,1526409727.903 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:08.300Z,1526409728.300 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:08.727Z,1526409728.727 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:09.112Z,1526409729.112 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:09.518Z,1526409729.518 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:09.923Z,1526409729.923 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:10.311Z,1526409730.311 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:10.696Z,1526409730.696 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:11.133Z,1526409731.133 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:11.498Z,1526409731.498 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:11.907Z,1526409731.907 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:12.300Z,1526409732.300 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:12.331Z,1526409732.331 [CommandLine](IMPORTANT): got command stop
2018-05-15T18:42:12.331Z,1526409732.331 [CommandLine](IMPORTANT): Scheduling is paused
2018-05-15T18:42:12.332Z,1526409732.332 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-05-15T18:42:12.702Z,1526409732.702 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-05-15T18:42:12.703Z,1526409732.703 [MissionManager](INFO): MissionManager is completed.
2018-05-15T18:42:12.703Z,1526409732.703 [MissionManager](INFO): Uninitializing Mission compassCal
2018-05-15T18:42:12.703Z,1526409732.703 [compassCal] Stopped
2018-05-15T18:42:12.703Z,1526409732.703 [compassCal](DEBUG): Aggregate::uninitialize compassCal
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science] Stopped
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science](DEBUG): Aggregate::uninitialize compassCal:Science
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:C] Stopped
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:D] Stopped
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:G] Stopped
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:PeakDetectChl] Stopped
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize compassCal:Science:PeakDetectChl
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-05-15T18:42:12.704Z,1526409732.704 [compassCal:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes] Stopped
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes](DEBUG): Aggregate::uninitialize compassCal:StandardEnvelopes
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-05-15T18:42:12.705Z,1526409732.705 [compassCal:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:I.Pitch] Stopped
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:J.Buoyancy] Stopped
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:K.DepthEnvelope] Stopped
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:K.DepthEnvelope](DEBUG): Uninitialize.
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:L.AltitudeEnvelope] Stopped
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:L.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:M.YoYo] Stopped
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:M.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:Calibration] Stopped
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:Calibration](DEBUG): Aggregate::uninitialize compassCal:Calibration
2018-05-15T18:42:12.706Z,1526409732.706 [compassCal:Calibration:AcquireCalibrationPoints] Stopped
2018-05-15T18:42:12.707Z,1526409732.707 [compassCal:Calibration:AcquireCalibrationPoints](DEBUG): Aggregate::uninitialize compassCal:Calibration:AcquireCalibrationPoints
2018-05-15T18:42:12.707Z,1526409732.707 [compassCal:Calibration:AcquireCalibrationPoints:A.Point] Stopped
2018-05-15T18:42:12.707Z,1526409732.707 [compassCal:Calibration:AcquireCalibrationPoints:B.SetSpeed] Stopped
2018-05-15T18:42:12.707Z,1526409732.707 [compassCal:Calibration:AcquireCalibrationPoints:B.SetSpeed](DEBUG): Uninitialize.
2018-05-15T18:42:12.707Z,1526409732.707 [compassCal:Calibration:D.CalibrateAHRS_M2] Stopped
2018-05-15T18:42:12.707Z,1526409732.707 [compassCal:Calibration:D.CalibrateAHRS_M2](INFO): Uninitialize CalibrateSpartonComponent.
2018-05-15T18:42:13.053Z,1526409733.053 [AHRS_M2](INFO): Data-stream disabled.
2018-05-15T18:42:13.078Z,1526409733.078 [AHRS_M2](INFO): Un-set autoFieldCalActive.
2018-05-15T18:42:13.134Z,1526409733.134 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.443225 seconds old.
2018-05-15T18:42:13.136Z,1526409733.136 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.443514 seconds old.
2018-05-15T18:42:13.140Z,1526409733.140 [MissionManager](IMPORTANT): Started mission Default
2018-05-15T18:42:13.140Z,1526409733.140 [Default] Running Loop=1
2018-05-15T18:42:13.140Z,1526409733.140 [Default](DEBUG): Aggregate::initialize Default
2018-05-15T18:42:13.140Z,1526409733.140 [Default:B.GoToSurface] Running Loop=1
2018-05-15T18:42:13.140Z,1526409733.140 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-15T18:42:13.153Z,1526409733.153 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-15T18:42:13.153Z,1526409733.153 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-15T18:42:13.154Z,1526409733.154 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-15T18:42:13.154Z,1526409733.154 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-15T18:42:13.155Z,1526409733.155 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-15T18:42:13.155Z,1526409733.155 [Default:A.Wait] Running Loop=1
2018-05-15T18:42:13.155Z,1526409733.155 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-15T18:42:13.380Z,1526409733.380 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:42:13.380Z,1526409733.380 [CTD_Seabird](INFO): Powering down
2018-05-15T18:42:13.462Z,1526409733.462 [AHRS_M2](IMPORTANT): Magnetic calibration quality (0[best] to 10000) is 2.588532.
2018-05-15T18:42:13.493Z,1526409733.493 [AHRS_M2](INFO): Data-stream active.
2018-05-15T18:42:13.544Z,1526409733.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Most recent orientation data is 0.852993 seconds old.
2018-05-15T18:42:13.546Z,1526409733.546 [DeadReckonUsingSpeedCalculator](DEBUG): Most recent orientation data is 0.853826 seconds old.
2018-05-15T18:42:25.445Z,1526409745.445 [NAL9602](INFO): Powering down
2018-05-15T18:42:26.298Z,1526409746.298 [Default:A.Wait](INFO): Done Waiting.
2018-05-15T18:42:26.298Z,1526409746.298 [Default:A.Wait] Stopped
2018-05-15T18:42:26.298Z,1526409746.298 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-15T18:42:26.691Z,1526409746.691 [Default:CheckIn] Running Loop=1
2018-05-15T18:42:26.691Z,1526409746.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-15T18:42:26.692Z,1526409746.692 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-15T18:42:27.098Z,1526409747.098 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-15T18:42:27.445Z,1526409747.445 [NAL9602](INFO): Powering up
2018-05-15T18:42:35.438Z,1526409755.438 [NAL9602](INFO): No messages in MT queue
2018-05-15T18:43:05.622Z,1526409785.622 [CommandLine](IMPORTANT): got command quit
2018-05-15T18:43:06.632Z,1526409786.632 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:43:06.632Z,1526409786.632 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:06.765Z,1526409786.765 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-05-15T18:43:06.765Z,1526409786.765 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:06.766Z,1526409786.766 [CommandLine](INFO): Join timeout helper Thread ID is 2315
2018-05-15T18:43:06.766Z,1526409786.766 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-05-15T18:43:06.766Z,1526409786.766 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:06.767Z,1526409786.767 [NavChartDb](INFO): Join timeout helper Thread ID is 2316
2018-05-15T18:43:07.065Z,1526409787.065 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:43:07.065Z,1526409787.065 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.069Z,1526409787.069 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-05-15T18:43:07.069Z,1526409787.069 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.069Z,1526409787.069 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2317
2018-05-15T18:43:07.165Z,1526409787.165 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:43:07.249Z,1526409787.249 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-15T18:43:07.249Z,1526409787.249 [CTD_Seabird](INFO): Powering down
2018-05-15T18:43:07.250Z,1526409787.250 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.251Z,1526409787.251 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-05-15T18:43:07.251Z,1526409787.251 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.252Z,1526409787.252 [Radio_Surface](INFO): Join timeout helper Thread ID is 2319
2018-05-15T18:43:07.565Z,1526409787.565 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:43:07.565Z,1526409787.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.569Z,1526409787.569 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-05-15T18:43:07.569Z,1526409787.569 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.569Z,1526409787.569 [logger](INFO): Join timeout helper Thread ID is 2320
2018-05-15T18:43:07.593Z,1526409787.593 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:43:07.593Z,1526409787.593 [logger ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.610Z,1526409787.610 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-05-15T18:43:07.610Z,1526409787.610 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.610Z,1526409787.610 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-05-15T18:43:07.610Z,1526409787.610 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:07.610Z,1526409787.610 [controlThread](INFO): Join timeout helper Thread ID is 2321
2018-05-15T18:43:07.813Z,1526409787.813 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-05-15T18:43:07.813Z,1526409787.813 [controlThread](DEBUG): Uninitializing ControlThread
2018-05-15T18:43:07.813Z,1526409787.813 [AHRS_M2](INFO): uninitialize
2018-05-15T18:43:07.841Z,1526409787.841 [AHRS_M2](INFO): Data-stream disabled.
2018-05-15T18:43:07.842Z,1526409787.842 [AHRS_M2](INFO): Powering down
2018-05-15T18:43:07.913Z,1526409787.913 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-05-15T18:43:08.033Z,1526409788.033 [NAL9602](INFO): Powering down
2018-05-15T18:43:08.037Z,1526409788.037 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-15T18:43:08.038Z,1526409788.038 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-05-15T18:43:08.039Z,1526409788.039 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-05-15T18:43:08.039Z,1526409788.039 [MissionManager](INFO): Uninitializing Mission Default
2018-05-15T18:43:08.039Z,1526409788.039 [Default] Stopped
2018-05-15T18:43:08.039Z,1526409788.039 [Default](DEBUG): Aggregate::uninitialize Default
2018-05-15T18:43:08.039Z,1526409788.039 [Default:B.GoToSurface] Stopped
2018-05-15T18:43:08.040Z,1526409788.040 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-15T18:43:08.040Z,1526409788.040 [Default:CheckIn] Stopped
2018-05-15T18:43:08.040Z,1526409788.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-15T18:43:08.040Z,1526409788.040 [Default:CheckIn:Read_GPS] Stopped
2018-05-15T18:43:08.043Z,1526409788.043 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-05-15T18:43:08.043Z,1526409788.043 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-05-15T18:43:08.043Z,1526409788.043 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-05-15T18:43:08.044Z,1526409788.044 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-05-15T18:43:08.044Z,1526409788.044 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-15T18:43:08.044Z,1526409788.044 [BuoyancyServo](INFO): Powering down
2018-05-15T18:43:08.057Z,1526409788.057 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-05-15T18:43:08.057Z,1526409788.057 [ElevatorServo](INFO): Powering down
2018-05-15T18:43:08.058Z,1526409788.058 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-05-15T18:43:08.058Z,1526409788.058 [MassServo](INFO): Powering down
2018-05-15T18:43:08.059Z,1526409788.059 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-05-15T18:43:08.059Z,1526409788.059 [RudderServo](INFO): Powering down
2018-05-15T18:43:08.060Z,1526409788.060 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-05-15T18:43:08.060Z,1526409788.060 [ThrusterServo](INFO): Powering down
2018-05-15T18:43:08.061Z,1526409788.061 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-05-15T18:43:08.061Z,1526409788.061 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-05-15T18:43:08.062Z,1526409788.062 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-05-15T18:43:08.063Z,1526409788.063 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:08.154Z,1526409788.154 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:08.195Z,1526409788.195 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:08.242Z,1526409788.242 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-05-15T18:43:08.302Z,1526409788.302 [logger ThreadHandler](INFO): Thread cancelled.