2022-05-11T04:20:50.494Z,1652242850.494 [Supervisor](DEBUG): Initializing supervisor.
2022-05-11T04:20:50.498Z,1652242850.498 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-05-11T04:20:50.499Z,1652242850.499 [SyncHandler](INFO): Protected caller Thread ID is 827
2022-05-11T04:20:50.499Z,1652242850.499 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-05-11T04:20:50.500Z,1652242850.500 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-05-11T04:20:50.500Z,1652242850.500 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 828
2022-05-11T04:20:50.504Z,1652242850.504 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-05-11T04:20:50.522Z,1652242850.522 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-05-11T04:20:50.523Z,1652242850.523 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-05-11T04:20:50.524Z,1652242850.524 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 829
2022-05-11T04:20:50.528Z,1652242850.528 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-05-11T04:20:50.529Z,1652242850.529 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-05-11T04:20:50.529Z,1652242850.529 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 830
2022-05-11T04:20:50.531Z,1652242850.531 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-05-11T04:20:50.532Z,1652242850.532 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-05-11T04:20:50.532Z,1652242850.532 [logger ThreadHandler](INFO): Protected caller Thread ID is 831
2022-05-11T04:20:50.536Z,1652242850.536 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-05-11T04:20:50.536Z,1652242850.536 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-05-11T04:20:50.540Z,1652242850.540 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-05-11T04:20:50.853Z,1652242850.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-05-11T04:20:50.854Z,1652242850.854 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-05-11T04:20:51.494Z,1652242851.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-05-11T04:20:51.495Z,1652242851.495 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-05-11T04:20:51.602Z,1652242851.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-05-11T04:20:51.604Z,1652242851.604 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-05-11T04:20:52.151Z,1652242852.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-05-11T04:20:52.152Z,1652242852.152 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-05-11T04:20:52.248Z,1652242852.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-05-11T04:20:52.250Z,1652242852.250 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-05-11T04:20:52.332Z,1652242852.332 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-05-11T04:20:52.697Z,1652242852.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-05-11T04:20:52.698Z,1652242852.698 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-05-11T04:20:52.915Z,1652242852.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-05-11T04:20:52.917Z,1652242852.917 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-05-11T04:20:53.042Z,1652242853.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-05-11T04:20:53.043Z,1652242853.043 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-05-11T04:20:53.266Z,1652242853.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-05-11T04:20:53.268Z,1652242853.268 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-05-11T04:20:53.740Z,1652242853.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-05-11T04:20:54.254Z,1652242854.254 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-05-11T04:20:54.891Z,1652242854.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-05-11T04:20:54.893Z,1652242854.893 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-05-11T04:20:54.973Z,1652242854.973 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-05-11T04:20:55.188Z,1652242855.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-05-11T04:20:55.189Z,1652242855.189 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-05-11T04:20:55.427Z,1652242855.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-05-11T04:20:55.428Z,1652242855.428 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-05-11T04:20:55.718Z,1652242855.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-05-11T04:20:55.720Z,1652242855.720 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2022-05-11T04:20:55.724Z,1652242855.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2022-05-11T04:20:55.831Z,1652242855.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2022-05-11T04:20:55.978Z,1652242855.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2022-05-11T04:20:56.064Z,1652242856.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2022-05-11T04:20:56.147Z,1652242856.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2022-05-11T04:20:56.260Z,1652242856.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2022-05-11T04:20:56.434Z,1652242856.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2022-05-11T04:20:56.694Z,1652242856.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-05-11T04:20:56.695Z,1652242856.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2022-05-11T04:20:56.825Z,1652242856.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2022-05-11T04:20:56.919Z,1652242856.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2022-05-11T04:20:57.022Z,1652242857.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2022-05-11T04:20:57.121Z,1652242857.121 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-05-11T04:20:57.133Z,1652242857.133 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-05-11T04:20:57.254Z,1652242857.254 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-05-11T04:20:57.255Z,1652242857.255 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-05-11T04:20:57.270Z,1652242857.270 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-05-11T04:20:57.271Z,1652242857.271 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-05-11T04:20:57.342Z,1652242857.342 [DepthRateCalculator] Loaded
2022-05-11T04:20:57.342Z,1652242857.342 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-05-11T04:20:57.348Z,1652242857.348 [PitchRateCalculator] Loaded
2022-05-11T04:20:57.348Z,1652242857.348 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-05-11T04:20:57.358Z,1652242857.358 [SpeedCalculator] Loaded
2022-05-11T04:20:57.358Z,1652242857.358 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-05-11T04:20:57.363Z,1652242857.363 [YawRateCalculator] Loaded
2022-05-11T04:20:57.363Z,1652242857.363 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-05-11T04:20:57.384Z,1652242857.384 [ElevatorOffsetCalculator] Loaded
2022-05-11T04:20:57.384Z,1652242857.384 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-05-11T04:20:57.385Z,1652242857.385 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-05-11T04:20:57.385Z,1652242857.385 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-05-11T04:20:57.453Z,1652242857.453 [VerticalControl](DEBUG): Construct VerticalControl.
2022-05-11T04:20:57.508Z,1652242857.508 [VerticalControl] Loaded
2022-05-11T04:20:57.509Z,1652242857.509 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-05-11T04:20:57.511Z,1652242857.511 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-05-11T04:20:57.550Z,1652242857.550 [HorizontalControl] Loaded
2022-05-11T04:20:57.550Z,1652242857.550 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-05-11T04:20:57.553Z,1652242857.553 [SpeedControl](DEBUG): Construct SpeedControl.
2022-05-11T04:20:57.556Z,1652242857.556 [SpeedControl] Loaded
2022-05-11T04:20:57.556Z,1652242857.556 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-05-11T04:20:57.558Z,1652242857.558 [LoopControl](DEBUG): Construct LoopControl.
2022-05-11T04:20:57.559Z,1652242857.559 [LoopControl] Loaded
2022-05-11T04:20:57.559Z,1652242857.559 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-05-11T04:20:57.559Z,1652242857.559 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-05-11T04:20:57.560Z,1652242857.560 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-05-11T04:20:57.694Z,1652242857.694 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-05-11T04:20:57.694Z,1652242857.694 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-05-11T04:20:57.707Z,1652242857.707 [NavChart] Loaded
2022-05-11T04:20:57.708Z,1652242857.708 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-05-11T04:20:57.713Z,1652242857.713 [UniversalFixResidualReporter] Loaded
2022-05-11T04:20:57.714Z,1652242857.714 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-05-11T04:20:57.714Z,1652242857.714 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-05-11T04:20:57.715Z,1652242857.715 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-05-11T04:20:57.854Z,1652242857.854 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-05-11T04:20:57.854Z,1652242857.854 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-05-11T04:20:58.636Z,1652242858.636 [AHRS_M2] Loaded
2022-05-11T04:20:58.636Z,1652242858.636 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-05-11T04:20:58.884Z,1652242858.884 [BackseatComponent] Loaded
2022-05-11T04:20:58.885Z,1652242858.885 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-05-11T04:20:58.886Z,1652242858.886 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0
2022-05-11T04:20:58.886Z,1652242858.886 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 914
2022-05-11T04:20:58.889Z,1652242858.889 [LcmUniversalReporter] Loaded
2022-05-11T04:20:58.889Z,1652242858.889 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-05-11T04:20:59.030Z,1652242859.030 [DataOverHttps] Loaded
2022-05-11T04:20:59.030Z,1652242859.030 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-05-11T04:20:59.031Z,1652242859.031 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2022-05-11T04:20:59.032Z,1652242859.032 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 915
2022-05-11T04:20:59.051Z,1652242859.051 [Depth_Keller] Loaded
2022-05-11T04:20:59.051Z,1652242859.051 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-05-11T04:20:59.056Z,1652242859.056 [DropWeight] Loaded
2022-05-11T04:20:59.056Z,1652242859.056 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-05-11T04:20:59.092Z,1652242859.092 [DVL_micro] Loaded
2022-05-11T04:20:59.092Z,1652242859.092 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-05-11T04:20:59.152Z,1652242859.152 [NAL9602] Loaded
2022-05-11T04:20:59.159Z,1652242859.159 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-05-11T04:20:59.220Z,1652242859.220 [Onboard] Loaded
2022-05-11T04:20:59.220Z,1652242859.220 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-05-11T04:20:59.221Z,1652242859.221 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2022-05-11T04:20:59.221Z,1652242859.221 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 916
2022-05-11T04:20:59.252Z,1652242859.252 [Power24vConverter] Loaded
2022-05-11T04:20:59.252Z,1652242859.252 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-05-11T04:20:59.315Z,1652242859.315 [Radio_Surface] Loaded
2022-05-11T04:20:59.316Z,1652242859.316 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-05-11T04:20:59.320Z,1652242859.320 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2022-05-11T04:20:59.320Z,1652242859.320 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 917
2022-05-11T04:20:59.553Z,1652242859.553 [DAT] Loaded
2022-05-11T04:20:59.553Z,1652242859.553 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-05-11T04:20:59.554Z,1652242859.554 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0
2022-05-11T04:20:59.554Z,1652242859.554 [DAT ThreadHandler](INFO): Protected caller Thread ID is 918
2022-05-11T04:20:59.555Z,1652242859.555 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-05-11T04:20:59.555Z,1652242859.555 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-05-11T04:20:59.953Z,1652242859.953 [BuoyancyServo] Loaded
2022-05-11T04:20:59.953Z,1652242859.953 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-05-11T04:20:59.973Z,1652242859.973 [ElevatorServo] Loaded
2022-05-11T04:20:59.973Z,1652242859.973 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-05-11T04:20:59.992Z,1652242859.992 [MassServo] Loaded
2022-05-11T04:20:59.992Z,1652242859.992 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-05-11T04:21:00.010Z,1652242860.010 [RudderServo] Loaded
2022-05-11T04:21:00.010Z,1652242860.010 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-05-11T04:21:00.025Z,1652242860.025 [ThrusterHE] Loaded
2022-05-11T04:21:00.025Z,1652242860.025 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-05-11T04:21:00.026Z,1652242860.026 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-05-11T04:21:00.026Z,1652242860.026 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-05-11T04:21:00.109Z,1652242860.109 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-05-11T04:21:00.109Z,1652242860.109 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-05-11T04:21:00.152Z,1652242860.152 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-05-11T04:21:00.152Z,1652242860.152 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-05-11T04:21:00.508Z,1652242860.508 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-05-11T04:21:00.509Z,1652242860.509 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-05-11T04:21:00.722Z,1652242860.722 [CTD_Seabird] Loaded
2022-05-11T04:21:00.722Z,1652242860.722 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-05-11T04:21:00.723Z,1652242860.723 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B394E0
2022-05-11T04:21:00.724Z,1652242860.724 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 919
2022-05-11T04:21:00.755Z,1652242860.755 [ESPComponent] Loaded
2022-05-11T04:21:00.755Z,1652242860.755 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-05-11T04:21:00.775Z,1652242860.775 [PAR_Licor] Loaded
2022-05-11T04:21:00.775Z,1652242860.775 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-05-11T04:21:00.806Z,1652242860.806 [WetLabsBB2FL] Loaded
2022-05-11T04:21:00.806Z,1652242860.806 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-05-11T04:21:00.807Z,1652242860.807 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B694E0
2022-05-11T04:21:00.808Z,1652242860.808 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 920
2022-05-11T04:21:00.808Z,1652242860.808 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-05-11T04:21:00.809Z,1652242860.809 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-05-11T04:21:00.975Z,1652242860.975 [SBIT](DEBUG): Construct Startup Built In Test.
2022-05-11T04:21:00.984Z,1652242860.984 [SBIT] Loaded
2022-05-11T04:21:00.985Z,1652242860.985 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-05-11T04:21:00.987Z,1652242860.987 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-05-11T04:21:01.001Z,1652242861.001 [IBIT] Loaded
2022-05-11T04:21:01.001Z,1652242861.001 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-05-11T04:21:01.007Z,1652242861.007 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-05-11T04:21:01.234Z,1652242861.234 [CBIT] Loaded
2022-05-11T04:21:01.234Z,1652242861.234 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-05-11T04:21:01.234Z,1652242861.234 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-05-11T04:21:01.240Z,1652242861.240 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-05-11T04:21:01.243Z,1652242861.243 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-05-11T04:21:01.254Z,1652242861.254 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-05-11T04:21:01.255Z,1652242861.255 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2022-05-11T04:21:01.255Z,1652242861.255 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 921
2022-05-11T04:21:01.259Z,1652242861.259 [Supervisor](INFO): Main Thread ID is 822
2022-05-11T04:21:01.259Z,1652242861.259 [Supervisor](DEBUG): Running supervisor.
2022-05-11T04:21:01.260Z,1652242861.260 [CommandExec ThreadHandler](INFO): Handler Thread ID is 922
2022-05-11T04:21:01.261Z,1652242861.261 [CommandExec](INFO): Initializing the command executive.
2022-05-11T04:21:01.262Z,1652242861.262 [CommandLine ThreadHandler](INFO): Handler Thread ID is 923
2022-05-11T04:21:01.264Z,1652242861.264 [controlThread ThreadHandler](INFO): Handler Thread ID is 924
2022-05-11T04:21:01.264Z,1652242861.264 [controlThread](DEBUG): Initializing ControlThread
2022-05-11T04:21:01.265Z,1652242861.265 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-05-11T04:21:01.266Z,1652242861.266 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-05-11T04:21:01.266Z,1652242861.266 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-05-11T04:21:01.266Z,1652242861.266 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-05-11T04:21:01.267Z,1652242861.267 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-05-11T04:21:01.267Z,1652242861.267 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-05-11T04:21:01.269Z,1652242861.269 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-05-11T04:21:01.270Z,1652242861.270 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-05-11T04:21:01.270Z,1652242861.270 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-05-11T04:21:01.271Z,1652242861.271 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-05-11T04:21:01.271Z,1652242861.271 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-05-11T04:21:01.277Z,1652242861.277 [SBIT](INFO): Initialize SBIT Component.
2022-05-11T04:21:01.278Z,1652242861.278 [SBIT](IMPORTANT): git: 2022-05-11
2022-05-11T04:21:01.278Z,1652242861.278 [SBIT](INFO): git hash: 7696a1969a630744ed6dd937befa6455a7154426
2022-05-11T04:21:01.278Z,1652242861.278 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-05-11T04:21:01.279Z,1652242861.279 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-05-11T04:21:01.280Z,1652242861.280 [SBIT](INFO): Beginning SBIT in 26.000000 seconds.
2022-05-11T04:21:01.281Z,1652242861.281 [IBIT](INFO): Initialize IBIT Component.
2022-05-11T04:21:01.282Z,1652242861.282 [CBIT](DEBUG): Initialize CBIT Component.
2022-05-11T04:21:01.283Z,1652242861.283 [logger ThreadHandler](INFO): Handler Thread ID is 925
2022-05-11T04:21:01.293Z,1652242861.293 [CBIT](DEBUG): Initialized mux pins.
2022-05-11T04:21:01.293Z,1652242861.293 [CBIT](DEBUG): Initializing the watchdog timer.
2022-05-11T04:21:01.301Z,1652242861.301 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 926
2022-05-11T04:21:01.313Z,1652242861.313 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 927
2022-05-11T04:21:01.314Z,1652242861.314 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-05-11T04:21:01.317Z,1652242861.317 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-05-11T04:21:01.318Z,1652242861.318 [CBIT](DEBUG): Initializing heartbeat.
2022-05-11T04:21:01.325Z,1652242861.325 [Onboard ThreadHandler](INFO): Handler Thread ID is 928
2022-05-11T04:21:01.343Z,1652242861.343 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 929
2022-05-11T04:21:01.362Z,1652242861.362 [DAT ThreadHandler](INFO): Handler Thread ID is 930
2022-05-11T04:21:01.362Z,1652242861.362 [DAT](INFO): Powering up
2022-05-11T04:21:01.362Z,1652242861.362 [DAT](DEBUG): Initializing DAT.
2022-05-11T04:21:01.367Z,1652242861.367 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 932
2022-05-11T04:21:01.367Z,1652242861.367 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-11T04:21:01.374Z,1652242861.374 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 934
2022-05-11T04:21:01.378Z,1652242861.378 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 936
2022-05-11T04:21:01.379Z,1652242861.379 [WetLabsBB2FL](INFO): Powering up
2022-05-11T04:21:01.387Z,1652242861.387 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-05-11T04:21:01.387Z,1652242861.387 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-05-11T04:21:01.387Z,1652242861.387 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-05-11T04:21:01.387Z,1652242861.387 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-05-11T04:21:01.388Z,1652242861.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-05-11T04:21:01.388Z,1652242861.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-05-11T04:21:01.388Z,1652242861.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-05-11T04:21:01.388Z,1652242861.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-05-11T04:21:01.388Z,1652242861.388 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2022-05-11T04:21:01.388Z,1652242861.388 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2022-05-11T04:21:01.389Z,1652242861.389 [CBIT](DEBUG): Deactivating GF circuits.
2022-05-11T04:21:01.389Z,1652242861.389 [CBIT](DEBUG): Deactivating emergency mode.
2022-05-11T04:21:01.425Z,1652242861.425 [CBIT](DEBUG): Backplane powered.
2022-05-11T04:21:01.426Z,1652242861.426 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-05-11T04:21:01.457Z,1652242861.457 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-11T04:21:01.468Z,1652242861.468 [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>


2022-05-11T04:21:01.469Z,1652242861.469 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-05-11T04:21:01.539Z,1652242861.539 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-05-11T04:21:01.540Z,1652242861.540 [Default:A.Wait](DEBUG): Construct Wait.
2022-05-11T04:21:01.542Z,1652242861.542 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-11T04:21:01.582Z,1652242861.582 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-05-11T04:21:01.585Z,1652242861.585 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-05-11T04:21:01.610Z,1652242861.610 [Default:E.Execute](DEBUG): Construct Execute.
2022-05-11T04:21:01.634Z,1652242861.634 [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>


2022-05-11T04:21:01.638Z,1652242861.638 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-05-11T04:21:01.644Z,1652242861.644 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-11T04:21:01.746Z,1652242861.746 [Depth_Keller](ERROR): Pressure reading out of range: 1829.595459 decibar
2022-05-11T04:21:01.749Z,1652242861.749 [Radio_Surface](INFO): Powering up
2022-05-11T04:21:01.754Z,1652242861.754 [Power24vConverter](INFO): Powering up.
2022-05-11T04:21:01.763Z,1652242861.763 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-05-11T04:21:01.843Z,1652242861.843 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-05-11T04:21:01.849Z,1652242861.849 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-05-11T04:21:01.850Z,1652242861.850 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-11T04:21:01.861Z,1652242861.861 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-11T04:21:01.862Z,1652242861.862 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-11T04:21:01.873Z,1652242861.873 [MassServo](DEBUG): Initializing MassServo.
2022-05-11T04:21:01.874Z,1652242861.874 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-11T04:21:01.881Z,1652242861.881 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-11T04:21:01.882Z,1652242861.882 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-05-11T04:21:01.889Z,1652242861.889 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-05-11T04:21:02.801Z,1652242862.801 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-05-11T04:21:02.801Z,1652242862.801 [RudderServo](FAULT): Rudder failed to initialize
2022-05-11T04:21:02.801Z,1652242862.801 [RudderServo] Communications Fault, FailCount= 1
2022-05-11T04:21:02.801Z,1652242862.801 [RudderServo](ERROR): Communications Fault
2022-05-11T04:21:02.805Z,1652242862.805 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-05-11T04:21:03.030Z,1652242863.030 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-11T04:21:03.030Z,1652242863.030 [RudderServo](INFO): Powering down
2022-05-11T04:21:03.689Z,1652242863.689 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-11T04:21:03.810Z,1652242863.810 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-11T04:21:03.814Z,1652242863.814 [CBIT](INFO): Clearing failed state for component RudderServo
2022-05-11T04:21:03.814Z,1652242863.814 [RudderServo] No Fault, FailCount= 1
2022-05-11T04:21:03.989Z,1652242863.989 [WetLabsBB2FL](INFO): Powering down
2022-05-11T04:21:07.474Z,1652242867.474 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-05-11T04:21:15.731Z,1652242875.731 [DAT](INFO): commRate: 800
2022-05-11T04:21:17.797Z,1652242877.797 [DAT](INFO): entering command mode
2022-05-11T04:21:17.998Z,1652242877.998 [DAT](INFO): setting verbose to 3
2022-05-11T04:21:18.251Z,1652242878.251 [DAT](INFO): set verbose to 3
2022-05-11T04:21:18.251Z,1652242878.251 [DAT](INFO): setting DatVerbose to 27440
2022-05-11T04:21:18.503Z,1652242878.503 [DAT](INFO): set DatVerbose to 27440
2022-05-11T04:21:18.503Z,1652242878.503 [DAT](INFO): setting transmit power to 8
2022-05-11T04:21:18.755Z,1652242878.755 [DAT](INFO): set transmit power to 8
2022-05-11T04:21:18.755Z,1652242878.755 [DAT](INFO): setting local address to 8
2022-05-11T04:21:19.006Z,1652242879.006 [DAT](INFO): set local address to 8
2022-05-11T04:21:19.007Z,1652242879.007 [DAT](INFO): Setting time to: 4:21:19 And date to:5/11/2022
2022-05-11T04:21:19.259Z,1652242879.259 [DAT](INFO): Local DAT time set to Wed May 11, 2022  04:21:19  

2022-05-11T04:21:27.963Z,1652242887.963 [SBIT](IMPORTANT): Beginning Startup BIT
2022-05-11T04:21:27.972Z,1652242887.972 [CBIT](IMPORTANT): Beginning ground fault scan
2022-05-11T04:21:29.557Z,1652242889.557 [NAL9602](INFO): Powering up NAL9602
2022-05-11T04:21:38.983Z,1652242898.983 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.044270
CHAN A1 (24V): 0.025420
CHAN A2 (12V): -0.014271
CHAN A3 (5V): -0.024465
CHAN B0 (3.3V): -0.012373
CHAN B1 (3.15aV): -0.005218
CHAN B2 (3.15bV): -0.002709
CHAN B3 (GND): -0.011155
OPEN: -0.000618
Full Scale: +/- 1 mA
2022-05-11T04:21:40.455Z,1652242900.455 [NAL9602](INFO): NAL9602 initialized
2022-05-11T04:22:21.715Z,1652242941.715 [SBIT](FAULT): Mass: EXPECTED:-0.001543 ACTUAL:-0.002768
2022-05-11T04:22:21.715Z,1652242941.715 [SBIT](FAULT): Control surface position failure.
2022-05-11T04:22:22.122Z,1652242942.122 [SBIT](CRITICAL): SBIT FAILED
2022-05-11T04:22:22.122Z,1652242942.122 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-05-11T04:22:22.123Z,1652242942.123 [SBIT](IMPORTANT): Express linearApproximation CBIT.ampHoursUsed 1.000000 ampere_hour;

2022-05-11T04:22:22.123Z,1652242942.123 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.010000 practical_salinity_unit;

2022-05-11T04:22:22.123Z,1652242942.123 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-05-11T04:22:22.123Z,1652242942.123 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.200000 microgram_per_liter;

2022-05-11T04:22:22.123Z,1652242942.123 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=275.139201 cubic_centimeter;

2022-05-11T04:22:22.123Z,1652242942.123 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.542962 millimeter;

2022-05-11T04:22:22.125Z,1652242942.125 [CommandExec](FAULT): Scheduling is paused
2022-05-11T04:22:22.125Z,1652242942.125 [CBIT](INFO): Critical error at 20220511T042222
2022-05-11T04:22:22.125Z,1652242942.125 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-05-11T04:22:22.495Z,1652242942.495 [MissionManager](IMPORTANT): Started mission Startup
2022-05-11T04:22:22.495Z,1652242942.495 [Startup] Running Loop=1
2022-05-11T04:22:22.495Z,1652242942.495 [Startup](DEBUG): Aggregate::initialize Startup
2022-05-11T04:22:22.495Z,1652242942.495 [Startup:A.GoToSurface] Running Loop=1
2022-05-11T04:22:22.495Z,1652242942.495 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-11T04:22:22.496Z,1652242942.496 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-11T04:22:22.496Z,1652242942.496 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-11T04:22:22.496Z,1652242942.496 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-11T04:22:22.497Z,1652242942.497 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-11T04:22:22.497Z,1652242942.497 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-11T04:22:22.499Z,1652242942.499 [Startup:StartupSatComms] Running Loop=1
2022-05-11T04:22:22.499Z,1652242942.499 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-05-11T04:22:22.499Z,1652242942.499 [Startup:StartupSatComms:A] Running Loop=1
2022-05-11T04:22:22.930Z,1652242942.930 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-05-11T04:23:20.167Z,1652243000.167 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004622
2022-05-11T04:23:21.515Z,1652243001.515 [DVL_micro](ERROR): only read 3 of 4 data items
2022-05-11T04:23:21.515Z,1652243001.515 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+99999.99
2022-05-11T04:23:22.684Z,1652243002.684 [Startup:StartupSatComms:A](INFO): Timed out from 2022-05-11T04:22:22.5Z
2022-05-11T04:23:22.684Z,1652243002.684 [Startup:StartupSatComms:A] Stopped
2022-05-11T04:23:22.684Z,1652243002.684 [Startup:StartupSatComms:B] Running Loop=1
2022-05-11T04:23:23.105Z,1652243003.105 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-05-11T04:23:29.436Z,1652243009.436 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220511T011051/Courier0076.lzma
2022-05-11T04:23:30.439Z,1652243010.439 [DataOverHttps](INFO): Moved sent file to Logs/20220511T011051/Courier0076.lzma.bak
2022-05-11T04:23:30.439Z,1652243010.439 [DataOverHttps](INFO): SBD MOMSN=16735840
2022-05-11T04:23:46.145Z,1652243026.145 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20220511T042050/Courier0000.lzma
2022-05-11T04:23:47.147Z,1652243027.147 [DataOverHttps](INFO): Moved sent file to Logs/20220511T042050/Courier0000.lzma.bak
2022-05-11T04:23:47.147Z,1652243027.147 [DataOverHttps](INFO): SBD MOMSN=16735842
2022-05-11T04:24:01.497Z,1652243041.497 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-05-11T04:24:01.497Z,1652243041.497 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-05-11T04:24:01.554Z,1652243041.554 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-05-11T04:24:01.899Z,1652243041.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-05-11T04:24:01.900Z,1652243041.900 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-05-11T04:24:02.924Z,1652243042.924 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220511T011051/Express0077.lzma
2022-05-11T04:24:03.927Z,1652243043.927 [DataOverHttps](INFO): Moved sent file to Logs/20220511T011051/Express0077.lzma.bak
2022-05-11T04:24:03.927Z,1652243043.927 [DataOverHttps](INFO): SBD MOMSN=16735846
2022-05-11T04:24:19.586Z,1652243059.586 [DataOverHttps](INFO): Sending 942 bytes from file Logs/20220511T042050/Express0001.lzma
2022-05-11T04:24:20.587Z,1652243060.587 [DataOverHttps](INFO): Moved sent file to Logs/20220511T042050/Express0001.lzma.bak
2022-05-11T04:24:20.587Z,1652243060.587 [DataOverHttps](INFO): SBD MOMSN=16735848
2022-05-11T04:24:21.696Z,1652243061.696 [Startup:StartupSatComms:B] Stopped
2022-05-11T04:24:21.696Z,1652243061.696 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-05-11T04:24:21.696Z,1652243061.696 [Startup:StartupSatComms] Stopped
2022-05-11T04:24:21.696Z,1652243061.696 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-05-11T04:24:21.697Z,1652243061.697 [Startup](INFO): Completed Startup
2022-05-11T04:24:21.697Z,1652243061.697 [MissionManager](INFO): Startup is completed.
2022-05-11T04:24:21.697Z,1652243061.697 [MissionManager](INFO): Uninitializing Mission Startup
2022-05-11T04:24:21.697Z,1652243061.697 [Startup] Stopped
2022-05-11T04:24:21.698Z,1652243061.698 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-05-11T04:24:21.698Z,1652243061.698 [Startup:A.GoToSurface] Stopped
2022-05-11T04:24:21.698Z,1652243061.698 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-11T04:24:22.091Z,1652243062.091 [MissionManager](IMPORTANT): Started mission Default
2022-05-11T04:24:22.091Z,1652243062.091 [Default] Running Loop=1
2022-05-11T04:24:22.091Z,1652243062.091 [Default](DEBUG): Aggregate::initialize Default
2022-05-11T04:24:22.091Z,1652243062.091 [Default:B.GoToSurface] Running Loop=1
2022-05-11T04:24:22.091Z,1652243062.091 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-11T04:24:22.092Z,1652243062.092 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-11T04:24:22.092Z,1652243062.092 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-11T04:24:22.092Z,1652243062.092 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-11T04:24:22.093Z,1652243062.093 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-11T04:24:22.093Z,1652243062.093 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-11T04:24:22.094Z,1652243062.094 [Default:A.Wait] Running Loop=1
2022-05-11T04:24:22.094Z,1652243062.094 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-05-11T04:24:35.414Z,1652243075.414 [Default:A.Wait](INFO): Done Waiting.
2022-05-11T04:24:35.414Z,1652243075.414 [Default:A.Wait] Stopped
2022-05-11T04:24:35.414Z,1652243075.414 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-05-11T04:24:35.815Z,1652243075.815 [Default:CheckIn] Running Loop=1
2022-05-11T04:24:35.815Z,1652243075.815 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-05-11T04:24:35.815Z,1652243075.815 [Default:CheckIn:Read_GPS] Running Loop=1
2022-05-11T04:24:36.224Z,1652243076.224 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-05-11T04:25:04.323Z,1652243104.323 [DataOverHttps](IMPORTANT): SBD MTMSN=20220511T042503
2022-05-11T04:25:11.565Z,1652243111.565 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2022-05-11T04:25:11.630Z,1652243111.630 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-05-11T04:25:11.630Z,1652243111.630 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-05-11T04:25:11.699Z,1652243111.699 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2022-05-11T04:25:11.702Z,1652243111.702 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2022-05-11T04:25:11.705Z,1652243111.705 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2022-05-11T04:25:11.708Z,1652243111.708 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2022-05-11T04:25:11.735Z,1652243111.735 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2022-05-11T04:25:11.738Z,1652243111.738 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2022-05-11T04:25:11.777Z,1652243111.777 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2022-05-11T04:25:11.780Z,1652243111.780 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2022-05-11T04:25:11.791Z,1652243111.791 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2022-05-11T04:25:11.799Z,1652243111.799 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2022-05-11T04:25:11.829Z,1652243111.829 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2022-05-11T04:25:11.832Z,1652243111.832 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2022-05-11T04:25:11.840Z,1652243111.840 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2022-05-11T04:25:11.853Z,1652243111.853 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2022-05-11T04:25:11.854Z,1652243111.854 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2022-05-11T04:25:12.253Z,1652243112.253 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2022-05-11T04:25:12.256Z,1652243112.256 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2022-05-11T04:25:12.259Z,1652243112.259 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2022-05-11T04:25:12.262Z,1652243112.262 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2022-05-11T04:25:12.265Z,1652243112.265 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2022-05-11T04:25:12.268Z,1652243112.268 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2022-05-11T04:25:12.290Z,1652243112.290 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2022-05-11T04:25:12.301Z,1652243112.301 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2022-05-11T04:25:12.304Z,1652243112.304 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2022-05-11T04:25:12.312Z,1652243112.312 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2022-05-11T04:25:12.330Z,1652243112.330 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2022-05-11T04:25:12.332Z,1652243112.332 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2022-05-11T04:25:12.339Z,1652243112.339 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2022-05-11T04:25:12.342Z,1652243112.342 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2022-05-11T04:25:12.353Z,1652243112.353 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-05-11T04:25:12.361Z,1652243112.361 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2022-05-11T04:25:12.368Z,1652243112.368 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2022-05-11T04:25:12.372Z,1652243112.372 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2022-05-11T04:25:12.380Z,1652243112.380 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2022-05-11T04:25:12.385Z,1652243112.385 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2022-05-11T04:25:12.396Z,1652243112.396 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2022-05-11T04:25:12.403Z,1652243112.403 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2022-05-11T04:25:12.408Z,1652243112.408 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2022-05-11T04:25:12.419Z,1652243112.419 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2022-05-11T04:25:12.446Z,1652243112.446 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2022-05-11T04:25:12.458Z,1652243112.458 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-05-11T04:25:12.465Z,1652243112.465 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2022-05-11T04:25:12.468Z,1652243112.468 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2022-05-11T04:25:12.476Z,1652243112.476 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2022-05-11T04:25:12.484Z,1652243112.484 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2022-05-11T04:25:12.487Z,1652243112.487 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2022-05-11T04:25:12.498Z,1652243112.498 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2022-05-11T04:25:12.506Z,1652243112.506 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2022-05-11T04:25:12.509Z,1652243112.509 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2022-05-11T04:25:12.528Z,1652243112.528 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2022-05-11T04:25:12.531Z,1652243112.531 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2022-05-11T04:25:12.539Z,1652243112.539 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2022-05-11T04:25:12.544Z,1652243112.544 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2022-05-11T04:25:12.560Z,1652243112.560 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2022-05-11T04:25:12.608Z,1652243112.608 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2022-05-11T04:25:12.620Z,1652243112.620 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2022-05-11T04:25:12.627Z,1652243112.627 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2022-05-11T04:25:12.650Z,1652243112.650 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2022-05-11T04:25:12.667Z,1652243112.667 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2022-05-11T04:25:12.675Z,1652243112.675 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2022-05-11T04:25:12.680Z,1652243112.680 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2022-05-11T04:25:12.695Z,1652243112.695 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2022-05-11T04:25:12.703Z,1652243112.703 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2022-05-11T04:25:12.708Z,1652243112.708 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2022-05-11T04:25:12.729Z,1652243112.729 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2022-05-11T04:25:12.736Z,1652243112.736 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2022-05-11T04:25:12.747Z,1652243112.747 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2022-05-11T04:25:12.755Z,1652243112.755 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2022-05-11T04:25:12.762Z,1652243112.762 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2022-05-11T04:25:12.773Z,1652243112.773 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2022-05-11T04:25:12.777Z,1652243112.777 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2022-05-11T04:25:12.784Z,1652243112.784 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2022-05-11T04:25:12.787Z,1652243112.787 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2022-05-11T04:25:12.802Z,1652243112.802 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2022-05-11T04:25:12.810Z,1652243112.810 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2022-05-11T04:25:12.813Z,1652243112.813 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2022-05-11T04:25:12.820Z,1652243112.820 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2022-05-11T04:25:12.842Z,1652243112.842 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2022-05-11T04:25:12.845Z,1652243112.845 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2022-05-11T04:25:12.852Z,1652243112.852 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2022-05-11T04:25:12.936Z,1652243112.936 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-05-11T04:25:13.021Z,1652243113.021 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-05-11T04:25:13.050Z,1652243113.050 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-05-11T04:25:13.082Z,1652243113.082 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-05-11T04:25:13.105Z,1652243113.105 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-05-11T04:25:13.134Z,1652243113.134 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-05-11T04:25:13.157Z,1652243113.157 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-05-11T04:25:13.182Z,1652243113.182 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-05-11T04:25:13.203Z,1652243113.203 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2022-05-11T04:25:13.460Z,1652243113.460 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2022-05-11T04:25:13.464Z,1652243113.464 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2022-05-11T04:25:13.476Z,1652243113.476 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2022-05-11T04:25:13.481Z,1652243113.481 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2022-05-11T04:25:13.509Z,1652243113.509 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2022-05-11T04:25:13.512Z,1652243113.512 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2022-05-11T04:25:13.520Z,1652243113.520 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2022-05-11T04:25:13.527Z,1652243113.527 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2022-05-11T04:25:13.535Z,1652243113.535 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2022-05-11T04:25:13.538Z,1652243113.538 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2022-05-11T04:25:13.557Z,1652243113.557 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2022-05-11T04:25:13.586Z,1652243113.586 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2022-05-11T04:25:13.597Z,1652243113.597 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2022-05-11T04:25:13.600Z,1652243113.600 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2022-05-11T04:25:13.668Z,1652243113.668 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2022-05-11T04:25:13.683Z,1652243113.683 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2022-05-11T04:25:13.686Z,1652243113.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2022-05-11T04:25:13.721Z,1652243113.721 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2022-05-11T04:25:13.736Z,1652243113.736 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2022-05-11T04:25:13.747Z,1652243113.747 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2022-05-11T04:25:13.760Z,1652243113.760 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2022-05-11T04:25:13.792Z,1652243113.792 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2022-05-11T04:25:13.806Z,1652243113.806 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2022-05-11T04:25:13.827Z,1652243113.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2022-05-11T04:25:13.854Z,1652243113.854 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2022-05-11T04:25:13.914Z,1652243113.914 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2022-05-11T04:25:13.916Z,1652243113.916 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2022-05-11T04:25:13.926Z,1652243113.926 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2022-05-11T04:25:13.928Z,1652243113.928 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2022-05-11T04:25:13.931Z,1652243113.931 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2022-05-11T04:25:13.932Z,1652243113.932 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2022-05-11T04:25:13.942Z,1652243113.942 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2022-05-11T04:25:13.944Z,1652243113.944 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2022-05-11T04:25:13.969Z,1652243113.969 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2022-05-11T04:25:13.982Z,1652243113.982 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2022-05-11T04:25:14.045Z,1652243114.045 [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="ballast_and_trim">

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

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed durnig the
        mission.
    </Description><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="SurfaceThreshold"><Description>
        Surface threshold depth.
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>20</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="WaitToFloat"><Units:minute/><Value>5</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

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

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Insert Filename="Insert/BallastAndTrim.xml">
            <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
            <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
            <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
            <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
            <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
        </Insert>

        <Aggregate Id="Depth1">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth1"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

        <Aggregate Id="Depth2">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth2"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="Float_Up">

        <Description>
            Float to surface instead of driving.
        </Description>

        <Sequence/>

        <Break>
            <Universal:depth/>
            <Lt><Control:VerticalControl.surfaceThreshold/></Lt>
        </Break>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyDefault/></Setting>
        </Guidance:Buoyancy>

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

    </Aggregate>

</Mission>


2022-05-11T04:25:14.049Z,1652243114.049 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-05-11T04:25:14.210Z,1652243114.210 [Default] Stopped
2022-05-11T04:25:14.210Z,1652243114.210 [Default](DEBUG): Aggregate::uninitialize Default
2022-05-11T04:25:14.210Z,1652243114.210 [Default:B.GoToSurface] Stopped
2022-05-11T04:25:14.211Z,1652243114.211 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-11T04:25:14.211Z,1652243114.211 [Default:CheckIn] Stopped
2022-05-11T04:25:14.211Z,1652243114.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-05-11T04:25:14.211Z,1652243114.211 [Default:CheckIn:Read_GPS] Stopped
2022-05-11T04:25:14.211Z,1652243114.211 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2022-05-11T04:25:14.211Z,1652243114.211 [ballast_and_trim] Running Loop=1
2022-05-11T04:25:14.211Z,1652243114.211 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2022-05-11T04:25:14.211Z,1652243114.211 [ballast_and_trim:Science] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:A] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:B] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:C] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:D] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:E] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:F] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-05-11T04:25:14.212Z,1652243114.212 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-05-11T04:25:14.219Z,1652243114.219 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2022-05-11T04:25:14.219Z,1652243114.219 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2022-05-11T04:25:14.219Z,1652243114.219 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-05-11T04:25:14.219Z,1652243114.219 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-05-11T04:25:14.219Z,1652243114.219 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2022-05-11T04:25:14.220Z,1652243114.220 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2022-05-11T04:25:14.220Z,1652243114.220 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-05-11T04:25:14.220Z,1652243114.220 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-05-11T04:25:14.222Z,1652243114.222 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2022-05-11T04:25:14.222Z,1652243114.222 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2022-05-11T04:25:14.222Z,1652243114.222 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-05-11T04:25:14.222Z,1652243114.222 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-05-11T04:25:14.224Z,1652243114.224 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2022-05-11T04:25:14.224Z,1652243114.224 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2022-05-11T04:25:14.224Z,1652243114.224 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-05-11T04:25:14.224Z,1652243114.224 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-05-11T04:25:14.225Z,1652243114.225 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2022-05-11T04:25:14.226Z,1652243114.226 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2022-05-11T04:25:14.226Z,1652243114.226 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-05-11T04:25:14.227Z,1652243114.227 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-05-11T04:25:14.228Z,1652243114.228 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2022-05-11T04:25:14.229Z,1652243114.229 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2022-05-11T04:25:14.238Z,1652243114.238 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-05-11T04:25:14.238Z,1652243114.238 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-05-11T04:25:14.240Z,1652243114.240 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2022-05-11T04:25:14.240Z,1652243114.240 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2022-05-11T04:25:14.240Z,1652243114.240 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-05-11T04:25:14.240Z,1652243114.240 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-05-11T04:25:14.241Z,1652243114.241 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2022-05-11T04:25:14.245Z,1652243114.245 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2022-05-11T04:25:14.245Z,1652243114.245 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-05-11T04:25:14.245Z,1652243114.245 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-05-11T04:25:14.245Z,1652243114.245 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2022-05-11T04:25:14.246Z,1652243114.246 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2022-05-11T04:25:14.246Z,1652243114.246 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2022-05-11T04:25:14.246Z,1652243114.246 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-05-11T04:25:14.246Z,1652243114.246 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2022-05-11T04:25:14.247Z,1652243114.247 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2022-05-11T04:25:14.247Z,1652243114.247 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2022-05-11T04:25:14.247Z,1652243114.247 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-05-11T04:25:14.247Z,1652243114.247 [ballast_and_trim:Science] Running Loop=1
2022-05-11T04:25:14.248Z,1652243114.248 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2022-05-11T04:25:14.248Z,1652243114.248 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2022-05-11T04:25:14.248Z,1652243114.248 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-05-11T04:25:14.248Z,1652243114.248 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-05-11T04:25:14.248Z,1652243114.248 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2022-05-11T04:25:14.248Z,1652243114.248 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2022-05-11T04:25:14.249Z,1652243114.249 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-05-11T04:25:14.249Z,1652243114.249 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-05-11T04:25:14.253Z,1652243114.253 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2022-05-11T04:25:14.253Z,1652243114.253 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2022-05-11T04:25:14.253Z,1652243114.253 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-05-11T04:25:14.253Z,1652243114.253 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-05-11T04:25:14.253Z,1652243114.253 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2022-05-11T04:25:14.253Z,1652243114.253 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectOil] Stopped
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2022-05-11T04:25:14.254Z,1652243114.254 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:PeakDetectChl] Stopped
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:Read_Oil] Stopped
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:F] Stopped
2022-05-11T04:25:14.255Z,1652243114.255 [ballast_and_trim:Science:E] Running Loop=1
2022-05-11T04:25:14.256Z,1652243114.256 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-05-11T04:25:14.256Z,1652243114.256 [ballast_and_trim:Science:D] Stopped
2022-05-11T04:25:14.256Z,1652243114.256 [ballast_and_trim:Science:C] Running Loop=1
2022-05-11T04:25:14.257Z,1652243114.257 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-05-11T04:25:14.258Z,1652243114.258 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-05-11T04:25:14.258Z,1652243114.258 [ballast_and_trim:Science:B] Running Loop=1
2022-05-11T04:25:14.259Z,1652243114.259 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-05-11T04:25:14.259Z,1652243114.259 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-05-11T04:25:14.260Z,1652243114.260 [ballast_and_trim:Science:A] Stopped
2022-05-11T04:25:14.474Z,1652243114.474 [WetLabsBB2FL](INFO): Powering up
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-05-11T04:25:14.631Z,1652243114.631 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2022-05-11T04:25:14.637Z,1652243114.637 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2022-05-11T04:25:14.637Z,1652243114.637 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-05-11T04:25:14.638Z,1652243114.638 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2022-05-11T04:25:14.639Z,1652243114.639 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2022-05-11T04:25:14.639Z,1652243114.639 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2022-05-11T04:25:14.639Z,1652243114.639 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-05-11T04:25:14.639Z,1652243114.639 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2022-05-11T04:25:14.639Z,1652243114.639 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-05-11T04:25:14.639Z,1652243114.639 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-05-11T04:25:14.640Z,1652243114.640 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-05-11T04:25:14.640Z,1652243114.640 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-05-11T04:25:14.640Z,1652243114.640 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2022-05-11T04:25:15.012Z,1652243115.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-05-11T04:25:15.013Z,1652243115.013 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2022-05-11T04:25:15.013Z,1652243115.013 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-05-11T04:25:15.408Z,1652243115.408 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-05-11T04:25:15.409Z,1652243115.409 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2022-05-11T04:25:15.409Z,1652243115.409 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2022-05-11T04:25:15.811Z,1652243115.811 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-05-11T04:25:15.812Z,1652243115.812 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2022-05-11T04:25:15.812Z,1652243115.812 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2022-05-11T04:25:15.812Z,1652243115.812 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2022-05-11T04:25:15.812Z,1652243115.812 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2022-05-11T04:25:17.435Z,1652243117.435 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-05-11T04:25:19.478Z,1652243119.478 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255111 psu
2022-05-11T04:25:20.478Z,1652243120.478 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255108 psu
2022-05-11T04:25:21.482Z,1652243121.482 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255108 psu
2022-05-11T04:25:22.486Z,1652243122.486 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255050 psu
2022-05-11T04:25:23.478Z,1652243123.478 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255046 psu
2022-05-11T04:25:24.486Z,1652243124.486 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255114 psu
2022-05-11T04:25:25.476Z,1652243125.476 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255113 psu
2022-05-11T04:25:26.479Z,1652243126.479 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255109 psu
2022-05-11T04:25:27.483Z,1652243127.483 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255046 psu
2022-05-11T04:25:28.487Z,1652243128.487 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255044 psu
2022-05-11T04:25:29.491Z,1652243129.491 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255039 psu
2022-05-11T04:25:30.495Z,1652243130.495 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255037 psu
2022-05-11T04:25:31.499Z,1652243131.499 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255093 psu
2022-05-11T04:25:32.127Z,1652243132.127 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 7 meter
2022-05-11T04:25:32.131Z,1652243132.131 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2022-05-11T04:25:32.460Z,1652243132.460 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-05-11T04:25:32.503Z,1652243132.503 [CTD_Seabird](ERROR): Salinity reading out of range: 0.254975 psu
2022-05-11T04:25:32.789Z,1652243132.789 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2022-05-11T04:25:32.789Z,1652243132.789 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-05-11T04:25:32.789Z,1652243132.789 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2022-05-11T04:25:32.789Z,1652243132.789 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2022-05-11T04:25:32.790Z,1652243132.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2022-05-11T04:25:32.791Z,1652243132.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-05-11T04:25:32.791Z,1652243132.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-05-11T04:25:32.791Z,1652243132.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2022-05-11T04:25:32.791Z,1652243132.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-05-11T04:25:32.791Z,1652243132.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2022-05-11T04:25:32.791Z,1652243132.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2022-05-11T04:25:33.193Z,1652243133.193 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.000000 m 
2022-05-11T04:25:33.193Z,1652243133.193 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2022-05-11T04:25:33.194Z,1652243133.194 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2022-05-11T04:25:33.194Z,1652243133.194 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2022-05-11T04:25:33.194Z,1652243133.194 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-05-11T04:25:33.194Z,1652243133.194 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-05-11T04:25:33.508Z,1652243133.508 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255036 psu
2022-05-11T04:25:34.511Z,1652243134.511 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255034 psu
2022-05-11T04:25:35.515Z,1652243135.515 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255035 psu
2022-05-11T04:25:36.520Z,1652243136.520 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255036 psu
2022-05-11T04:25:37.525Z,1652243137.525 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255033 psu
2022-05-11T04:25:38.527Z,1652243138.527 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255024 psu
2022-05-11T04:25:38.715Z,1652243138.715 [CommandExec](IMPORTANT): got command maintain clear
2022-05-11T04:25:38.911Z,1652243138.911 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-05-11T04:25:39.531Z,1652243139.531 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255024 psu
2022-05-11T04:25:40.535Z,1652243140.535 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255019 psu
2022-05-11T04:25:41.422Z,1652243141.422 [CommandExec](IMPORTANT): got command stop
2022-05-11T04:25:41.422Z,1652243141.422 [CommandExec](IMPORTANT): Scheduling is paused
2022-05-11T04:25:41.422Z,1652243141.422 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2022-05-11T04:25:41.539Z,1652243141.539 [CTD_Seabird](ERROR): Salinity reading out of range: 0.255021 psu
2022-05-11T04:25:41.670Z,1652243141.670 [DVL_micro](ERROR): only read 3 of 4 data items
2022-05-11T04:25:41.671Z,1652243141.671 [DVL_micro](ERROR): Failed to parse:
:BI,+0859,+00298,+00BD,+0000000000000.00,+000,9999.9
2022-05-11T04:25:41.730Z,1652243141.730 [MissionManager](INFO): MissionManager is completed.
2022-05-11T04:25:41.730Z,1652243141.730 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2022-05-11T04:25:41.730Z,1652243141.730 [ballast_and_trim] Stopped
2022-05-11T04:25:41.730Z,1652243141.730 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science:B] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science:C] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science:E] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science:OceanCurrent] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:RunBallastAndTrim] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-05-11T04:25:41.731Z,1652243141.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-05-11T04:25:41.732Z,1652243141.732 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2022-05-11T04:25:41.733Z,1652243141.733 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-05-11T04:25:42.083Z,1652243142.083 [MissionManager](IMPORTANT): Started mission Default
2022-05-11T04:25:42.083Z,1652243142.083 [Default] Running Loop=1
2022-05-11T04:25:42.084Z,1652243142.084 [Default](DEBUG): Aggregate::initialize Default
2022-05-11T04:25:42.084Z,1652243142.084 [Default:B.GoToSurface] Running Loop=1
2022-05-11T04:25:42.084Z,1652243142.084 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-11T04:25:42.084Z,1652243142.084 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-11T04:25:42.084Z,1652243142.084 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-11T04:25:42.084Z,1652243142.084 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-11T04:25:42.085Z,1652243142.085 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-11T04:25:42.085Z,1652243142.085 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-11T04:25:42.086Z,1652243142.086 [Default:A.Wait] Running Loop=1
2022-05-11T04:25:42.086Z,1652243142.086 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-05-11T04:25:42.293Z,1652243142.293 [WetLabsBB2FL](INFO): Powering down
2022-05-11T04:25:47.994Z,1652243147.994 [CommandExec](IMPORTANT): got command quit
2022-05-11T04:25:48.997Z,1652243148.997 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:48.997Z,1652243148.997 [CommandExec](INFO): Uninitializing the command executive.
2022-05-11T04:25:48.997Z,1652243148.997 [CommandExec](INFO): Uninitializing the command scheduler.
2022-05-11T04:25:48.998Z,1652243148.998 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.073Z,1652243149.073 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-05-11T04:25:49.073Z,1652243149.073 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-05-11T04:25:49.073Z,1652243149.073 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.074Z,1652243149.074 [NavChartDb](INFO): Join timeout helper Thread ID is 969
2022-05-11T04:25:49.074Z,1652243149.074 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:49.075Z,1652243149.075 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.075Z,1652243149.075 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-05-11T04:25:49.075Z,1652243149.075 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.076Z,1652243149.076 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 970
2022-05-11T04:25:49.255Z,1652243149.255 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:49.256Z,1652243149.256 [WetLabsBB2FL](INFO): Powering down
2022-05-11T04:25:49.258Z,1652243149.258 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.258Z,1652243149.258 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-05-11T04:25:49.258Z,1652243149.258 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.259Z,1652243149.259 [CTD_Seabird](INFO): Join timeout helper Thread ID is 971
2022-05-11T04:25:49.593Z,1652243149.593 [CTD_Seabird](INFO): Powering down
2022-05-11T04:25:49.608Z,1652243149.608 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:49.608Z,1652243149.608 [CTD_Seabird](INFO): Powering down
2022-05-11T04:25:49.621Z,1652243149.621 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.625Z,1652243149.625 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-05-11T04:25:49.625Z,1652243149.625 [DAT ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.626Z,1652243149.626 [DAT](INFO): Join timeout helper Thread ID is 972
2022-05-11T04:25:49.789Z,1652243149.789 [DAT](INFO): Powering down
2022-05-11T04:25:49.858Z,1652243149.858 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:49.858Z,1652243149.858 [DAT](INFO): Powering down
2022-05-11T04:25:49.859Z,1652243149.859 [DAT ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.877Z,1652243149.877 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-05-11T04:25:49.877Z,1652243149.877 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:49.878Z,1652243149.878 [Radio_Surface](INFO): Join timeout helper Thread ID is 973
2022-05-11T04:25:50.237Z,1652243150.237 [Radio_Surface](INFO): Powering down
2022-05-11T04:25:50.238Z,1652243150.238 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:50.238Z,1652243150.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:50.254Z,1652243150.254 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-05-11T04:25:50.254Z,1652243150.254 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:50.254Z,1652243150.254 [Onboard](INFO): Join timeout helper Thread ID is 974
2022-05-11T04:25:50.409Z,1652243150.409 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-05-11T04:25:51.597Z,1652243151.597 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:51.597Z,1652243151.597 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:51.618Z,1652243151.618 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-05-11T04:25:51.618Z,1652243151.618 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:51.618Z,1652243151.618 [DataOverHttps](INFO): Join timeout helper Thread ID is 975
2022-05-11T04:25:52.417Z,1652243152.417 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:52.417Z,1652243152.417 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.430Z,1652243152.430 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-05-11T04:25:52.430Z,1652243152.430 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.430Z,1652243152.430 [BackseatComponent](INFO): Join timeout helper Thread ID is 976
2022-05-11T04:25:52.618Z,1652243152.618 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:52.619Z,1652243152.619 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.634Z,1652243152.634 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-05-11T04:25:52.634Z,1652243152.634 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.634Z,1652243152.634 [logger](INFO): Join timeout helper Thread ID is 977
2022-05-11T04:25:52.678Z,1652243152.678 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:52.678Z,1652243152.678 [logger ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.682Z,1652243152.682 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-05-11T04:25:52.682Z,1652243152.682 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.682Z,1652243152.682 [CommandLine](INFO): Join timeout helper Thread ID is 978
2022-05-11T04:25:52.753Z,1652243152.753 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:52.754Z,1652243152.754 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.762Z,1652243152.762 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-05-11T04:25:52.762Z,1652243152.762 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.762Z,1652243152.762 [CommandExec](INFO): Join timeout helper Thread ID is 979
2022-05-11T04:25:52.763Z,1652243152.763 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-05-11T04:25:52.763Z,1652243152.763 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:52.764Z,1652243152.764 [controlThread](INFO): Join timeout helper Thread ID is 980
2022-05-11T04:25:52.961Z,1652243152.961 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-05-11T04:25:52.961Z,1652243152.961 [controlThread](DEBUG): Uninitializing ControlThread
2022-05-11T04:25:52.962Z,1652243152.962 [AHRS_M2](INFO): Powering down
2022-05-11T04:25:53.105Z,1652243153.105 [DVL_micro](INFO): Powering down
2022-05-11T04:25:53.106Z,1652243153.106 [NAL9602](INFO): Powering down
2022-05-11T04:25:53.109Z,1652243153.109 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-05-11T04:25:53.110Z,1652243153.110 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-05-11T04:25:53.110Z,1652243153.110 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-05-11T04:25:53.111Z,1652243153.111 [MissionManager](INFO): Uninitializing Mission Default
2022-05-11T04:25:53.111Z,1652243153.111 [Default] Stopped
2022-05-11T04:25:53.111Z,1652243153.111 [Default](DEBUG): Aggregate::uninitialize Default
2022-05-11T04:25:53.111Z,1652243153.111 [Default:A.Wait] Stopped
2022-05-11T04:25:53.111Z,1652243153.111 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-05-11T04:25:53.111Z,1652243153.111 [Default:B.GoToSurface] Stopped
2022-05-11T04:25:53.111Z,1652243153.111 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-05-11T04:25:53.114Z,1652243153.114 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-05-11T04:25:53.114Z,1652243153.114 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-05-11T04:25:53.115Z,1652243153.115 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-05-11T04:25:53.115Z,1652243153.115 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-05-11T04:25:53.115Z,1652243153.115 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-05-11T04:25:53.115Z,1652243153.115 [BuoyancyServo](INFO): Powering down
2022-05-11T04:25:53.245Z,1652243153.245 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-11T04:25:53.245Z,1652243153.245 [ElevatorServo](INFO): Powering down
2022-05-11T04:25:53.246Z,1652243153.246 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-11T04:25:53.246Z,1652243153.246 [MassServo](INFO): Powering down
2022-05-11T04:25:53.247Z,1652243153.247 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-11T04:25:53.247Z,1652243153.247 [RudderServo](INFO): Powering down
2022-05-11T04:25:53.248Z,1652243153.248 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-05-11T04:25:53.248Z,1652243153.248 [ThrusterHE](INFO): Powering down
2022-05-11T04:25:53.249Z,1652243153.249 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-05-11T04:25:53.249Z,1652243153.249 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-05-11T04:25:53.250Z,1652243153.250 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-05-11T04:25:53.250Z,1652243153.250 [CBIT](DEBUG): Powering off loads.
2022-05-11T04:25:53.261Z,1652243153.261 [CBIT](DEBUG): Disabling WDT.
2022-05-11T04:25:53.273Z,1652243153.273 [CBIT](DEBUG): Opening all GF detection circuits.
2022-05-11T04:25:53.274Z,1652243153.274 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.315Z,1652243153.315 [DAT ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.320Z,1652243153.320 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.322Z,1652243153.322 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.329Z,1652243153.329 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.336Z,1652243153.336 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.384Z,1652243153.384 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.389Z,1652243153.389 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.411Z,1652243153.411 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-05-11T04:25:53.474Z,1652243153.474 [logger ThreadHandler](INFO): Thread cancelled.