2021-08-27T04:30:52.945Z,1630038652.945 [Supervisor](DEBUG): Initializing supervisor.
2021-08-27T04:30:52.955Z,1630038652.955 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-08-27T04:30:52.956Z,1630038652.956 [SyncHandler](INFO): Protected caller Thread ID is 4905
2021-08-27T04:30:52.956Z,1630038652.956 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-08-27T04:30:52.957Z,1630038652.957 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-08-27T04:30:52.958Z,1630038652.958 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4906
2021-08-27T04:30:52.962Z,1630038652.962 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-08-27T04:30:52.981Z,1630038652.981 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-08-27T04:30:52.987Z,1630038652.987 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-08-27T04:30:52.987Z,1630038652.987 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4907
2021-08-27T04:30:52.992Z,1630038652.992 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-08-27T04:30:52.993Z,1630038652.993 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-08-27T04:30:52.993Z,1630038652.993 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4908
2021-08-27T04:30:52.995Z,1630038652.995 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-08-27T04:30:53.003Z,1630038653.003 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-08-27T04:30:53.003Z,1630038653.003 [logger ThreadHandler](INFO): Protected caller Thread ID is 4909
2021-08-27T04:30:53.007Z,1630038653.007 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-08-27T04:30:53.008Z,1630038653.008 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-08-27T04:30:53.009Z,1630038653.009 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-08-27T04:30:53.499Z,1630038653.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-08-27T04:30:53.500Z,1630038653.500 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-08-27T04:30:53.636Z,1630038653.636 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-08-27T04:30:54.770Z,1630038654.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-08-27T04:30:54.771Z,1630038654.771 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-08-27T04:30:55.493Z,1630038655.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-08-27T04:30:55.493Z,1630038655.493 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-08-27T04:30:55.590Z,1630038655.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-08-27T04:30:55.591Z,1630038655.591 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-08-27T04:30:56.022Z,1630038656.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-08-27T04:30:56.022Z,1630038656.022 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-08-27T04:30:56.223Z,1630038656.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-08-27T04:30:56.224Z,1630038656.224 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-08-27T04:30:56.675Z,1630038656.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-08-27T04:30:56.676Z,1630038656.676 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-08-27T04:30:56.802Z,1630038656.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-08-27T04:30:56.803Z,1630038656.803 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-08-27T04:30:56.882Z,1630038656.882 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-08-27T04:30:57.539Z,1630038657.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-08-27T04:30:57.540Z,1630038657.540 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-08-27T04:30:57.907Z,1630038657.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-08-27T04:30:57.908Z,1630038657.908 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-08-27T04:30:58.106Z,1630038658.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-08-27T04:30:58.107Z,1630038658.107 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-08-27T04:30:58.219Z,1630038658.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-08-27T04:30:58.219Z,1630038658.219 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-08-27T04:30:58.373Z,1630038658.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-08-27T04:30:58.373Z,1630038658.373 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-08-27T04:30:58.602Z,1630038658.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-08-27T04:30:58.604Z,1630038658.604 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-08-27T04:30:58.605Z,1630038658.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-08-27T04:30:58.696Z,1630038658.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-08-27T04:30:58.783Z,1630038658.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-08-27T04:30:58.885Z,1630038658.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-08-27T04:30:58.969Z,1630038658.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-08-27T04:30:59.065Z,1630038659.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-08-27T04:30:59.167Z,1630038659.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-08-27T04:30:59.368Z,1630038659.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-08-27T04:30:59.444Z,1630038659.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-08-27T04:30:59.596Z,1630038659.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-08-27T04:30:59.736Z,1630038659.736 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-08-27T04:30:59.855Z,1630038659.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-08-27T04:31:00.115Z,1630038660.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-08-27T04:31:00.116Z,1630038660.116 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-08-27T04:31:00.117Z,1630038660.117 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-08-27T04:31:00.131Z,1630038660.131 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-08-27T04:31:00.202Z,1630038660.202 [VerticalControl](DEBUG): Construct VerticalControl.
2021-08-27T04:31:00.262Z,1630038660.262 [VerticalControl] Loaded
2021-08-27T04:31:00.263Z,1630038660.263 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-08-27T04:31:00.265Z,1630038660.265 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-08-27T04:31:00.303Z,1630038660.303 [HorizontalControl] Loaded
2021-08-27T04:31:00.303Z,1630038660.303 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-08-27T04:31:00.305Z,1630038660.305 [SpeedControl](DEBUG): Construct SpeedControl.
2021-08-27T04:31:00.309Z,1630038660.309 [SpeedControl] Loaded
2021-08-27T04:31:00.309Z,1630038660.309 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-08-27T04:31:00.312Z,1630038660.312 [LoopControl](DEBUG): Construct LoopControl.
2021-08-27T04:31:00.312Z,1630038660.312 [LoopControl] Loaded
2021-08-27T04:31:00.313Z,1630038660.313 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-08-27T04:31:00.313Z,1630038660.313 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-08-27T04:31:00.314Z,1630038660.314 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-08-27T04:31:00.521Z,1630038660.521 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-08-27T04:31:00.522Z,1630038660.522 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-08-27T04:31:01.133Z,1630038661.133 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-08-27T04:31:01.134Z,1630038661.134 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-08-27T04:31:01.320Z,1630038661.320 [BuoyancyServo] Loaded
2021-08-27T04:31:01.321Z,1630038661.321 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-08-27T04:31:01.339Z,1630038661.339 [ElevatorServo] Loaded
2021-08-27T04:31:01.340Z,1630038661.340 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-08-27T04:31:01.358Z,1630038661.358 [MassServo] Loaded
2021-08-27T04:31:01.358Z,1630038661.358 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-08-27T04:31:01.375Z,1630038661.375 [RudderServo] Loaded
2021-08-27T04:31:01.376Z,1630038661.376 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-08-27T04:31:01.393Z,1630038661.393 [ThrusterServo] Loaded
2021-08-27T04:31:01.394Z,1630038661.394 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-08-27T04:31:01.394Z,1630038661.394 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-08-27T04:31:01.395Z,1630038661.395 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-08-27T04:31:01.493Z,1630038661.493 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-08-27T04:31:01.493Z,1630038661.493 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-08-27T04:31:01.506Z,1630038661.506 [NavChart] Loaded
2021-08-27T04:31:01.506Z,1630038661.506 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-08-27T04:31:01.512Z,1630038661.512 [UniversalFixResidualReporter] Loaded
2021-08-27T04:31:01.512Z,1630038661.512 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-08-27T04:31:01.513Z,1630038661.513 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-08-27T04:31:01.513Z,1630038661.513 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-08-27T04:31:01.595Z,1630038661.595 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-08-27T04:31:01.596Z,1630038661.596 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-08-27T04:31:02.309Z,1630038662.309 [AHRS_M2] Loaded
2021-08-27T04:31:02.309Z,1630038662.309 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-08-27T04:31:03.097Z,1630038663.097 [BPC1] Loaded
2021-08-27T04:31:03.097Z,1630038663.097 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-08-27T04:31:03.404Z,1630038663.404 [DataOverHttps] Loaded
2021-08-27T04:31:03.404Z,1630038663.404 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-08-27T04:31:03.405Z,1630038663.405 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B94E0
2021-08-27T04:31:03.405Z,1630038663.405 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4997
2021-08-27T04:31:03.425Z,1630038663.425 [Depth_Keller] Loaded
2021-08-27T04:31:03.425Z,1630038663.425 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-08-27T04:31:03.430Z,1630038663.430 [DropWeight] Loaded
2021-08-27T04:31:03.430Z,1630038663.430 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-08-27T04:31:03.489Z,1630038663.489 [NAL9602] Loaded
2021-08-27T04:31:03.489Z,1630038663.489 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-08-27T04:31:03.518Z,1630038663.518 [Onboard] Loaded
2021-08-27T04:31:03.518Z,1630038663.518 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-08-27T04:31:03.520Z,1630038663.520 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E94E0
2021-08-27T04:31:03.520Z,1630038663.520 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4998
2021-08-27T04:31:03.532Z,1630038663.532 [Power24vConverter] Loaded
2021-08-27T04:31:03.533Z,1630038663.533 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-08-27T04:31:03.545Z,1630038663.545 [Radio_Surface] Loaded
2021-08-27T04:31:03.546Z,1630038663.546 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-08-27T04:31:03.547Z,1630038663.547 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409194E0
2021-08-27T04:31:03.547Z,1630038663.547 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4999
2021-08-27T04:31:03.548Z,1630038663.548 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-08-27T04:31:03.549Z,1630038663.549 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-08-27T04:31:03.611Z,1630038663.611 [DepthRateCalculator] Loaded
2021-08-27T04:31:03.612Z,1630038663.612 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-08-27T04:31:03.616Z,1630038663.616 [PitchRateCalculator] Loaded
2021-08-27T04:31:03.616Z,1630038663.616 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-08-27T04:31:03.625Z,1630038663.625 [SpeedCalculator] Loaded
2021-08-27T04:31:03.626Z,1630038663.626 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-08-27T04:31:03.639Z,1630038663.639 [TempGradientCalculator] Loaded
2021-08-27T04:31:03.640Z,1630038663.640 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-08-27T04:31:03.644Z,1630038663.644 [YawRateCalculator] Loaded
2021-08-27T04:31:03.644Z,1630038663.644 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-08-27T04:31:03.661Z,1630038663.661 [ElevatorOffsetCalculator] Loaded
2021-08-27T04:31:03.662Z,1630038663.662 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-08-27T04:31:03.662Z,1630038663.662 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-08-27T04:31:03.662Z,1630038663.662 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-08-27T04:31:03.846Z,1630038663.846 [CANONSampler] Loaded
2021-08-27T04:31:03.846Z,1630038663.846 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-08-27T04:31:03.903Z,1630038663.903 [CTD_Seabird] Loaded
2021-08-27T04:31:03.903Z,1630038663.903 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-08-27T04:31:03.905Z,1630038663.905 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409CE4E0
2021-08-27T04:31:03.905Z,1630038663.905 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5000
2021-08-27T04:31:03.924Z,1630038663.924 [PAR_Licor] Loaded
2021-08-27T04:31:03.925Z,1630038663.925 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-08-27T04:31:03.957Z,1630038663.957 [WetLabsBB2FL] Loaded
2021-08-27T04:31:03.957Z,1630038663.957 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-08-27T04:31:03.960Z,1630038663.960 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0
2021-08-27T04:31:03.960Z,1630038663.960 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5001
2021-08-27T04:31:03.961Z,1630038663.961 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-08-27T04:31:03.962Z,1630038663.962 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-08-27T04:31:04.003Z,1630038664.003 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-08-27T04:31:04.004Z,1630038664.004 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-08-27T04:31:04.389Z,1630038664.389 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-08-27T04:31:04.389Z,1630038664.389 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-08-27T04:31:04.564Z,1630038664.564 [SBIT](DEBUG): Construct Startup Built In Test.
2021-08-27T04:31:04.573Z,1630038664.573 [SBIT] Loaded
2021-08-27T04:31:04.573Z,1630038664.573 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-08-27T04:31:04.576Z,1630038664.576 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-08-27T04:31:04.591Z,1630038664.591 [IBIT] Loaded
2021-08-27T04:31:04.591Z,1630038664.591 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-08-27T04:31:04.597Z,1630038664.597 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-08-27T04:31:04.696Z,1630038664.696 [CBIT] Loaded
2021-08-27T04:31:04.696Z,1630038664.696 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-08-27T04:31:04.696Z,1630038664.696 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-08-27T04:31:04.697Z,1630038664.697 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-08-27T04:31:04.816Z,1630038664.816 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-08-27T04:31:04.822Z,1630038664.822 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-08-27T04:31:04.825Z,1630038664.825 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-08-27T04:31:04.836Z,1630038664.836 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-08-27T04:31:04.837Z,1630038664.837 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B324E0
2021-08-27T04:31:04.838Z,1630038664.838 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5002
2021-08-27T04:31:04.843Z,1630038664.843 [Supervisor](INFO): Main Thread ID is 4904
2021-08-27T04:31:04.843Z,1630038664.843 [Supervisor](DEBUG): Running supervisor.
2021-08-27T04:31:04.843Z,1630038664.843 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5003
2021-08-27T04:31:04.848Z,1630038664.848 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5004
2021-08-27T04:31:04.850Z,1630038664.850 [controlThread ThreadHandler](INFO): Handler Thread ID is 5005
2021-08-27T04:31:04.851Z,1630038664.851 [controlThread](DEBUG): Initializing ControlThread
2021-08-27T04:31:04.852Z,1630038664.852 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-08-27T04:31:04.854Z,1630038664.854 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-08-27T04:31:04.854Z,1630038664.854 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-08-27T04:31:04.855Z,1630038664.855 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-08-27T04:31:04.857Z,1630038664.857 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-08-27T04:31:04.857Z,1630038664.857 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-08-27T04:31:04.861Z,1630038664.861 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-08-27T04:31:04.861Z,1630038664.861 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-08-27T04:31:04.862Z,1630038664.862 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-08-27T04:31:04.862Z,1630038664.862 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-08-27T04:31:04.863Z,1630038664.863 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-08-27T04:31:04.863Z,1630038664.863 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-08-27T04:31:04.867Z,1630038664.867 [SBIT](INFO): Initialize SBIT Component.
2021-08-27T04:31:04.868Z,1630038664.868 [SBIT](IMPORTANT): git: 2021-07-27-17-g93e8890bb
2021-08-27T04:31:04.868Z,1630038664.868 [SBIT](INFO): git hash: 93e8890bb379c2c117ee84191f9dcbd8a4c5c033
2021-08-27T04:31:04.868Z,1630038664.868 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-08-27T04:31:04.869Z,1630038664.869 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-08-27T04:31:04.870Z,1630038664.870 [SBIT](INFO): Beginning SBIT in 90.000000 seconds.
2021-08-27T04:31:04.871Z,1630038664.871 [IBIT](INFO): Initialize IBIT Component.
2021-08-27T04:31:04.872Z,1630038664.872 [CBIT](DEBUG): Initialize CBIT Component.
2021-08-27T04:31:04.873Z,1630038664.873 [logger ThreadHandler](INFO): Handler Thread ID is 5006
2021-08-27T04:31:04.883Z,1630038664.883 [CBIT](DEBUG): Initialized mux pins.
2021-08-27T04:31:04.883Z,1630038664.883 [CBIT](DEBUG): Initializing the watchdog timer.
2021-08-27T04:31:04.891Z,1630038664.891 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5007
2021-08-27T04:31:04.893Z,1630038664.893 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-08-27T04:31:04.903Z,1630038664.903 [Onboard ThreadHandler](INFO): Handler Thread ID is 5008
2021-08-27T04:31:04.908Z,1630038664.908 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-08-27T04:31:04.908Z,1630038664.908 [CBIT](DEBUG): Initializing heartbeat.
2021-08-27T04:31:04.924Z,1630038664.924 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5009
2021-08-27T04:31:04.944Z,1630038664.944 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5010
2021-08-27T04:31:04.944Z,1630038664.944 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-08-27T04:31:04.949Z,1630038664.949 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5012
2021-08-27T04:31:04.951Z,1630038664.951 [WetLabsBB2FL](INFO): Powering up
2021-08-27T04:31:04.953Z,1630038664.953 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5014
2021-08-27T04:31:04.961Z,1630038664.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-08-27T04:31:04.961Z,1630038664.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-08-27T04:31:04.961Z,1630038664.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-08-27T04:31:04.962Z,1630038664.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-08-27T04:31:04.962Z,1630038664.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-08-27T04:31:04.962Z,1630038664.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-08-27T04:31:04.962Z,1630038664.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-08-27T04:31:04.962Z,1630038664.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-08-27T04:31:04.962Z,1630038664.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-08-27T04:31:04.963Z,1630038664.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-08-27T04:31:04.963Z,1630038664.963 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-08-27T04:31:04.963Z,1630038664.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-08-27T04:31:04.963Z,1630038664.963 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-08-27T04:31:04.964Z,1630038664.964 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-08-27T04:31:04.964Z,1630038664.964 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-08-27T04:31:04.964Z,1630038664.964 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-08-27T04:31:04.979Z,1630038664.979 [CBIT](DEBUG): Deactivating GF circuits.
2021-08-27T04:31:04.979Z,1630038664.979 [CBIT](DEBUG): Deactivating emergency mode.
2021-08-27T04:31:05.015Z,1630038665.015 [CBIT](DEBUG): Backplane powered.
2021-08-27T04:31:05.016Z,1630038665.016 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-08-27T04:31:05.030Z,1630038665.030 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-08-27T04:31:05.071Z,1630038665.071 [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>


2021-08-27T04:31:05.072Z,1630038665.072 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-08-27T04:31:05.150Z,1630038665.150 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-08-27T04:31:05.151Z,1630038665.151 [Default:A.Wait](DEBUG): Construct Wait.
2021-08-27T04:31:05.153Z,1630038665.153 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-08-27T04:31:05.201Z,1630038665.201 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-08-27T04:31:05.203Z,1630038665.203 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-08-27T04:31:05.224Z,1630038665.224 [Default:E.Execute](DEBUG): Construct Execute.
2021-08-27T04:31:05.243Z,1630038665.243 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</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>


2021-08-27T04:31:05.248Z,1630038665.248 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-08-27T04:31:05.267Z,1630038665.267 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-08-27T04:31:05.331Z,1630038665.331 [Radio_Surface](INFO): Powering up
2021-08-27T04:31:05.382Z,1630038665.382 [Power24vConverter](INFO): Powering up.
2021-08-27T04:31:05.382Z,1630038665.382 [CANONSampler](INFO): Powering down
2021-08-27T04:31:05.549Z,1630038665.549 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-08-27T04:31:05.563Z,1630038665.563 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-08-27T04:31:05.564Z,1630038665.564 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-08-27T04:31:05.575Z,1630038665.575 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-08-27T04:31:05.576Z,1630038665.576 [MassServo](DEBUG): Initializing EZServoServo.
2021-08-27T04:31:05.583Z,1630038665.583 [MassServo](DEBUG): Initializing MassServo.
2021-08-27T04:31:05.584Z,1630038665.584 [RudderServo](DEBUG): Initializing EZServoServo.
2021-08-27T04:31:05.591Z,1630038665.591 [RudderServo](DEBUG): Initializing RudderServo.
2021-08-27T04:31:05.592Z,1630038665.592 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-08-27T04:31:05.599Z,1630038665.599 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-08-27T04:31:07.075Z,1630038667.075 [WetLabsBB2FL](INFO): Powering down
2021-08-27T04:31:11.975Z,1630038671.975 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-08-27T04:31:18.554Z,1630038678.554 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005684
2021-08-27T04:31:33.537Z,1630038693.537 [NAL9602](INFO): Powering up NAL9602
2021-08-27T04:31:44.453Z,1630038704.453 [NAL9602](INFO): NAL9602 initialized
2021-08-27T04:32:04.899Z,1630038724.899 [CommandExec](IMPORTANT): got command report touch controlThread.durationOfLastRun
2021-08-27T04:32:05.514Z,1630038725.514 [Reporter](INFO): controlThread.durationOfLastRun 0.078077 s
2021-08-27T04:32:05.930Z,1630038725.930 [Reporter](INFO): controlThread.durationOfLastRun 0.067446 s
2021-08-27T04:32:06.336Z,1630038726.336 [Reporter](INFO): controlThread.durationOfLastRun 0.080354 s
2021-08-27T04:32:06.752Z,1630038726.752 [Reporter](INFO): controlThread.durationOfLastRun 0.081831 s
2021-08-27T04:32:07.134Z,1630038727.134 [Reporter](INFO): controlThread.durationOfLastRun 0.093659 s
2021-08-27T04:32:07.532Z,1630038727.532 [Reporter](INFO): controlThread.durationOfLastRun 0.071654 s
2021-08-27T04:32:07.951Z,1630038727.951 [Reporter](INFO): controlThread.durationOfLastRun 0.065838 s
2021-08-27T04:32:08.381Z,1630038728.381 [Reporter](INFO): controlThread.durationOfLastRun 0.080798 s
2021-08-27T04:32:08.744Z,1630038728.744 [Reporter](INFO): controlThread.durationOfLastRun 0.106923 s
2021-08-27T04:32:09.157Z,1630038729.157 [Reporter](INFO): controlThread.durationOfLastRun 0.065945 s
2021-08-27T04:32:09.561Z,1630038729.561 [Reporter](INFO): controlThread.durationOfLastRun 0.074295 s
2021-08-27T04:32:09.968Z,1630038729.968 [Reporter](INFO): controlThread.durationOfLastRun 0.074869 s
2021-08-27T04:32:10.382Z,1630038730.382 [Reporter](INFO): controlThread.durationOfLastRun 0.081992 s
2021-08-27T04:32:10.767Z,1630038730.767 [Reporter](INFO): controlThread.durationOfLastRun 0.088146 s
2021-08-27T04:32:11.166Z,1630038731.166 [Reporter](INFO): controlThread.durationOfLastRun 0.068682 s
2021-08-27T04:32:11.532Z,1630038731.532 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:32:11.605Z,1630038731.605 [Reporter](INFO): controlThread.durationOfLastRun 0.061830 s
2021-08-27T04:32:11.978Z,1630038731.978 [Reporter](INFO): controlThread.durationOfLastRun 0.096991 s
2021-08-27T04:32:12.392Z,1630038732.392 [Reporter](INFO): controlThread.durationOfLastRun 0.092432 s
2021-08-27T04:32:12.785Z,1630038732.785 [Reporter](INFO): controlThread.durationOfLastRun 0.077100 s
2021-08-27T04:32:13.170Z,1630038733.170 [Reporter](INFO): controlThread.durationOfLastRun 0.066644 s
2021-08-27T04:32:13.585Z,1630038733.585 [Reporter](INFO): controlThread.durationOfLastRun 0.047905 s
2021-08-27T04:32:13.980Z,1630038733.980 [Reporter](INFO): controlThread.durationOfLastRun 0.058844 s
2021-08-27T04:32:14.402Z,1630038734.402 [Reporter](INFO): controlThread.durationOfLastRun 0.050101 s
2021-08-27T04:32:14.786Z,1630038734.786 [Reporter](INFO): controlThread.durationOfLastRun 0.067671 s
2021-08-27T04:32:15.185Z,1630038735.185 [Reporter](INFO): controlThread.durationOfLastRun 0.064049 s
2021-08-27T04:32:15.593Z,1630038735.593 [Reporter](INFO): controlThread.durationOfLastRun 0.042566 s
2021-08-27T04:32:16.001Z,1630038736.001 [Reporter](INFO): controlThread.durationOfLastRun 0.046305 s
2021-08-27T04:32:16.386Z,1630038736.386 [Reporter](INFO): controlThread.durationOfLastRun 0.050869 s
2021-08-27T04:32:16.791Z,1630038736.791 [Reporter](INFO): controlThread.durationOfLastRun 0.032085 s
2021-08-27T04:32:17.202Z,1630038737.202 [Reporter](INFO): controlThread.durationOfLastRun 0.032349 s
2021-08-27T04:32:17.617Z,1630038737.617 [Reporter](INFO): controlThread.durationOfLastRun 0.039888 s
2021-08-27T04:32:18.002Z,1630038738.002 [Reporter](INFO): controlThread.durationOfLastRun 0.050147 s
2021-08-27T04:32:18.414Z,1630038738.414 [Reporter](INFO): controlThread.durationOfLastRun 0.031499 s
2021-08-27T04:32:18.828Z,1630038738.828 [Reporter](INFO): controlThread.durationOfLastRun 0.039296 s
2021-08-27T04:32:19.240Z,1630038739.240 [Reporter](INFO): controlThread.durationOfLastRun 0.049129 s
2021-08-27T04:32:19.654Z,1630038739.654 [Reporter](INFO): controlThread.durationOfLastRun 0.057872 s
2021-08-27T04:32:20.057Z,1630038740.057 [Reporter](INFO): controlThread.durationOfLastRun 0.067648 s
2021-08-27T04:32:20.434Z,1630038740.434 [Reporter](INFO): controlThread.durationOfLastRun 0.066640 s
2021-08-27T04:32:20.842Z,1630038740.842 [Reporter](INFO): controlThread.durationOfLastRun 0.039673 s
2021-08-27T04:32:21.253Z,1630038741.253 [Reporter](INFO): controlThread.durationOfLastRun 0.044702 s
2021-08-27T04:32:21.643Z,1630038741.643 [Reporter](INFO): controlThread.durationOfLastRun 0.050391 s
2021-08-27T04:32:22.049Z,1630038742.049 [Reporter](INFO): controlThread.durationOfLastRun 0.036602 s
2021-08-27T04:32:22.472Z,1630038742.472 [Reporter](INFO): controlThread.durationOfLastRun 0.038331 s
2021-08-27T04:32:22.904Z,1630038742.904 [Reporter](INFO): controlThread.durationOfLastRun 0.057774 s
2021-08-27T04:32:23.258Z,1630038743.258 [Reporter](INFO): controlThread.durationOfLastRun 0.085989 s
2021-08-27T04:32:23.673Z,1630038743.673 [Reporter](INFO): controlThread.durationOfLastRun 0.035709 s
2021-08-27T04:32:24.098Z,1630038744.098 [Reporter](INFO): controlThread.durationOfLastRun 0.046502 s
2021-08-27T04:32:24.573Z,1630038744.573 [Reporter](INFO): controlThread.durationOfLastRun 0.068215 s
2021-08-27T04:32:24.927Z,1630038744.927 [Reporter](INFO): controlThread.durationOfLastRun 0.138929 s
2021-08-27T04:32:25.328Z,1630038745.328 [Reporter](INFO): controlThread.durationOfLastRun 0.089007 s
2021-08-27T04:32:25.700Z,1630038745.700 [Reporter](INFO): controlThread.durationOfLastRun 0.085507 s
2021-08-27T04:32:26.084Z,1630038746.084 [Reporter](INFO): controlThread.durationOfLastRun 0.053634 s
2021-08-27T04:32:26.484Z,1630038746.484 [Reporter](INFO): controlThread.durationOfLastRun 0.034109 s
2021-08-27T04:32:26.684Z,1630038746.684 [CommandExec](IMPORTANT): got command report clear
2021-08-27T04:32:35.447Z,1630038755.447 [SBIT](IMPORTANT): Beginning Startup BIT
2021-08-27T04:32:35.451Z,1630038755.451 [CBIT](IMPORTANT): Beginning ground fault scan
2021-08-27T04:32:46.321Z,1630038766.321 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.015297
CHAN A1 (24V): -0.181114
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.001568
CHAN B0 (3.3V): -0.000054
CHAN B1 (3.15aV): 0.000284
CHAN B2 (3.15bV): 0.000011
CHAN B3 (GND): -0.000127
OPEN: -0.000523
Full Scale Calc: 4.765 mA, -1.589 mA
2021-08-27T04:33:17.108Z,1630038797.108 [CommandExec](IMPORTANT): got command failComponent
2021-08-27T04:33:17.108Z,1630038797.108 [CommandExec](IMPORTANT): Failed components:
2021-08-27T04:33:17.108Z,1630038797.108 [CommandExec](IMPORTANT): No failed Components.
2021-08-27T04:33:29.188Z,1630038809.188 [SBIT](IMPORTANT): SBIT PASSED
2021-08-27T04:33:29.188Z,1630038809.188 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-08-27T04:33:29.190Z,1630038809.190 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-08-27T04:33:29.190Z,1630038809.190 [SBIT](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-08-27T04:33:29.190Z,1630038809.190 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-08-27T04:33:29.190Z,1630038809.190 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-08-27T04:33:29.190Z,1630038809.190 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-08-27T04:33:29.192Z,1630038809.192 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=310.836325 cubic_centimeter;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): VerticalControl.kdDepthBuoy=1000 second;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): VerticalControl.kiDepthBuoy=0.15 reciprocal_second;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): VerticalControl.kiDepthOff=0.01 meter_per_second;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): VerticalControl.kpDepthBuoy=120 ratio;

2021-08-27T04:33:29.193Z,1630038809.193 [SBIT](IMPORTANT): VerticalControl.massDefault=-17.611304 millimeter;

2021-08-27T04:33:29.513Z,1630038809.513 [MissionManager](IMPORTANT): Started mission Startup
2021-08-27T04:33:29.514Z,1630038809.514 [Startup] Running Loop=1
2021-08-27T04:33:29.514Z,1630038809.514 [Startup](DEBUG): Aggregate::initialize Startup
2021-08-27T04:33:29.514Z,1630038809.514 [Startup:A.GoToSurface] Running Loop=1
2021-08-27T04:33:29.514Z,1630038809.514 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-08-27T04:33:29.514Z,1630038809.514 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-08-27T04:33:29.515Z,1630038809.515 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-08-27T04:33:29.516Z,1630038809.516 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-08-27T04:33:29.516Z,1630038809.516 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-08-27T04:33:29.516Z,1630038809.516 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-08-27T04:33:29.518Z,1630038809.518 [Startup:StartupSatComms] Running Loop=1
2021-08-27T04:33:29.518Z,1630038809.518 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-08-27T04:33:29.518Z,1630038809.518 [Startup:StartupSatComms:A] Running Loop=1
2021-08-27T04:33:29.909Z,1630038809.909 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-08-27T04:34:05.128Z,1630038845.128 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-08-27T04:34:05.128Z,1630038845.128 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-08-27T04:34:05.213Z,1630038845.213 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-08-27T04:34:05.563Z,1630038845.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-08-27T04:34:05.563Z,1630038845.563 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-08-27T04:34:16.001Z,1630038856.001 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:34:16.180Z,1630038856.180 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T043415
2021-08-27T04:34:22.836Z,1630038862.836 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:34:23.695Z,1630038863.695 [DataOverHttps](INFO): Received command: failc
2021-08-27T04:34:23.731Z,1630038863.731 [CommandExec](IMPORTANT): got command failComponent
2021-08-27T04:34:23.732Z,1630038863.732 [CommandExec](IMPORTANT): Failed components:
2021-08-27T04:34:23.732Z,1630038863.732 [CommandExec](IMPORTANT): No failed Components.
2021-08-27T04:34:29.722Z,1630038869.722 [Startup:StartupSatComms:A](INFO): Timed out from 2021-08-27T04:33:29.5Z
2021-08-27T04:34:29.722Z,1630038869.722 [Startup:StartupSatComms:A] Stopped
2021-08-27T04:34:29.722Z,1630038869.722 [Startup:StartupSatComms:B] Running Loop=1
2021-08-27T04:34:30.154Z,1630038870.154 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-08-27T04:34:37.104Z,1630038877.104 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210805T201921/Courier0030.lzma
2021-08-27T04:34:38.105Z,1630038878.105 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0030.lzma.bak
2021-08-27T04:34:38.105Z,1630038878.105 [DataOverHttps](INFO): SBD MOMSN=15900781
2021-08-27T04:34:53.895Z,1630038893.895 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210827T043052/Courier0000.lzma
2021-08-27T04:34:54.897Z,1630038894.897 [DataOverHttps](INFO): Moved sent file to Logs/20210827T043052/Courier0000.lzma.bak
2021-08-27T04:34:54.897Z,1630038894.897 [DataOverHttps](INFO): SBD MOMSN=15900783
2021-08-27T04:35:09.398Z,1630038909.398 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:35:10.864Z,1630038910.864 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20210805T201921/Express0031.lzma
2021-08-27T04:35:11.865Z,1630038911.865 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0031.lzma.bak
2021-08-27T04:35:11.865Z,1630038911.865 [DataOverHttps](INFO): SBD MOMSN=15900786
2021-08-27T04:35:19.852Z,1630038919.852 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:35:27.824Z,1630038927.824 [DataOverHttps](INFO): Sending 1037 bytes from file Logs/20210827T043052/Express0001.lzma
2021-08-27T04:35:28.825Z,1630038928.825 [DataOverHttps](INFO): Moved sent file to Logs/20210827T043052/Express0001.lzma.bak
2021-08-27T04:35:28.825Z,1630038928.825 [DataOverHttps](INFO): SBD MOMSN=15900789
2021-08-27T04:35:29.897Z,1630038929.897 [Startup:StartupSatComms:B](INFO): Timed out from 2021-08-27T04:34:29.7Z
2021-08-27T04:35:29.897Z,1630038929.897 [Startup:StartupSatComms:B] Stopped
2021-08-27T04:35:29.897Z,1630038929.897 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-08-27T04:35:29.897Z,1630038929.897 [Startup:StartupSatComms] Stopped
2021-08-27T04:35:29.897Z,1630038929.897 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-08-27T04:35:29.898Z,1630038929.898 [Startup](INFO): Completed Startup
2021-08-27T04:35:29.898Z,1630038929.898 [MissionManager](INFO): Startup is completed.
2021-08-27T04:35:29.898Z,1630038929.898 [MissionManager](INFO): Uninitializing Mission Startup
2021-08-27T04:35:29.898Z,1630038929.898 [Startup] Stopped
2021-08-27T04:35:29.898Z,1630038929.898 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-08-27T04:35:29.898Z,1630038929.898 [Startup:A.GoToSurface] Stopped
2021-08-27T04:35:29.899Z,1630038929.899 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-08-27T04:35:30.301Z,1630038930.301 [MissionManager](IMPORTANT): Started mission Default
2021-08-27T04:35:30.302Z,1630038930.302 [Default] Running Loop=1
2021-08-27T04:35:30.302Z,1630038930.302 [Default](DEBUG): Aggregate::initialize Default
2021-08-27T04:35:30.302Z,1630038930.302 [Default:B.GoToSurface] Running Loop=1
2021-08-27T04:35:30.302Z,1630038930.302 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-08-27T04:35:30.302Z,1630038930.302 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-08-27T04:35:30.303Z,1630038930.303 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-08-27T04:35:30.303Z,1630038930.303 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-08-27T04:35:30.303Z,1630038930.303 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-08-27T04:35:30.304Z,1630038930.304 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-08-27T04:35:30.304Z,1630038930.304 [Default:A.Wait] Running Loop=1
2021-08-27T04:35:30.304Z,1630038930.304 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-08-27T04:35:30.868Z,1630038930.868 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:35:41.670Z,1630038941.670 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:35:43.646Z,1630038943.646 [Default:A.Wait](INFO): Done Waiting.
2021-08-27T04:35:43.646Z,1630038943.646 [Default:A.Wait] Stopped
2021-08-27T04:35:43.646Z,1630038943.646 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-08-27T04:35:44.051Z,1630038944.051 [Default:CheckIn] Running Loop=1
2021-08-27T04:35:44.051Z,1630038944.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-08-27T04:35:44.051Z,1630038944.051 [Default:CheckIn:Read_GPS] Running Loop=1
2021-08-27T04:35:44.522Z,1630038944.522 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-08-27T04:35:52.582Z,1630038952.582 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:36:03.531Z,1630038963.531 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:36:05.500Z,1630038965.500 [CommandExec](IMPORTANT): got command burn 10.000000
2021-08-27T04:36:06.682Z,1630038966.682 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210827T043052/Express0005.lzma
2021-08-27T04:36:07.685Z,1630038967.685 [DataOverHttps](INFO): Moved sent file to Logs/20210827T043052/Express0005.lzma.bak
2021-08-27T04:36:07.685Z,1630038967.685 [DataOverHttps](INFO): SBD MOMSN=15900822
2021-08-27T04:36:14.395Z,1630038974.395 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:36:15.519Z,1630038975.519 [CommandExec](IMPORTANT): Deactivating dropweight wire
2021-08-27T04:36:23.216Z,1630038983.216 [CommandExec](IMPORTANT): got command burn 5.000000
2021-08-27T04:36:24.895Z,1630038984.895 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:36:28.255Z,1630038988.255 [CommandExec](IMPORTANT): Deactivating dropweight wire
2021-08-27T04:36:36.224Z,1630038996.224 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:36:38.940Z,1630038998.940 [CommandExec](IMPORTANT): got command strobe off
2021-08-27T04:36:38.940Z,1630038998.940 [CommandExec](IMPORTANT): Deactivating strobe
2021-08-27T04:36:43.520Z,1630039003.520 [CommandExec](IMPORTANT): got command ibit
2021-08-27T04:36:43.846Z,1630039003.846 [IBIT](IMPORTANT): Beginning Initiated BIT
2021-08-27T04:36:43.846Z,1630039003.846 [IBIT](IMPORTANT): Beginning control surface checks.
2021-08-27T04:36:43.849Z,1630039003.849 [CBIT](IMPORTANT): Beginning ground fault scan
2021-08-27T04:36:47.122Z,1630039007.122 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:36:47.873Z,1630039007.873 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-08-27T04:36:50.876Z,1630039010.876 [CommandExec](IMPORTANT): got command failComponent
2021-08-27T04:36:50.876Z,1630039010.876 [CommandExec](IMPORTANT): Failed components:
2021-08-27T04:36:50.876Z,1630039010.876 [CommandExec](IMPORTANT): No failed Components.
2021-08-27T04:36:54.751Z,1630039014.751 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.017295
CHAN A1 (24V): 4.610929
CHAN A2 (12V): -0.002185
CHAN A3 (5V): -0.001747
CHAN B0 (3.3V): 4.766929
CHAN B1 (3.15aV): -0.000488
CHAN B2 (3.15bV): -0.000222
CHAN B3 (GND): -0.000086
OPEN: -0.000439
Full Scale Calc: 4.765 mA, -1.589 mA
2021-08-27T04:36:58.028Z,1630039018.028 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:37:06.064Z,1630039026.064 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-08-27T04:37:06.064Z,1630039026.064 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-08-27T04:37:06.127Z,1630039026.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-08-27T04:37:06.704Z,1630039026.704 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-08-27T04:37:06.704Z,1630039026.704 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-08-27T04:37:08.931Z,1630039028.931 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:37:19.833Z,1630039039.833 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:37:19.914Z,1630039039.914 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:37:30.748Z,1630039050.748 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:37:57.001Z,1630039077.001 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:38:09.808Z,1630039089.808 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T043808
2021-08-27T04:38:17.215Z,1630039097.215 [DataOverHttps](INFO): Received command: sched "failComponent"
2021-08-27T04:38:17.224Z,1630039097.224 [CommandExec](IMPORTANT): got command schedule "failComponent"
2021-08-27T04:38:17.225Z,1630039097.225 [CommandExec](IMPORTANT): Scheduled #1: "failComponent"
2021-08-27T04:38:17.276Z,1630039097.276 [CommandExec](IMPORTANT): got command failComponent
2021-08-27T04:38:17.276Z,1630039097.276 [CommandExec](IMPORTANT): Failed components:
2021-08-27T04:38:17.277Z,1630039097.277 [CommandExec](IMPORTANT): No failed Components.
2021-08-27T04:38:34.962Z,1630039114.962 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2021-08-27T04:38:35.379Z,1630039115.379 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2021-08-27T04:38:35.745Z,1630039115.745 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2021-08-27T04:38:35.746Z,1630039115.746 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2021-08-27T04:38:35.746Z,1630039115.746 [IBIT](IMPORTANT): Pressure:9.942170 PSI
2021-08-27T04:38:35.746Z,1630039115.746 [IBIT](IMPORTANT): Humidity:7.398364 %
2021-08-27T04:38:36.160Z,1630039116.160 [IBIT](IMPORTANT): Vehicle Pitch:0.259410 degrees
2021-08-27T04:38:36.160Z,1630039116.160 [IBIT](IMPORTANT): Vehicle  Roll:4.263228 degrees
2021-08-27T04:38:36.160Z,1630039116.160 [IBIT](IMPORTANT): Vehicle  Heading:224.771622 degrees
2021-08-27T04:38:36.568Z,1630039116.568 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2021-08-27T04:38:36.569Z,1630039116.569 [IBIT](IMPORTANT): buoyancyNeutral: 310.836334 cc
2021-08-27T04:38:36.569Z,1630039116.569 [IBIT](IMPORTANT): massDefault: -1.761130 cm
2021-08-27T04:38:36.569Z,1630039116.569 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2021-08-27T04:38:36.570Z,1630039116.570 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2021-08-27T04:38:36.570Z,1630039116.570 [IBIT](IMPORTANT): IBIT FAILED
2021-08-27T04:39:19.249Z,1630039159.249 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T043918
2021-08-27T04:39:26.807Z,1630039166.807 [DataOverHttps](INFO): Received command: sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 8 h;set profile_station.NeedCommsTime 60 min;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 20 m" 3ekwt 1 2
2021-08-27T04:39:26.836Z,1630039166.836 [CommandExec](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 8 h;set profile_station.NeedCommsTime 60 min;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 20 m" 3ekwt 1 2.000000
2021-08-27T04:39:26.836Z,1630039166.836 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3ekwt
2021-08-27T04:39:26.837Z,1630039166.837 [CommandExec](IMPORTANT): Scheduled #2 (#1 of 2 with id='3ekwt'): "load Science/profile_station.xml;set profile_station.MissionTimeout 8 h;set profile_station.NeedCommsTime 60 min;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree;set profile_station.YoYoMaxDepth 20 m" ASAP
2021-08-27T04:39:27.808Z,1630039167.808 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T043926
2021-08-27T04:39:35.211Z,1630039175.211 [DataOverHttps](INFO): Received command: sched asap "set profile_station.MaxDepth 27 m;run" 3ekwt 2 2
2021-08-27T04:39:35.254Z,1630039175.254 [CommandExec](IMPORTANT): got command schedule asap "set profile_station.MaxDepth 27 m;run" 3ekwt 2 2.000000
2021-08-27T04:39:35.254Z,1630039175.254 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3ekwt
2021-08-27T04:39:35.255Z,1630039175.255 [CommandExec](IMPORTANT): Scheduled #3 (#2 of 2 with id='3ekwt'): "set profile_station.MaxDepth 27 m;run" ASAP
2021-08-27T04:39:35.369Z,1630039175.369 [CommandExec](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2021-08-27T04:39:35.370Z,1630039175.370 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2021-08-27T04:39:35.466Z,1630039175.466 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2021-08-27T04:39:35.470Z,1630039175.470 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2021-08-27T04:39:35.473Z,1630039175.473 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2021-08-27T04:39:35.476Z,1630039175.476 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2021-08-27T04:39:35.478Z,1630039175.478 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2021-08-27T04:39:35.482Z,1630039175.482 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2021-08-27T04:39:35.485Z,1630039175.485 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2021-08-27T04:39:35.487Z,1630039175.487 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2021-08-27T04:39:35.490Z,1630039175.490 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2021-08-27T04:39:35.493Z,1630039175.493 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2021-08-27T04:39:35.499Z,1630039175.499 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2021-08-27T04:39:35.502Z,1630039175.502 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2021-08-27T04:39:35.541Z,1630039175.541 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2021-08-27T04:39:35.545Z,1630039175.545 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2021-08-27T04:39:35.568Z,1630039175.568 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2021-08-27T04:39:35.579Z,1630039175.579 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2021-08-27T04:39:35.580Z,1630039175.580 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-08-27T04:39:36.085Z,1630039176.085 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2021-08-27T04:39:36.087Z,1630039176.087 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2021-08-27T04:39:36.090Z,1630039176.090 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2021-08-27T04:39:36.093Z,1630039176.093 [MissionManager](INFO): DefineArg profile_station:Science.HighestSaltPeakReportActive = 0 bool
2021-08-27T04:39:36.095Z,1630039176.095 [MissionManager](INFO): DefineArg profile_station:Science.HighestOilPeakReportActive = 0 bool
2021-08-27T04:39:36.098Z,1630039176.098 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2021-08-27T04:39:36.101Z,1630039176.101 [MissionManager](INFO): DefineArg profile_station:Science.DetectTrough = 0 bool
2021-08-27T04:39:36.128Z,1630039176.128 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2021-08-27T04:39:36.131Z,1630039176.131 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2021-08-27T04:39:36.134Z,1630039176.134 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2021-08-27T04:39:36.136Z,1630039176.136 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2021-08-27T04:39:36.162Z,1630039176.162 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2021-08-27T04:39:36.165Z,1630039176.165 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2021-08-27T04:39:36.167Z,1630039176.167 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectSalinityActive = 0 bool
2021-08-27T04:39:36.178Z,1630039176.178 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-08-27T04:39:36.192Z,1630039176.192 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 0.000000 bool
2021-08-27T04:39:36.195Z,1630039176.195 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2021-08-27T04:39:36.212Z,1630039176.212 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T043935
2021-08-27T04:39:36.224Z,1630039176.224 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2021-08-27T04:39:36.227Z,1630039176.227 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-08-27T04:39:36.231Z,1630039176.231 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-08-27T04:39:36.234Z,1630039176.234 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-08-27T04:39:36.250Z,1630039176.250 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2021-08-27T04:39:36.252Z,1630039176.252 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2021-08-27T04:39:36.255Z,1630039176.255 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2021-08-27T04:39:36.261Z,1630039176.261 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2021-08-27T04:39:36.278Z,1630039176.278 [MissionManager](INFO): DefineArg profile_station:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-08-27T04:39:36.282Z,1630039176.282 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2021-08-27T04:39:36.287Z,1630039176.287 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2021-08-27T04:39:36.290Z,1630039176.290 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2021-08-27T04:39:36.293Z,1630039176.293 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2021-08-27T04:39:36.345Z,1630039176.345 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2021-08-27T04:39:36.348Z,1630039176.348 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2021-08-27T04:39:36.367Z,1630039176.367 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2021-08-27T04:39:36.371Z,1630039176.371 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2021-08-27T04:39:36.389Z,1630039176.389 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2021-08-27T04:39:36.392Z,1630039176.392 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2021-08-27T04:39:36.395Z,1630039176.395 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2021-08-27T04:39:36.431Z,1630039176.431 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2021-08-27T04:39:36.434Z,1630039176.434 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2021-08-27T04:39:36.437Z,1630039176.437 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2021-08-27T04:39:36.440Z,1630039176.440 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2021-08-27T04:39:36.454Z,1630039176.454 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMTemperature = nan degC
2021-08-27T04:39:36.457Z,1630039176.457 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2021-08-27T04:39:36.460Z,1630039176.460 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2021-08-27T04:39:36.484Z,1630039176.484 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2021-08-27T04:39:36.491Z,1630039176.491 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSalt = 0.000000 psu
2021-08-27T04:39:36.494Z,1630039176.494 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltDepth = 0.000000 m
2021-08-27T04:39:36.501Z,1630039176.501 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltTemperature = 0.000000 degC
2021-08-27T04:39:36.509Z,1630039176.509 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltLatitude = nan arcdeg
2021-08-27T04:39:36.513Z,1630039176.513 [MissionManager](INFO): DefineOutput profile_station:Science.PeakSaltLongitude = nan arcdeg
2021-08-27T04:39:36.525Z,1630039176.525 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 ug/l
2021-08-27T04:39:36.528Z,1630039176.528 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2021-08-27T04:39:36.539Z,1630039176.539 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2021-08-27T04:39:36.542Z,1630039176.542 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2021-08-27T04:39:36.549Z,1630039176.549 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2021-08-27T04:39:36.554Z,1630039176.554 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSalt = 0.000000 psu
2021-08-27T04:39:36.565Z,1630039176.565 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltDepth = 0.000000 m
2021-08-27T04:39:36.572Z,1630039176.572 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltLatitude = nan arcdeg
2021-08-27T04:39:36.578Z,1630039176.578 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltLongitude = nan arcdeg
2021-08-27T04:39:36.594Z,1630039176.594 [MissionManager](INFO): DefineOutput profile_station:Science.PatchSaltDistance = nan m
2021-08-27T04:39:36.606Z,1630039176.606 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOil = 0.000000 psu
2021-08-27T04:39:36.610Z,1630039176.610 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilDepth = 0.000000 m
2021-08-27T04:39:36.614Z,1630039176.614 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilLatitude = nan arcdeg
2021-08-27T04:39:36.626Z,1630039176.626 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilLongitude = nan arcdeg
2021-08-27T04:39:36.637Z,1630039176.637 [MissionManager](INFO): DefineOutput profile_station:Science.PatchOilDistance = nan m
2021-08-27T04:39:36.710Z,1630039176.710 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-08-27T04:39:36.800Z,1630039176.800 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-08-27T04:39:36.838Z,1630039176.838 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-08-27T04:39:36.869Z,1630039176.869 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-08-27T04:39:36.892Z,1630039176.892 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-08-27T04:39:36.918Z,1630039176.918 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-08-27T04:39:36.953Z,1630039176.953 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-08-27T04:39:36.974Z,1630039176.974 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-08-27T04:39:37.022Z,1630039177.022 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2021-08-27T04:39:37.022Z,1630039177.022 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-08-27T04:39:37.106Z,1630039177.106 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2021-08-27T04:39:37.109Z,1630039177.109 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2021-08-27T04:39:37.112Z,1630039177.112 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-08-27T04:39:37.180Z,1630039177.180 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2021-08-27T04:39:37.183Z,1630039177.183 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-08-27T04:39:37.192Z,1630039177.192 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2021-08-27T04:39:37.195Z,1630039177.195 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2021-08-27T04:39:37.228Z,1630039177.228 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-08-27T04:39:37.284Z,1630039177.284 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-08-27T04:39:37.348Z,1630039177.348 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2021-08-27T04:39:37.353Z,1630039177.353 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2021-08-27T04:39:37.357Z,1630039177.357 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-08-27T04:39:37.358Z,1630039177.358 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-08-27T04:39:37.374Z,1630039177.374 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-08-27T04:39:37.405Z,1630039177.405 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-08-27T04:39:37.427Z,1630039177.427 [profile_station:I.Pitch](DEBUG): Construct.
2021-08-27T04:39:37.456Z,1630039177.456 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2021-08-27T04:39:37.467Z,1630039177.467 [profile_station:K.SetSpeed](DEBUG): Construct.
2021-08-27T04:39:37.487Z,1630039177.487 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-08-27T04:39:37.497Z,1630039177.497 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-08-27T04:39:37.527Z,1630039177.527 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2021-08-27T04:39:37.601Z,1630039177.601 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2021-08-27T04:39:37.682Z,1630039177.682 [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="profile_station">

    <Description>
        This mission yoyos in a circle around a specified location.
    </Description>

<!-- Mission-wide settings -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>4</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. -->

    <DefineArg Name="Lat"><Description>
        The latitude of the center of the circle.
    </Description><Units:degree/><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Lon"><Description>
        The longitude of the center of the circle.
    </Description><Units:degree/><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius to circle at
    </Description><Units:meter/><Value>300</Value></DefineArg>

<!-- 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>20</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

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

    <DefineArg Name="CircleMaxError"><Description>
        If this distance away from the circle, drive straight towards (or away
        from the center). Otherwise, try to reduce distance from the ideal
        circle.
    </Description><Units:meter/><Value>100</Value></DefineArg>

    <DefineArg Name="CircleTurnToPort"><Description>
        If true, vehicle turns to the left around the center point. If false,
        vehicle turns to the right.
    </Description><False/></DefineArg>

    <DefineArg Name="KwpHeading"><Description>
        Used to relax waypoint cross-track error constant that is adjusted for
        docking. (You can override this setting by passing an argument.)
    </Description><Units:radian_per_meter/><Value>0.010</Value></DefineArg>

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="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:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

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

        <Sequence Repeat="4096"/>

        <Assign><Parallel/><Control:HorizontalControl.kwpHeading/><Arg Name="KwpHeading"/></Assign>

        <Guidance:Circle>
            <Sequence/>
            <Setting><Guidance:Circle.latitude/><Arg Name="Lat"/></Setting>
            <Setting><Guidance:Circle.longitude/><Arg Name="Lon"/></Setting>
            <Setting><Guidance:Circle.radius/><Arg Name="Radius"/></Setting>
            <Setting><Guidance:Circle.maxError/><Arg Name="CircleMaxError"/></Setting>
            <Setting><Guidance:Circle.turnToPort/><Arg Name="CircleTurnToPort"/></Setting>
        </Guidance:Circle>

    </Aggregate>

</Mission>


2021-08-27T04:39:37.683Z,1630039177.683 [CommandExec](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2021-08-27T04:39:44.929Z,1630039184.929 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:39:47.323Z,1630039187.323 [DataOverHttps](INFO): Received command: sched "stop;schedule resume"
2021-08-27T04:39:51.995Z,1630039191.995 [CommandExec](IMPORTANT): got command set profile_station.MissionTimeout 8.000000 hour
2021-08-27T04:39:51.996Z,1630039191.996 [CommandExec](IMPORTANT): got command set profile_station.NeedCommsTime 60.000000 minute
2021-08-27T04:39:51.997Z,1630039191.997 [CommandExec](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2021-08-27T04:39:51.997Z,1630039191.997 [CommandExec](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2021-08-27T04:39:51.998Z,1630039191.998 [CommandExec](IMPORTANT): got command set profile_station.YoYoMaxDepth 20.000000 meter
2021-08-27T04:39:52.001Z,1630039192.001 [CommandExec](IMPORTANT): got command schedule "stop;schedule resume"
2021-08-27T04:39:52.001Z,1630039192.001 [CommandExec](IMPORTANT): Scheduled #4: "stop;schedule resume"
2021-08-27T04:39:52.004Z,1630039192.004 [CommandExec](IMPORTANT): got command stop
2021-08-27T04:39:52.007Z,1630039192.007 [CommandExec](IMPORTANT): Scheduling is paused for 2 commands
2021-08-27T04:39:52.007Z,1630039192.007 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2021-08-27T04:39:52.007Z,1630039192.007 [CommandExec](IMPORTANT): got command schedule resume
2021-08-27T04:39:52.007Z,1630039192.007 [CommandExec](IMPORTANT): Scheduling is resumed
2021-08-27T04:39:53.920Z,1630039193.920 [CommandExec](IMPORTANT): got command set profile_station.MaxDepth 27.000000 meter
2021-08-27T04:39:53.920Z,1630039193.920 [CommandExec](IMPORTANT): got command run
2021-08-27T04:39:53.930Z,1630039193.930 [CommandExec](IMPORTANT): Running 
2021-08-27T04:39:54.159Z,1630039194.159 [Default] Stopped
2021-08-27T04:39:54.159Z,1630039194.159 [Default](DEBUG): Aggregate::uninitialize Default
2021-08-27T04:39:54.159Z,1630039194.159 [Default:B.GoToSurface] Stopped
2021-08-27T04:39:54.160Z,1630039194.160 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-08-27T04:39:54.160Z,1630039194.160 [Default:CheckIn] Stopped
2021-08-27T04:39:54.160Z,1630039194.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-08-27T04:39:54.160Z,1630039194.160 [Default:CheckIn:Read_GPS] Stopped
2021-08-27T04:39:54.160Z,1630039194.160 [MissionManager](IMPORTANT): Started mission profile_station
2021-08-27T04:39:54.160Z,1630039194.160 [profile_station] Running Loop=1
2021-08-27T04:39:54.160Z,1630039194.160 [profile_station](DEBUG): Aggregate::initialize profile_station
2021-08-27T04:39:54.160Z,1630039194.160 [profile_station:Science] Running Loop=1
2021-08-27T04:39:54.160Z,1630039194.160 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:A] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:B] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:C] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:D] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:E] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:F] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:Read_Oil] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:PeakDetectChl] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-08-27T04:39:54.161Z,1630039194.161 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-08-27T04:39:54.168Z,1630039194.168 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2021-08-27T04:39:54.168Z,1630039194.168 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2021-08-27T04:39:54.168Z,1630039194.168 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-08-27T04:39:54.168Z,1630039194.168 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-08-27T04:39:54.169Z,1630039194.169 [profile_station:Science:PeakDetectNO3] Running Loop=1
2021-08-27T04:39:54.169Z,1630039194.169 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2021-08-27T04:39:54.169Z,1630039194.169 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-08-27T04:39:54.169Z,1630039194.169 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-08-27T04:39:54.195Z,1630039194.195 [profile_station:Science:PeakDetectOil] Running Loop=1
2021-08-27T04:39:54.195Z,1630039194.195 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2021-08-27T04:39:54.195Z,1630039194.195 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-08-27T04:39:54.196Z,1630039194.196 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-08-27T04:39:54.197Z,1630039194.197 [profile_station:Science:HighestOilPeakReport] Running Loop=1
2021-08-27T04:39:54.198Z,1630039194.198 [profile_station:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestOilPeakReport
2021-08-27T04:39:54.198Z,1630039194.198 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-08-27T04:39:54.198Z,1630039194.198 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-08-27T04:39:54.198Z,1630039194.198 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2021-08-27T04:39:54.198Z,1630039194.198 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2021-08-27T04:39:54.199Z,1630039194.199 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-08-27T04:39:54.199Z,1630039194.199 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-08-27T04:39:54.209Z,1630039194.209 [profile_station:Science:PeakDetectSalinity] Running Loop=1
2021-08-27T04:39:54.209Z,1630039194.209 [profile_station:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectSalinity
2021-08-27T04:39:54.209Z,1630039194.209 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-08-27T04:39:54.209Z,1630039194.209 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-08-27T04:39:54.223Z,1630039194.223 [profile_station:Science:HighestSaltPeakReport] Running Loop=1
2021-08-27T04:39:54.223Z,1630039194.223 [profile_station:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestSaltPeakReport
2021-08-27T04:39:54.223Z,1630039194.223 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-08-27T04:39:54.223Z,1630039194.223 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-08-27T04:39:54.224Z,1630039194.224 [profile_station:Science:OceanCurrent] Running Loop=1
2021-08-27T04:39:54.224Z,1630039194.224 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::initialize profile_station:Science:OceanCurrent
2021-08-27T04:39:54.224Z,1630039194.224 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2021-08-27T04:39:54.224Z,1630039194.224 [profile_station:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-08-27T04:39:54.224Z,1630039194.224 [profile_station:StandardEnvelopes] Running Loop=1
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:I.Pitch] Running Loop=1
2021-08-27T04:39:54.225Z,1630039194.225 [profile_station:I.Pitch](DEBUG): Initialize.
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:J.Buoyancy] Running Loop=1
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:K.SetSpeed] Running Loop=1
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:K.SetSpeed](DEBUG): Initialize.
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:L.DepthEnvelope] Running Loop=1
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:M.AltitudeEnvelope] Running Loop=1
2021-08-27T04:39:54.226Z,1630039194.226 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-08-27T04:39:54.235Z,1630039194.235 [profile_station:N.YoYo] Running Loop=1
2021-08-27T04:39:54.235Z,1630039194.235 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2021-08-27T04:39:54.236Z,1630039194.236 [profile_station:C] Running Loop=1
2021-08-27T04:39:54.236Z,1630039194.236 [profile_station:N.YoYo] Running Loop=1
2021-08-27T04:39:54.237Z,1630039194.237 [profile_station:M.AltitudeEnvelope] Running Loop=1
2021-08-27T04:39:54.237Z,1630039194.237 [profile_station:L.DepthEnvelope] Running Loop=1
2021-08-27T04:39:54.247Z,1630039194.247 [profile_station:K.SetSpeed] Running Loop=1
2021-08-27T04:39:54.247Z,1630039194.247 [profile_station:J.Buoyancy] Running Loop=1
2021-08-27T04:39:54.248Z,1630039194.248 [profile_station:I.Pitch] Running Loop=1
2021-08-27T04:39:54.248Z,1630039194.248 [profile_station:StandardEnvelopes] Running Loop=1
2021-08-27T04:39:54.248Z,1630039194.248 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-08-27T04:39:54.248Z,1630039194.248 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](ERROR): Location Measurement is not Active.
2021-08-27T04:39:54.249Z,1630039194.249 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-08-27T04:39:54.249Z,1630039194.249 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-08-27T04:39:54.249Z,1630039194.249 [profile_station:C] Stopped
2021-08-27T04:39:54.250Z,1630039194.250 [profile_station:E] Running Loop=1
2021-08-27T04:39:54.250Z,1630039194.250 [profile_station:NeedComms] Running Loop=1
2021-08-27T04:39:54.250Z,1630039194.250 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2021-08-27T04:39:54.250Z,1630039194.250 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2021-08-27T04:39:54.250Z,1630039194.250 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-08-27T04:39:54.250Z,1630039194.250 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-08-27T04:39:54.251Z,1630039194.251 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-08-27T04:39:54.260Z,1630039194.260 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-08-27T04:39:54.260Z,1630039194.260 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-08-27T04:39:54.261Z,1630039194.261 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-08-27T04:39:54.261Z,1630039194.261 [profile_station:NeedComms:A] Running Loop=1
2021-08-27T04:39:54.262Z,1630039194.262 [profile_station:NeedComms:A](ERROR): data element is not active.
2021-08-27T04:39:54.262Z,1630039194.262 [profile_station:NeedComms:A](INFO): last time_fix was: nan second since 1970/01/01T00:00:00Z 
2021-08-27T04:39:54.262Z,1630039194.262 [profile_station:NeedComms:A] Stopped
2021-08-27T04:39:54.262Z,1630039194.262 [profile_station:Science] Running Loop=1
2021-08-27T04:39:54.272Z,1630039194.272 [profile_station:Science:HighestSaltPeakReport] Stopped
2021-08-27T04:39:54.272Z,1630039194.272 [profile_station:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestSaltPeakReport
2021-08-27T04:39:54.272Z,1630039194.272 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-08-27T04:39:54.272Z,1630039194.272 [profile_station:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-08-27T04:39:54.272Z,1630039194.272 [profile_station:Science:PeakDetectSalinity] Stopped
2021-08-27T04:39:54.272Z,1630039194.272 [profile_station:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectSalinity
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectFDOM] Stopped
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:HighestOilPeakReport] Stopped
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestOilPeakReport
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectOil] Stopped
2021-08-27T04:39:54.273Z,1630039194.273 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectNO3] Stopped
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:HighestChlPeakReport] Stopped
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-08-27T04:39:54.274Z,1630039194.274 [profile_station:Science:PeakDetectChl] Running Loop=1
2021-08-27T04:39:54.275Z,1630039194.275 [profile_station:Science:OceanCurrent:A.] Running Loop=1
2021-08-27T04:39:54.283Z,1630039194.283 [profile_station:Science:Read_Oil] Stopped
2021-08-27T04:39:54.283Z,1630039194.283 [profile_station:Science:F] Stopped
2021-08-27T04:39:54.283Z,1630039194.283 [profile_station:Science:E] Running Loop=1
2021-08-27T04:39:54.284Z,1630039194.284 [profile_station:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-08-27T04:39:54.284Z,1630039194.284 [profile_station:Science:D] Stopped
2021-08-27T04:39:54.284Z,1630039194.284 [profile_station:Science:C] Running Loop=1
2021-08-27T04:39:54.285Z,1630039194.285 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-08-27T04:39:54.286Z,1630039194.286 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-08-27T04:39:54.286Z,1630039194.286 [profile_station:Science:B] Running Loop=1
2021-08-27T04:39:54.286Z,1630039194.286 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-08-27T04:39:54.292Z,1630039194.292 [profile_station:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-08-27T04:39:54.292Z,1630039194.292 [profile_station:Science:A] Stopped
2021-08-27T04:39:54.292Z,1630039194.292 [MissionManager](INFO): MissionManager is completed.
2021-08-27T04:39:54.293Z,1630039194.293 [MissionManager](INFO): Uninitializing Mission profile_station
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:B] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:C] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:E] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:OceanCurrent] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:OceanCurrent:A.] Stopped
2021-08-27T04:39:54.293Z,1630039194.293 [profile_station:Science:PeakDetectChl] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:NeedComms] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:NeedComms:B.GoToSurface] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:StandardEnvelopes] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-08-27T04:39:54.294Z,1630039194.294 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-08-27T04:39:54.295Z,1630039194.295 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-08-27T04:39:54.295Z,1630039194.295 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:E] Stopped
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:I.Pitch] Stopped
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:J.Buoyancy] Stopped
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:K.SetSpeed] Stopped
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2021-08-27T04:39:54.307Z,1630039194.307 [profile_station:L.DepthEnvelope] Stopped
2021-08-27T04:39:54.308Z,1630039194.308 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2021-08-27T04:39:54.308Z,1630039194.308 [profile_station:M.AltitudeEnvelope] Stopped
2021-08-27T04:39:54.308Z,1630039194.308 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-08-27T04:39:54.308Z,1630039194.308 [profile_station:N.YoYo] Stopped
2021-08-27T04:39:54.308Z,1630039194.308 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2021-08-27T04:39:54.566Z,1630039194.566 [MissionManager](IMPORTANT): Started mission Default
2021-08-27T04:39:54.566Z,1630039194.566 [Default] Running Loop=1
2021-08-27T04:39:54.566Z,1630039194.566 [Default](DEBUG): Aggregate::initialize Default
2021-08-27T04:39:54.566Z,1630039194.566 [Default:B.GoToSurface] Running Loop=1
2021-08-27T04:39:54.566Z,1630039194.566 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-08-27T04:39:54.567Z,1630039194.567 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-08-27T04:39:54.567Z,1630039194.567 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-08-27T04:39:54.568Z,1630039194.568 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-08-27T04:39:54.568Z,1630039194.568 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-08-27T04:39:54.568Z,1630039194.568 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-08-27T04:39:54.569Z,1630039194.569 [Default:A.Wait] Running Loop=1
2021-08-27T04:39:54.569Z,1630039194.569 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-08-27T04:40:07.066Z,1630039207.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-08-27T04:40:07.066Z,1630039207.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-08-27T04:40:07.077Z,1630039207.077 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-08-27T04:40:07.462Z,1630039207.462 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-08-27T04:40:07.462Z,1630039207.462 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-08-27T04:40:07.873Z,1630039207.873 [Default:A.Wait](INFO): Done Waiting.
2021-08-27T04:40:07.873Z,1630039207.873 [Default:A.Wait] Stopped
2021-08-27T04:40:07.873Z,1630039207.873 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-08-27T04:40:08.286Z,1630039208.286 [Default:CheckIn] Running Loop=1
2021-08-27T04:40:08.286Z,1630039208.286 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-08-27T04:40:08.286Z,1630039208.286 [Default:CheckIn:Read_GPS] Running Loop=1
2021-08-27T04:40:22.038Z,1630039222.038 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-08-27T04:41:06.429Z,1630039266.429 [Power24vConverter](INFO): Powering down.
2021-08-27T04:41:26.272Z,1630039286.272 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:41:47.228Z,1630039307.228 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-08-27T04:41:47.228Z,1630039307.228 [NAL9602] Data Fault, FailCount= 1
2021-08-27T04:41:47.229Z,1630039307.229 [NAL9602](ERROR): Data Fault
2021-08-27T04:41:47.273Z,1630039307.273 [CBIT](ERROR): Data Fault in component: NAL9602
2021-08-27T04:41:47.687Z,1630039307.687 [NAL9602](INFO): Powering down
2021-08-27T04:41:48.473Z,1630039308.473 [CBIT](INFO): Clearing failed state for component NAL9602
2021-08-27T04:41:48.473Z,1630039308.473 [NAL9602] No Fault, FailCount= 1
2021-08-27T04:42:16.375Z,1630039336.375 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:42:17.938Z,1630039337.938 [NAL9602](INFO): Powering up NAL9602
2021-08-27T04:42:28.849Z,1630039348.849 [NAL9602](INFO): NAL9602 initialized
2021-08-27T04:42:47.532Z,1630039367.532 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T044246
2021-08-27T04:42:55.031Z,1630039375.031 [DataOverHttps](INFO): Received command: sched "stop"
2021-08-27T04:42:55.057Z,1630039375.057 [CommandExec](IMPORTANT): got command schedule "stop"
2021-08-27T04:42:55.057Z,1630039375.057 [CommandExec](IMPORTANT): Scheduled #5: "stop"
2021-08-27T04:42:55.108Z,1630039375.108 [CommandExec](IMPORTANT): got command stop
2021-08-27T04:42:55.115Z,1630039375.115 [CommandExec](IMPORTANT): Scheduling is paused for 1 commands
2021-08-27T04:42:56.032Z,1630039376.032 [DataOverHttps](IMPORTANT): SBD MTMSN=20210827T044255
2021-08-27T04:43:03.547Z,1630039383.547 [DataOverHttps](INFO): Received command: stop
2021-08-27T04:43:03.568Z,1630039383.568 [CommandExec](IMPORTANT): got command stop
2021-08-27T04:43:08.054Z,1630039388.054 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-08-27T04:43:08.054Z,1630039388.054 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-08-27T04:43:08.065Z,1630039388.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-08-27T04:43:08.469Z,1630039388.469 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-08-27T04:43:08.469Z,1630039388.469 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-08-27T04:43:29.908Z,1630039409.908 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:43:40.060Z,1630039420.060 [CommandExec](IMPORTANT): got command failComponent
2021-08-27T04:43:40.060Z,1630039420.060 [CommandExec](IMPORTANT): Failed components:
2021-08-27T04:43:40.060Z,1630039420.060 [CommandExec](IMPORTANT): No failed Components.
2021-08-27T04:44:21.616Z,1630039461.616 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-08-27T04:44:33.392Z,1630039473.392 [CommandExec](IMPORTANT): got command quit
2021-08-27T04:44:34.395Z,1630039474.395 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:34.395Z,1630039474.395 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:34.463Z,1630039474.463 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-08-27T04:44:34.463Z,1630039474.463 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-08-27T04:44:34.463Z,1630039474.463 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:34.464Z,1630039474.464 [NavChartDb](INFO): Join timeout helper Thread ID is 5054
2021-08-27T04:44:34.587Z,1630039474.587 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:34.587Z,1630039474.587 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:34.607Z,1630039474.607 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-08-27T04:44:34.607Z,1630039474.607 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:34.608Z,1630039474.608 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5055
2021-08-27T04:44:34.767Z,1630039474.767 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:34.767Z,1630039474.767 [WetLabsBB2FL](INFO): Powering down
2021-08-27T04:44:34.768Z,1630039474.768 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:34.771Z,1630039474.771 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-08-27T04:44:34.771Z,1630039474.771 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:34.772Z,1630039474.772 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5056
2021-08-27T04:44:35.375Z,1630039475.375 [CTD_Seabird](INFO): Powering down
2021-08-27T04:44:35.387Z,1630039475.387 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:35.387Z,1630039475.387 [CTD_Seabird](INFO): Powering down
2021-08-27T04:44:35.399Z,1630039475.399 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:35.415Z,1630039475.415 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-08-27T04:44:35.415Z,1630039475.415 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:35.416Z,1630039475.416 [Radio_Surface](INFO): Join timeout helper Thread ID is 5057
2021-08-27T04:44:35.511Z,1630039475.511 [Radio_Surface](INFO): Powering down
2021-08-27T04:44:35.512Z,1630039475.512 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:35.512Z,1630039475.512 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:35.523Z,1630039475.523 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-08-27T04:44:35.523Z,1630039475.523 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:35.523Z,1630039475.523 [Onboard](INFO): Join timeout helper Thread ID is 5058
2021-08-27T04:44:35.687Z,1630039475.687 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:35.687Z,1630039475.687 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:35.704Z,1630039475.704 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-08-27T04:44:35.704Z,1630039475.704 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:35.704Z,1630039475.704 [DataOverHttps](INFO): Join timeout helper Thread ID is 5059
2021-08-27T04:44:36.055Z,1630039476.055 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:36.059Z,1630039476.059 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.064Z,1630039476.064 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-08-27T04:44:36.064Z,1630039476.064 [logger ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.064Z,1630039476.064 [logger](INFO): Join timeout helper Thread ID is 5060
2021-08-27T04:44:36.075Z,1630039476.075 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:36.076Z,1630039476.076 [logger ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.084Z,1630039476.084 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-08-27T04:44:36.084Z,1630039476.084 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.084Z,1630039476.084 [CommandLine](INFO): Join timeout helper Thread ID is 5061
2021-08-27T04:44:36.175Z,1630039476.175 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:36.176Z,1630039476.176 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.184Z,1630039476.184 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-08-27T04:44:36.184Z,1630039476.184 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.184Z,1630039476.184 [CommandExec](INFO): Join timeout helper Thread ID is 5062
2021-08-27T04:44:36.192Z,1630039476.192 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-08-27T04:44:36.192Z,1630039476.192 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.192Z,1630039476.192 [controlThread](INFO): Join timeout helper Thread ID is 5063
2021-08-27T04:44:36.491Z,1630039476.491 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-08-27T04:44:36.491Z,1630039476.491 [controlThread](DEBUG): Uninitializing ControlThread
2021-08-27T04:44:36.492Z,1630039476.492 [AHRS_M2](INFO): Powering down
2021-08-27T04:44:36.564Z,1630039476.564 [NAL9602](INFO): Powering down
2021-08-27T04:44:36.567Z,1630039476.567 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-08-27T04:44:36.568Z,1630039476.568 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-08-27T04:44:36.568Z,1630039476.568 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-08-27T04:44:36.569Z,1630039476.569 [MissionManager](INFO): Uninitializing Mission Default
2021-08-27T04:44:36.569Z,1630039476.569 [Default] Stopped
2021-08-27T04:44:36.569Z,1630039476.569 [Default](DEBUG): Aggregate::uninitialize Default
2021-08-27T04:44:36.569Z,1630039476.569 [Default:B.GoToSurface] Stopped
2021-08-27T04:44:36.569Z,1630039476.569 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-08-27T04:44:36.569Z,1630039476.569 [Default:CheckIn] Stopped
2021-08-27T04:44:36.569Z,1630039476.569 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-08-27T04:44:36.569Z,1630039476.569 [Default:CheckIn:Read_GPS] Stopped
2021-08-27T04:44:36.572Z,1630039476.572 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-08-27T04:44:36.573Z,1630039476.573 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-08-27T04:44:36.573Z,1630039476.573 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-08-27T04:44:36.573Z,1630039476.573 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-08-27T04:44:36.574Z,1630039476.574 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-08-27T04:44:36.574Z,1630039476.574 [BuoyancyServo](INFO): Powering down
2021-08-27T04:44:36.587Z,1630039476.587 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-08-27T04:44:36.587Z,1630039476.587 [ElevatorServo](INFO): Powering down
2021-08-27T04:44:36.588Z,1630039476.588 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-08-27T04:44:36.588Z,1630039476.588 [MassServo](INFO): Powering down
2021-08-27T04:44:36.589Z,1630039476.589 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-08-27T04:44:36.589Z,1630039476.589 [RudderServo](INFO): Powering down
2021-08-27T04:44:36.590Z,1630039476.590 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-08-27T04:44:36.590Z,1630039476.590 [ThrusterServo](INFO): Powering down
2021-08-27T04:44:36.591Z,1630039476.591 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-08-27T04:44:36.591Z,1630039476.591 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-08-27T04:44:36.592Z,1630039476.592 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-08-27T04:44:36.592Z,1630039476.592 [CBIT](DEBUG): Powering off loads.
2021-08-27T04:44:36.603Z,1630039476.603 [CBIT](DEBUG): Disabling WDT.
2021-08-27T04:44:36.615Z,1630039476.615 [CBIT](DEBUG): Opening all GF detection circuits.
2021-08-27T04:44:36.616Z,1630039476.616 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.666Z,1630039476.666 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.669Z,1630039476.669 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.674Z,1630039476.674 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.743Z,1630039476.743 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.747Z,1630039476.747 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.786Z,1630039476.786 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-08-27T04:44:36.848Z,1630039476.848 [logger ThreadHandler](INFO): Thread cancelled.