2022-07-11T18:34:45.842Z,1657564485.842 [Supervisor](DEBUG): Initializing supervisor.
2022-07-11T18:34:45.847Z,1657564485.847 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-07-11T18:34:45.847Z,1657564485.847 [SyncHandler](INFO): Protected caller Thread ID is 834
2022-07-11T18:34:45.848Z,1657564485.848 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-07-11T18:34:45.849Z,1657564485.849 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-07-11T18:34:45.849Z,1657564485.849 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2022-07-11T18:34:45.853Z,1657564485.853 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-07-11T18:34:45.872Z,1657564485.872 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-07-11T18:34:45.873Z,1657564485.873 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-07-11T18:34:45.874Z,1657564485.874 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2022-07-11T18:34:45.878Z,1657564485.878 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-07-11T18:34:45.879Z,1657564485.879 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-07-11T18:34:45.879Z,1657564485.879 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2022-07-11T18:34:45.881Z,1657564485.881 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-07-11T18:34:45.882Z,1657564485.882 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-07-11T18:34:45.883Z,1657564485.883 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2022-07-11T18:34:45.887Z,1657564485.887 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-07-11T18:34:45.887Z,1657564485.887 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-07-11T18:34:45.890Z,1657564485.890 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-07-11T18:34:46.205Z,1657564486.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-07-11T18:34:46.206Z,1657564486.206 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-07-11T18:34:46.853Z,1657564486.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-07-11T18:34:46.854Z,1657564486.854 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-07-11T18:34:46.961Z,1657564486.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-07-11T18:34:46.963Z,1657564486.963 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-07-11T18:34:47.518Z,1657564487.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-07-11T18:34:47.519Z,1657564487.519 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-07-11T18:34:47.615Z,1657564487.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-07-11T18:34:47.617Z,1657564487.617 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-07-11T18:34:47.698Z,1657564487.698 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-07-11T18:34:48.067Z,1657564488.067 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-07-11T18:34:48.067Z,1657564488.067 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-07-11T18:34:48.287Z,1657564488.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-07-11T18:34:48.289Z,1657564488.289 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-07-11T18:34:48.420Z,1657564488.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-07-11T18:34:48.421Z,1657564488.421 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-07-11T18:34:48.645Z,1657564488.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-07-11T18:34:48.647Z,1657564488.647 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-07-11T18:34:49.121Z,1657564489.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-07-11T18:34:49.123Z,1657564489.123 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-07-11T18:34:49.485Z,1657564489.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-07-11T18:34:49.487Z,1657564489.487 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-07-11T18:34:50.204Z,1657564490.204 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-07-11T18:34:50.698Z,1657564490.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-07-11T18:34:50.698Z,1657564490.698 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-07-11T18:34:50.919Z,1657564490.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-07-11T18:34:50.919Z,1657564490.919 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-07-11T18:34:51.212Z,1657564491.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-07-11T18:34:51.214Z,1657564491.214 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2022-07-11T18:34:51.218Z,1657564491.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2022-07-11T18:34:51.327Z,1657564491.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2022-07-11T18:34:51.475Z,1657564491.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2022-07-11T18:34:51.562Z,1657564491.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2022-07-11T18:34:51.645Z,1657564491.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2022-07-11T18:34:51.758Z,1657564491.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2022-07-11T18:34:51.935Z,1657564491.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2022-07-11T18:34:52.199Z,1657564492.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-07-11T18:34:52.200Z,1657564492.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2022-07-11T18:34:52.332Z,1657564492.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2022-07-11T18:34:52.426Z,1657564492.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2022-07-11T18:34:52.530Z,1657564492.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2022-07-11T18:34:52.628Z,1657564492.628 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-07-11T18:34:52.655Z,1657564492.655 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-07-11T18:34:52.775Z,1657564492.775 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-07-11T18:34:52.777Z,1657564492.777 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-07-11T18:34:52.793Z,1657564492.793 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-07-11T18:34:52.794Z,1657564492.794 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-07-11T18:34:52.865Z,1657564492.865 [DepthRateCalculator] Loaded
2022-07-11T18:34:52.865Z,1657564492.865 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-07-11T18:34:52.870Z,1657564492.870 [PitchRateCalculator] Loaded
2022-07-11T18:34:52.870Z,1657564492.870 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-07-11T18:34:52.881Z,1657564492.881 [SpeedCalculator] Loaded
2022-07-11T18:34:52.881Z,1657564492.881 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-07-11T18:34:52.886Z,1657564492.886 [YawRateCalculator] Loaded
2022-07-11T18:34:52.887Z,1657564492.887 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-07-11T18:34:52.907Z,1657564492.907 [ElevatorOffsetCalculator] Loaded
2022-07-11T18:34:52.908Z,1657564492.908 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-07-11T18:34:52.925Z,1657564492.925 [ShortestDistanceToPolygonSidesCalculator] Loaded
2022-07-11T18:34:52.925Z,1657564492.925 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2022-07-11T18:34:52.926Z,1657564492.926 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-07-11T18:34:52.926Z,1657564492.926 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-07-11T18:34:52.994Z,1657564492.994 [VerticalControl](DEBUG): Construct VerticalControl.
2022-07-11T18:34:53.051Z,1657564493.051 [VerticalControl] Loaded
2022-07-11T18:34:53.052Z,1657564493.052 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-07-11T18:34:53.054Z,1657564493.054 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-07-11T18:34:53.094Z,1657564493.094 [HorizontalControl] Loaded
2022-07-11T18:34:53.094Z,1657564493.094 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-07-11T18:34:53.096Z,1657564493.096 [SpeedControl](DEBUG): Construct SpeedControl.
2022-07-11T18:34:53.099Z,1657564493.099 [SpeedControl] Loaded
2022-07-11T18:34:53.099Z,1657564493.099 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-07-11T18:34:53.102Z,1657564493.102 [LoopControl](DEBUG): Construct LoopControl.
2022-07-11T18:34:53.103Z,1657564493.103 [LoopControl] Loaded
2022-07-11T18:34:53.103Z,1657564493.103 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-07-11T18:34:53.103Z,1657564493.103 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-07-11T18:34:53.104Z,1657564493.104 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-07-11T18:34:53.240Z,1657564493.240 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-07-11T18:34:53.240Z,1657564493.240 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-07-11T18:34:53.254Z,1657564493.254 [NavChart] Loaded
2022-07-11T18:34:53.254Z,1657564493.254 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-07-11T18:34:53.260Z,1657564493.260 [UniversalFixResidualReporter] Loaded
2022-07-11T18:34:53.260Z,1657564493.260 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-07-11T18:34:53.261Z,1657564493.261 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-07-11T18:34:53.262Z,1657564493.262 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-07-11T18:34:53.401Z,1657564493.401 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-07-11T18:34:53.402Z,1657564493.402 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-07-11T18:34:54.131Z,1657564494.131 [AHRS_M2] Loaded
2022-07-11T18:34:54.131Z,1657564494.131 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-07-11T18:34:54.381Z,1657564494.381 [BackseatComponent] Loaded
2022-07-11T18:34:54.381Z,1657564494.381 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-07-11T18:34:54.383Z,1657564494.383 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0
2022-07-11T18:34:54.383Z,1657564494.383 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920
2022-07-11T18:34:54.386Z,1657564494.386 [LcmUniversalReporter] Loaded
2022-07-11T18:34:54.386Z,1657564494.386 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-07-11T18:34:54.525Z,1657564494.525 [DataOverHttps] Loaded
2022-07-11T18:34:54.525Z,1657564494.525 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-07-11T18:34:54.526Z,1657564494.526 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2022-07-11T18:34:54.527Z,1657564494.527 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921
2022-07-11T18:34:54.547Z,1657564494.547 [Depth_Keller] Loaded
2022-07-11T18:34:54.547Z,1657564494.547 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-07-11T18:34:54.552Z,1657564494.552 [DropWeight] Loaded
2022-07-11T18:34:54.553Z,1657564494.553 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-07-11T18:34:54.627Z,1657564494.627 [NAL9602] Loaded
2022-07-11T18:34:54.627Z,1657564494.627 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-07-11T18:34:54.657Z,1657564494.657 [Onboard] Loaded
2022-07-11T18:34:54.657Z,1657564494.657 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-07-11T18:34:54.658Z,1657564494.658 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2022-07-11T18:34:54.658Z,1657564494.658 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922
2022-07-11T18:34:54.670Z,1657564494.670 [Power24vConverter] Loaded
2022-07-11T18:34:54.670Z,1657564494.670 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-07-11T18:34:54.683Z,1657564494.683 [Radio_Surface] Loaded
2022-07-11T18:34:54.683Z,1657564494.683 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-07-11T18:34:54.684Z,1657564494.684 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2022-07-11T18:34:54.684Z,1657564494.684 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923
2022-07-11T18:34:54.685Z,1657564494.685 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-07-11T18:34:54.686Z,1657564494.686 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-07-11T18:34:55.193Z,1657564495.193 [BuoyancyServo] Loaded
2022-07-11T18:34:55.193Z,1657564495.193 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-07-11T18:34:55.217Z,1657564495.217 [ElevatorServo] Loaded
2022-07-11T18:34:55.217Z,1657564495.217 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-07-11T18:34:55.237Z,1657564495.237 [MassServo] Loaded
2022-07-11T18:34:55.237Z,1657564495.237 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-07-11T18:34:55.255Z,1657564495.255 [RudderServo] Loaded
2022-07-11T18:34:55.255Z,1657564495.255 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-07-11T18:34:55.270Z,1657564495.270 [ThrusterHE] Loaded
2022-07-11T18:34:55.270Z,1657564495.270 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-07-11T18:34:55.271Z,1657564495.271 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-07-11T18:34:55.271Z,1657564495.271 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-07-11T18:34:55.354Z,1657564495.354 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-07-11T18:34:55.355Z,1657564495.355 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-07-11T18:34:55.397Z,1657564495.397 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-07-11T18:34:55.398Z,1657564495.398 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-07-11T18:34:55.761Z,1657564495.761 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-07-11T18:34:55.761Z,1657564495.761 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-07-11T18:34:55.977Z,1657564495.977 [CTD_Seabird] Loaded
2022-07-11T18:34:55.978Z,1657564495.978 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-07-11T18:34:55.979Z,1657564495.979 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2022-07-11T18:34:55.979Z,1657564495.979 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2022-07-11T18:34:56.011Z,1657564496.011 [ESPComponent] Loaded
2022-07-11T18:34:56.012Z,1657564496.012 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-07-11T18:34:56.031Z,1657564496.031 [PAR_Licor] Loaded
2022-07-11T18:34:56.031Z,1657564496.031 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-07-11T18:34:56.063Z,1657564496.063 [WetLabsBB2FL] Loaded
2022-07-11T18:34:56.063Z,1657564496.063 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-07-11T18:34:56.064Z,1657564496.064 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B324E0
2022-07-11T18:34:56.064Z,1657564496.064 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2022-07-11T18:34:56.065Z,1657564496.065 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-07-11T18:34:56.066Z,1657564496.066 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-07-11T18:34:56.234Z,1657564496.234 [SBIT](DEBUG): Construct Startup Built In Test.
2022-07-11T18:34:56.244Z,1657564496.244 [SBIT] Loaded
2022-07-11T18:34:56.244Z,1657564496.244 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-07-11T18:34:56.247Z,1657564496.247 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-07-11T18:34:56.261Z,1657564496.261 [IBIT] Loaded
2022-07-11T18:34:56.261Z,1657564496.261 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-07-11T18:34:56.267Z,1657564496.267 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-07-11T18:34:56.495Z,1657564496.495 [CBIT] Loaded
2022-07-11T18:34:56.495Z,1657564496.495 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-07-11T18:34:56.495Z,1657564496.495 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-07-11T18:34:56.502Z,1657564496.502 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-07-11T18:34:56.505Z,1657564496.505 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-07-11T18:34:56.516Z,1657564496.516 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-07-11T18:34:56.517Z,1657564496.517 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA04E0
2022-07-11T18:34:56.517Z,1657564496.517 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2022-07-11T18:34:56.522Z,1657564496.522 [Supervisor](INFO): Main Thread ID is 830
2022-07-11T18:34:56.522Z,1657564496.522 [Supervisor](DEBUG): Running supervisor.
2022-07-11T18:34:56.523Z,1657564496.523 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2022-07-11T18:34:56.523Z,1657564496.523 [CommandExec](INFO): Initializing the command executive.
2022-07-11T18:34:56.525Z,1657564496.525 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2022-07-11T18:34:56.527Z,1657564496.527 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2022-07-11T18:34:56.527Z,1657564496.527 [controlThread](DEBUG): Initializing ControlThread
2022-07-11T18:34:56.528Z,1657564496.528 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-07-11T18:34:56.528Z,1657564496.528 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-07-11T18:34:56.529Z,1657564496.529 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-07-11T18:34:56.529Z,1657564496.529 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-07-11T18:34:56.530Z,1657564496.530 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-07-11T18:34:56.530Z,1657564496.530 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2022-07-11T18:34:56.531Z,1657564496.531 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-07-11T18:34:56.533Z,1657564496.533 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-07-11T18:34:56.533Z,1657564496.533 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-07-11T18:34:56.534Z,1657564496.534 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-07-11T18:34:56.535Z,1657564496.535 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-07-11T18:34:56.535Z,1657564496.535 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-07-11T18:34:56.544Z,1657564496.544 [SBIT](INFO): Initialize SBIT Component.
2022-07-11T18:34:56.545Z,1657564496.545 [SBIT](IMPORTANT): git: 2022-07-07
2022-07-11T18:34:56.545Z,1657564496.545 [SBIT](INFO): git hash: 48b7c4fbe3545a8904a3b2b7df7cd86ca01e1427
2022-07-11T18:34:56.545Z,1657564496.545 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-07-11T18:34:56.546Z,1657564496.546 [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-07-11T18:34:56.547Z,1657564496.547 [SBIT](INFO): Beginning SBIT in 48.000000 seconds.
2022-07-11T18:34:56.548Z,1657564496.548 [IBIT](INFO): Initialize IBIT Component.
2022-07-11T18:34:56.549Z,1657564496.549 [CBIT](DEBUG): Initialize CBIT Component.
2022-07-11T18:34:56.550Z,1657564496.550 [logger ThreadHandler](INFO): Handler Thread ID is 930
2022-07-11T18:34:56.560Z,1657564496.560 [CBIT](DEBUG): Initialized mux pins.
2022-07-11T18:34:56.560Z,1657564496.560 [CBIT](DEBUG): Initializing the watchdog timer.
2022-07-11T18:34:56.568Z,1657564496.568 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 931
2022-07-11T18:34:56.580Z,1657564496.580 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 932
2022-07-11T18:34:56.581Z,1657564496.581 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-07-11T18:34:56.584Z,1657564496.584 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-07-11T18:34:56.584Z,1657564496.584 [CBIT](DEBUG): Initializing heartbeat.
2022-07-11T18:34:56.592Z,1657564496.592 [Onboard ThreadHandler](INFO): Handler Thread ID is 933
2022-07-11T18:34:56.610Z,1657564496.610 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 934
2022-07-11T18:34:56.632Z,1657564496.632 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 935
2022-07-11T18:34:56.633Z,1657564496.633 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-07-11T18:34:56.637Z,1657564496.637 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 937
2022-07-11T18:34:56.640Z,1657564496.640 [WetLabsBB2FL](INFO): Powering up
2022-07-11T18:34:56.642Z,1657564496.642 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 939
2022-07-11T18:34:56.649Z,1657564496.649 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-07-11T18:34:56.649Z,1657564496.649 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-07-11T18:34:56.649Z,1657564496.649 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-07-11T18:34:56.650Z,1657564496.649 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-07-11T18:34:56.650Z,1657564496.650 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-07-11T18:34:56.650Z,1657564496.650 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-07-11T18:34:56.650Z,1657564496.650 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-07-11T18:34:56.650Z,1657564496.650 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-07-11T18:34:56.650Z,1657564496.650 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2022-07-11T18:34:56.650Z,1657564496.650 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2022-07-11T18:34:56.656Z,1657564496.656 [CBIT](DEBUG): Deactivating GF circuits.
2022-07-11T18:34:56.656Z,1657564496.656 [CBIT](DEBUG): Deactivating emergency mode.
2022-07-11T18:34:56.692Z,1657564496.692 [CBIT](DEBUG): Backplane powered.
2022-07-11T18:34:56.693Z,1657564496.693 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-07-11T18:34:56.719Z,1657564496.719 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-07-11T18:34:56.764Z,1657564496.764 [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-07-11T18:34:56.765Z,1657564496.765 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-07-11T18:34:56.857Z,1657564496.857 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-07-11T18:34:56.858Z,1657564496.858 [Default:A.Wait](DEBUG): Construct Wait.
2022-07-11T18:34:56.861Z,1657564496.861 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-07-11T18:34:56.906Z,1657564496.906 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-07-11T18:34:56.909Z,1657564496.909 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-07-11T18:34:56.927Z,1657564496.927 [Default:E.Execute](DEBUG): Construct Execute.
2022-07-11T18:34:56.931Z,1657564496.931 [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-07-11T18:34:56.940Z,1657564496.940 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-07-11T18:34:56.956Z,1657564496.956 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-07-11T18:34:57.016Z,1657564497.016 [Radio_Surface](INFO): Powering up
2022-07-11T18:34:57.138Z,1657564497.138 [Power24vConverter](INFO): Powering up.
2022-07-11T18:34:57.178Z,1657564497.178 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-07-11T18:34:57.184Z,1657564497.184 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-07-11T18:34:57.185Z,1657564497.185 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-07-11T18:34:57.192Z,1657564497.192 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-07-11T18:34:57.193Z,1657564497.193 [MassServo](DEBUG): Initializing EZServoServo.
2022-07-11T18:34:57.200Z,1657564497.200 [MassServo](DEBUG): Initializing MassServo.
2022-07-11T18:34:57.201Z,1657564497.201 [RudderServo](DEBUG): Initializing EZServoServo.
2022-07-11T18:34:57.208Z,1657564497.208 [RudderServo](DEBUG): Initializing RudderServo.
2022-07-11T18:34:57.209Z,1657564497.209 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-07-11T18:34:57.216Z,1657564497.216 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-07-11T18:34:58.096Z,1657564498.096 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-07-11T18:34:58.096Z,1657564498.096 [RudderServo](FAULT): Rudder failed to initialize
2022-07-11T18:34:58.096Z,1657564498.096 [RudderServo] Communications Fault, FailCount= 1
2022-07-11T18:34:58.096Z,1657564498.096 [RudderServo](ERROR): Communications Fault
2022-07-11T18:34:58.100Z,1657564498.100 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-07-11T18:34:58.290Z,1657564498.290 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-07-11T18:34:58.291Z,1657564498.291 [RudderServo](INFO): Powering down
2022-07-11T18:34:58.988Z,1657564498.988 [RudderServo](DEBUG): Initializing EZServoServo.
2022-07-11T18:34:59.109Z,1657564499.109 [RudderServo](DEBUG): Initializing RudderServo.
2022-07-11T18:34:59.113Z,1657564499.113 [CBIT](INFO): Clearing failed state for component RudderServo
2022-07-11T18:34:59.113Z,1657564499.113 [RudderServo] No Fault, FailCount= 1
2022-07-11T18:34:59.252Z,1657564499.252 [WetLabsBB2FL](INFO): Powering down
2022-07-11T18:35:02.781Z,1657564502.781 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-07-11T18:35:24.825Z,1657564524.825 [NAL9602](INFO): Powering up NAL9602
2022-07-11T18:35:35.738Z,1657564535.738 [NAL9602](INFO): NAL9602 initialized
2022-07-11T18:35:45.056Z,1657564545.056 [SBIT](IMPORTANT): Beginning Startup BIT
2022-07-11T18:35:45.060Z,1657564545.060 [CBIT](IMPORTANT): Beginning ground fault scan
2022-07-11T18:35:56.132Z,1657564556.132 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004621
CHAN A1 (24V): -0.002679
CHAN A2 (12V): -0.000857
CHAN A3 (5V): 0.000031
CHAN B0 (3.3V): 0.000472
CHAN B1 (3.15aV): 0.000874
CHAN B2 (3.15bV): 0.000486
CHAN B3 (GND): 0.001276
OPEN: -0.003452
Full Scale: +/- 1 mA
2022-07-11T18:36:39.194Z,1657564599.194 [SBIT](IMPORTANT): SBIT PASSED
2022-07-11T18:36:39.194Z,1657564599.194 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-07-11T18:36:39.195Z,1657564599.195 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-07-11T18:36:39.195Z,1657564599.195 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2022-07-11T18:36:39.195Z,1657564599.195 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2022-07-11T18:36:39.196Z,1657564599.196 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar;

2022-07-11T18:36:39.196Z,1657564599.196 [SBIT](IMPORTANT): Express mostRecent CBIT.ampHoursUsed;

2022-07-11T18:36:39.196Z,1657564599.196 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2022-07-11T18:36:39.196Z,1657564599.196 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 1.000000 kelvin;

2022-07-11T18:36:39.196Z,1657564599.196 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2022-07-11T18:36:39.196Z,1657564599.196 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.200000 microgram_per_liter;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=34.55 degree;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=34.4 degree;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=34.13 degree;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=34.42 degree;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex5=nan degree;

2022-07-11T18:36:39.197Z,1657564599.197 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-120.67 degree;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-119.28 degree;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-119.28 degree;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-120.67 degree;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex5=nan degree;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=633 cubic_centimeter;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): VerticalControl.massDefault=-7 millimeter;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2022-07-11T18:36:39.198Z,1657564599.198 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2022-07-11T18:36:39.585Z,1657564599.585 [MissionManager](IMPORTANT): Started mission Startup
2022-07-11T18:36:39.586Z,1657564599.586 [Startup] Running Loop=1
2022-07-11T18:36:39.586Z,1657564599.586 [Startup](DEBUG): Aggregate::initialize Startup
2022-07-11T18:36:39.586Z,1657564599.586 [Startup:A.GoToSurface] Running Loop=1
2022-07-11T18:36:39.586Z,1657564599.586 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-07-11T18:36:39.586Z,1657564599.586 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-07-11T18:36:39.587Z,1657564599.587 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-07-11T18:36:39.587Z,1657564599.587 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-07-11T18:36:39.587Z,1657564599.587 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-07-11T18:36:39.588Z,1657564599.588 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-07-11T18:36:39.589Z,1657564599.589 [Startup:StartupSatComms] Running Loop=1
2022-07-11T18:36:39.589Z,1657564599.589 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-07-11T18:36:39.589Z,1657564599.589 [Startup:StartupSatComms:A] Running Loop=1
2022-07-11T18:36:39.990Z,1657564599.990 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-07-11T18:37:39.793Z,1657564659.793 [Startup:StartupSatComms:A](INFO): Timed out from 2022-07-11T18:36:39.6Z
2022-07-11T18:37:39.793Z,1657564659.793 [Startup:StartupSatComms:A] Stopped
2022-07-11T18:37:39.794Z,1657564659.794 [Startup:StartupSatComms:B] Running Loop=1
2022-07-11T18:37:40.195Z,1657564660.195 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-07-11T18:37:56.741Z,1657564676.741 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-07-11T18:37:56.741Z,1657564676.741 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-07-11T18:37:56.790Z,1657564676.790 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-07-11T18:37:57.155Z,1657564677.155 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-07-11T18:37:57.155Z,1657564677.155 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-07-11T18:38:02.450Z,1657564682.450 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004389
2022-07-11T18:38:39.493Z,1657564719.493 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220711T173632/Courier0013.lzma
2022-07-11T18:38:39.982Z,1657564719.982 [Startup:StartupSatComms:B](INFO): Timed out from 2022-07-11T18:37:39.8Z
2022-07-11T18:38:39.982Z,1657564719.982 [Startup:StartupSatComms:B] Stopped
2022-07-11T18:38:39.983Z,1657564719.983 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-07-11T18:38:39.983Z,1657564719.983 [Startup:StartupSatComms] Stopped
2022-07-11T18:38:39.983Z,1657564719.983 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-07-11T18:38:39.984Z,1657564719.984 [Startup](INFO): Completed Startup
2022-07-11T18:38:39.984Z,1657564719.984 [MissionManager](INFO): Startup is completed.
2022-07-11T18:38:39.984Z,1657564719.984 [MissionManager](INFO): Uninitializing Mission Startup
2022-07-11T18:38:39.984Z,1657564719.984 [Startup] Stopped
2022-07-11T18:38:39.984Z,1657564719.984 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-07-11T18:38:39.984Z,1657564719.984 [Startup:A.GoToSurface] Stopped
2022-07-11T18:38:39.984Z,1657564719.984 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-07-11T18:38:40.394Z,1657564720.394 [MissionManager](IMPORTANT): Started mission Default
2022-07-11T18:38:40.394Z,1657564720.394 [Default] Running Loop=1
2022-07-11T18:38:40.394Z,1657564720.394 [Default](DEBUG): Aggregate::initialize Default
2022-07-11T18:38:40.394Z,1657564720.394 [Default:B.GoToSurface] Running Loop=1
2022-07-11T18:38:40.394Z,1657564720.394 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-07-11T18:38:40.394Z,1657564720.394 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-07-11T18:38:40.395Z,1657564720.395 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-07-11T18:38:40.395Z,1657564720.395 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-07-11T18:38:40.395Z,1657564720.395 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-07-11T18:38:40.395Z,1657564720.395 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-07-11T18:38:40.396Z,1657564720.396 [Default:A.Wait] Running Loop=1
2022-07-11T18:38:40.396Z,1657564720.396 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-07-11T18:38:40.466Z,1657564720.466 [DataOverHttps](INFO): Moved sent file to Logs/20220711T173632/Courier0013.lzma.bak
2022-07-11T18:38:40.466Z,1657564720.466 [DataOverHttps](INFO): SBD MOMSN=16961298
2022-07-11T18:38:53.715Z,1657564733.715 [Default:A.Wait](INFO): Done Waiting.
2022-07-11T18:38:53.715Z,1657564733.715 [Default:A.Wait] Stopped
2022-07-11T18:38:53.715Z,1657564733.715 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T18:38:54.115Z,1657564734.115 [Default:CheckIn] Running Loop=1
2022-07-11T18:38:54.115Z,1657564734.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-11T18:38:54.115Z,1657564734.115 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-11T18:38:54.518Z,1657564734.518 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-07-11T18:39:50.144Z,1657564790.144 [CommandExec](IMPORTANT): got command failComponent
2022-07-11T18:39:50.145Z,1657564790.145 [CommandExec](IMPORTANT): Failed components:
2022-07-11T18:39:50.145Z,1657564790.145 [CommandExec](IMPORTANT): No failed Components.
2022-07-11T18:40:39.194Z,1657564839.194 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-11T18:40:42.835Z,1657564842.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184042.00,A,3648.16766,N,12147.28079,W,0.136,316.21,110722,,,A*7F
2022-07-11T18:40:42.838Z,1657564842.838 [NAL9602](INFO): GPS fix at 20220711T184042: (36.802794, -121.788013)
2022-07-11T18:40:42.964Z,1657564842.964 [Default:CheckIn:Read_GPS] Stopped
2022-07-11T18:40:42.964Z,1657564842.964 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-11T18:40:43.292Z,1657564843.292 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-07-11T18:40:48.859Z,1657564848.859 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220711T183445/Courier0000.lzma
2022-07-11T18:40:49.866Z,1657564849.866 [DataOverHttps](INFO): Moved sent file to Logs/20220711T183445/Courier0000.lzma.bak
2022-07-11T18:40:49.866Z,1657564849.866 [DataOverHttps](INFO): SBD MOMSN=16961300
2022-07-11T18:41:10.056Z,1657564870.056 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20220711T183445/Courier0004.lzma
2022-07-11T18:41:11.058Z,1657564871.058 [DataOverHttps](INFO): Moved sent file to Logs/20220711T183445/Courier0004.lzma.bak
2022-07-11T18:41:11.058Z,1657564871.058 [DataOverHttps](INFO): SBD MOMSN=16961303
2022-07-11T18:41:15.285Z,1657564875.285 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-11T18:41:25.376Z,1657564885.376 [CommandExec](IMPORTANT): got command failComponent
2022-07-11T18:41:25.377Z,1657564885.377 [CommandExec](IMPORTANT): Failed components:
2022-07-11T18:41:25.377Z,1657564885.377 [CommandExec](IMPORTANT): No failed Components.
2022-07-11T18:41:30.921Z,1657564890.921 [CommandLine](INFO): End of History
2022-07-11T18:41:30.935Z,1657564890.935 [CommandLine](INFO): End of History
2022-07-11T18:41:30.936Z,1657564890.936 [CommandLine](INFO): End of History
2022-07-11T18:41:30.937Z,1657564890.937 [CommandLine](INFO): End of History
2022-07-11T18:41:30.968Z,1657564890.968 [CommandLine](INFO): End of History
2022-07-11T18:41:30.970Z,1657564890.970 [CommandLine](INFO): End of History
2022-07-11T18:41:30.974Z,1657564890.974 [CommandLine](INFO): End of History
2022-07-11T18:41:31.011Z,1657564891.011 [CommandLine](INFO): End of History
2022-07-11T18:41:31.012Z,1657564891.012 [CommandLine](INFO): End of History
2022-07-11T18:41:31.013Z,1657564891.013 [CommandLine](INFO): End of History
2022-07-11T18:41:31.030Z,1657564891.030 [CommandLine](INFO): End of History
2022-07-11T18:41:31.030Z,1657564891.030 [CommandLine](INFO): End of History
2022-07-11T18:41:31.031Z,1657564891.031 [CommandLine](INFO): End of History
2022-07-11T18:41:31.232Z,1657564891.232 [CommandLine](INFO): End of History
2022-07-11T18:41:31.233Z,1657564891.233 [CommandLine](INFO): End of History
2022-07-11T18:41:31.234Z,1657564891.234 [CommandLine](INFO): End of History
2022-07-11T18:41:31.448Z,1657564891.448 [CommandLine](INFO): End of History
2022-07-11T18:41:31.449Z,1657564891.449 [CommandLine](INFO): End of History
2022-07-11T18:41:31.449Z,1657564891.449 [CommandLine](INFO): End of History
2022-07-11T18:41:31.553Z,1657564891.553 [CommandLine](INFO): End of History
2022-07-11T18:41:31.554Z,1657564891.554 [CommandLine](INFO): End of History
2022-07-11T18:41:31.555Z,1657564891.555 [CommandLine](INFO): End of History
2022-07-11T18:41:31.639Z,1657564891.639 [CommandLine](INFO): End of History
2022-07-11T18:41:31.639Z,1657564891.639 [CommandLine](INFO): End of History
2022-07-11T18:41:31.640Z,1657564891.640 [CommandLine](INFO): End of History
2022-07-11T18:41:31.655Z,1657564891.655 [CommandLine](INFO): End of History
2022-07-11T18:41:31.656Z,1657564891.656 [CommandLine](INFO): End of History
2022-07-11T18:41:31.657Z,1657564891.657 [CommandLine](INFO): End of History
2022-07-11T18:41:31.679Z,1657564891.679 [CommandLine](INFO): End of History
2022-07-11T18:41:31.680Z,1657564891.680 [CommandLine](INFO): End of History
2022-07-11T18:41:31.680Z,1657564891.680 [CommandLine](INFO): End of History
2022-07-11T18:41:31.799Z,1657564891.799 [CommandLine](INFO): End of History
2022-07-11T18:41:31.800Z,1657564891.800 [CommandLine](INFO): End of History
2022-07-11T18:41:31.801Z,1657564891.801 [CommandLine](INFO): End of History
2022-07-11T18:41:37.853Z,1657564897.853 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T014210/Express0001.lzma
2022-07-11T18:41:38.854Z,1657564898.854 [DataOverHttps](INFO): Moved sent file to Logs/19241126T014210/Express0001.lzma.bak
2022-07-11T18:41:38.854Z,1657564898.854 [DataOverHttps](INFO): SBD MOMSN=16961306
2022-07-11T18:41:47.837Z,1657564907.837 [CommandLine](INFO): End of History
2022-07-11T18:41:47.838Z,1657564907.838 [CommandLine](INFO): End of History
2022-07-11T18:41:47.839Z,1657564907.839 [CommandLine](INFO): End of History
2022-07-11T18:41:47.842Z,1657564907.842 [CommandLine](INFO): End of History
2022-07-11T18:41:47.843Z,1657564907.843 [CommandLine](INFO): End of History
2022-07-11T18:41:47.843Z,1657564907.843 [CommandLine](INFO): End of History
2022-07-11T18:41:47.848Z,1657564907.848 [CommandLine](INFO): End of History
2022-07-11T18:41:47.851Z,1657564907.851 [CommandLine](INFO): End of History
2022-07-11T18:41:47.854Z,1657564907.854 [CommandLine](INFO): End of History
2022-07-11T18:41:47.859Z,1657564907.859 [CommandLine](INFO): End of History
2022-07-11T18:41:47.862Z,1657564907.862 [CommandLine](INFO): End of History
2022-07-11T18:41:47.866Z,1657564907.866 [CommandLine](INFO): End of History
2022-07-11T18:41:47.885Z,1657564907.885 [CommandLine](INFO): End of History
2022-07-11T18:41:47.912Z,1657564907.912 [CommandLine](INFO): End of History
2022-07-11T18:41:47.932Z,1657564907.932 [CommandLine](INFO): End of History
2022-07-11T18:41:47.950Z,1657564907.950 [CommandLine](INFO): End of History
2022-07-11T18:41:47.951Z,1657564907.951 [CommandLine](INFO): End of History
2022-07-11T18:41:47.951Z,1657564907.951 [CommandLine](INFO): End of History
2022-07-11T18:41:47.985Z,1657564907.985 [CommandLine](INFO): End of History
2022-07-11T18:41:47.986Z,1657564907.986 [CommandLine](INFO): End of History
2022-07-11T18:41:47.987Z,1657564907.987 [CommandLine](INFO): End of History
2022-07-11T18:41:59.463Z,1657564919.463 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T014539/Express0001.lzma
2022-07-11T18:42:00.466Z,1657564920.466 [DataOverHttps](INFO): Moved sent file to Logs/19241126T014539/Express0001.lzma.bak
2022-07-11T18:42:00.466Z,1657564920.466 [DataOverHttps](INFO): SBD MOMSN=16961308
2022-07-11T18:42:37.839Z,1657564957.839 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T014937/Express0001.lzma
2022-07-11T18:42:38.842Z,1657564958.842 [DataOverHttps](INFO): Moved sent file to Logs/19241126T014937/Express0001.lzma.bak
2022-07-11T18:42:38.842Z,1657564958.842 [DataOverHttps](INFO): SBD MOMSN=16961310
2022-07-11T18:42:59.619Z,1657564979.619 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T015254/Express0001.lzma
2022-07-11T18:43:00.622Z,1657564980.622 [DataOverHttps](INFO): Moved sent file to Logs/19241126T015254/Express0001.lzma.bak
2022-07-11T18:43:00.622Z,1657564980.622 [DataOverHttps](INFO): SBD MOMSN=16961312
2022-07-11T18:43:06.964Z,1657564986.964 [CommandExec](IMPORTANT): got command ibit
2022-07-11T18:43:07.115Z,1657564987.115 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-07-11T18:43:07.115Z,1657564987.115 [IBIT](IMPORTANT): Beginning control surface checks.
2022-07-11T18:43:07.119Z,1657564987.119 [CBIT](IMPORTANT): Beginning ground fault scan
2022-07-11T18:43:08.635Z,1657564988.635 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184307.00,A,3648.16268,N,12147.27527,W,0.194,316.21,110722,,,A*7F
2022-07-11T18:43:08.637Z,1657564988.637 [NAL9602](INFO): GPS fix at 20220711T184307: (36.802711, -121.787921)
2022-07-11T18:43:09.048Z,1657564989.048 [CommandExec](IMPORTANT): got command failComponent
2022-07-11T18:43:09.048Z,1657564989.048 [CommandExec](IMPORTANT): Failed components:
2022-07-11T18:43:09.049Z,1657564989.049 [CommandExec](IMPORTANT): No failed Components.
2022-07-11T18:43:17.992Z,1657564997.992 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004266
CHAN A1 (24V): -0.003116
CHAN A2 (12V): -0.000402
CHAN A3 (5V): 0.000596
CHAN B0 (3.3V): 0.000740
CHAN B1 (3.15aV): 0.000325
CHAN B2 (3.15bV): -0.000358
CHAN B3 (GND): 0.001255
OPEN: 0.004644
Full Scale: +/- 1 mA
2022-07-11T18:43:39.330Z,1657565019.330 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-11T18:43:39.408Z,1657565019.408 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-07-11T18:43:46.635Z,1657565026.635 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T015641/Express0001.lzma
2022-07-11T18:43:47.638Z,1657565027.638 [DataOverHttps](INFO): Moved sent file to Logs/19241126T015641/Express0001.lzma.bak
2022-07-11T18:43:47.638Z,1657565027.638 [DataOverHttps](INFO): SBD MOMSN=16961314
2022-07-11T18:44:05.437Z,1657565045.437 [CommandExec](IMPORTANT): got command failComponent
2022-07-11T18:44:05.437Z,1657565045.437 [CommandExec](IMPORTANT): Failed components:
2022-07-11T18:44:05.437Z,1657565045.437 [CommandExec](IMPORTANT): No failed Components.
2022-07-11T18:44:11.351Z,1657565051.351 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T020009/Express0001.lzma
2022-07-11T18:44:12.354Z,1657565052.354 [DataOverHttps](INFO): Moved sent file to Logs/19241126T020009/Express0001.lzma.bak
2022-07-11T18:44:12.354Z,1657565052.354 [DataOverHttps](INFO): SBD MOMSN=16961316
2022-07-11T18:44:57.870Z,1657565097.870 [Power24vConverter](INFO): Powering down.
2022-07-11T18:44:58.350Z,1657565098.350 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-07-11T18:44:58.715Z,1657565098.715 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2022-07-11T18:44:59.126Z,1657565099.126 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-07-11T18:44:59.126Z,1657565099.126 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-07-11T18:44:59.126Z,1657565099.126 [IBIT](IMPORTANT): Pressure:10.552207 PSI
2022-07-11T18:44:59.127Z,1657565099.127 [IBIT](IMPORTANT): Humidity:13.820046 %
2022-07-11T18:44:59.503Z,1657565099.503 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T020357/Express0001.lzma
2022-07-11T18:44:59.517Z,1657565099.517 [IBIT](IMPORTANT): Vehicle Pitch:-1.122971 degrees
2022-07-11T18:44:59.517Z,1657565099.517 [IBIT](IMPORTANT): Vehicle  Roll:-1.588702 degrees
2022-07-11T18:44:59.517Z,1657565099.517 [IBIT](IMPORTANT): Vehicle  Heading:274.831116 degrees
2022-07-11T18:44:59.915Z,1657565099.915 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-07-11T18:44:59.915Z,1657565099.915 [IBIT](IMPORTANT): buoyancyNeutral: 633.000000 cc
2022-07-11T18:44:59.915Z,1657565099.915 [IBIT](IMPORTANT): massDefault: -0.700000 cm
2022-07-11T18:44:59.916Z,1657565099.916 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2022-07-11T18:44:59.917Z,1657565099.917 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2022-07-11T18:44:59.917Z,1657565099.917 [IBIT](IMPORTANT): IBIT FAILED
2022-07-11T18:45:00.506Z,1657565100.506 [DataOverHttps](INFO): Moved sent file to Logs/19241126T020357/Express0001.lzma.bak
2022-07-11T18:45:00.506Z,1657565100.506 [DataOverHttps](INFO): SBD MOMSN=16961318
2022-07-11T18:45:24.523Z,1657565124.523 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T020723/Express0001.lzma
2022-07-11T18:45:25.526Z,1657565125.526 [DataOverHttps](INFO): Moved sent file to Logs/19241126T020723/Express0001.lzma.bak
2022-07-11T18:45:25.526Z,1657565125.526 [DataOverHttps](INFO): SBD MOMSN=16961320
2022-07-11T18:45:56.747Z,1657565156.747 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T021113/Express0001.lzma
2022-07-11T18:45:57.750Z,1657565157.750 [DataOverHttps](INFO): Moved sent file to Logs/19241126T021113/Express0001.lzma.bak
2022-07-11T18:45:57.750Z,1657565157.750 [DataOverHttps](INFO): SBD MOMSN=16961322
2022-07-11T18:46:31.691Z,1657565191.691 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T021437/Express0001.lzma
2022-07-11T18:46:32.694Z,1657565192.694 [DataOverHttps](INFO): Moved sent file to Logs/19241126T021437/Express0001.lzma.bak
2022-07-11T18:46:32.694Z,1657565192.694 [DataOverHttps](INFO): SBD MOMSN=16961324
2022-07-11T18:46:53.115Z,1657565213.115 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T021824/Express0001.lzma
2022-07-11T18:46:54.118Z,1657565214.118 [DataOverHttps](INFO): Moved sent file to Logs/19241126T021824/Express0001.lzma.bak
2022-07-11T18:46:54.118Z,1657565214.118 [DataOverHttps](INFO): SBD MOMSN=16961326
2022-07-11T18:47:20.959Z,1657565240.959 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T022149/Express0001.lzma
2022-07-11T18:47:21.962Z,1657565241.962 [DataOverHttps](INFO): Moved sent file to Logs/19241126T022149/Express0001.lzma.bak
2022-07-11T18:47:21.962Z,1657565241.962 [DataOverHttps](INFO): SBD MOMSN=16961328
2022-07-11T18:47:42.455Z,1657565262.455 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T022535/Express0001.lzma
2022-07-11T18:47:43.458Z,1657565263.458 [DataOverHttps](INFO): Moved sent file to Logs/19241126T022535/Express0001.lzma.bak
2022-07-11T18:47:43.458Z,1657565263.458 [DataOverHttps](INFO): SBD MOMSN=16961330
2022-07-11T18:48:10.421Z,1657565290.421 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-11T18:48:27.963Z,1657565307.963 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T022904/Express0001.lzma
2022-07-11T18:48:28.966Z,1657565308.966 [DataOverHttps](INFO): Moved sent file to Logs/19241126T022904/Express0001.lzma.bak
2022-07-11T18:48:28.966Z,1657565308.966 [DataOverHttps](INFO): SBD MOMSN=16961332
2022-07-11T18:48:41.173Z,1657565321.173 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-11T18:49:00.955Z,1657565340.955 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T023251/Express0001.lzma
2022-07-11T18:49:01.958Z,1657565341.958 [DataOverHttps](INFO): Moved sent file to Logs/19241126T023251/Express0001.lzma.bak
2022-07-11T18:49:01.958Z,1657565341.958 [DataOverHttps](INFO): SBD MOMSN=16961334
2022-07-11T18:49:29.599Z,1657565369.599 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T023621/Express0001.lzma
2022-07-11T18:49:30.602Z,1657565370.602 [DataOverHttps](INFO): Moved sent file to Logs/19241126T023621/Express0001.lzma.bak
2022-07-11T18:49:30.602Z,1657565370.602 [DataOverHttps](INFO): SBD MOMSN=16961336
2022-07-11T18:49:50.952Z,1657565390.952 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T024010/Express0001.lzma
2022-07-11T18:49:51.954Z,1657565391.954 [DataOverHttps](INFO): Moved sent file to Logs/19241126T024010/Express0001.lzma.bak
2022-07-11T18:49:51.954Z,1657565391.954 [DataOverHttps](INFO): SBD MOMSN=16961338
2022-07-11T18:50:25.118Z,1657565425.118 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T024220/Express0001.lzma
2022-07-11T18:50:26.121Z,1657565426.121 [DataOverHttps](INFO): Moved sent file to Logs/19241126T024220/Express0001.lzma.bak
2022-07-11T18:50:26.122Z,1657565426.122 [DataOverHttps](IMPORTANT): SBD MOMSN=16961341, MTMSN=20220711T185025
2022-07-11T18:50:40.463Z,1657565440.463 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2022-07-11T18:50:40.560Z,1657565440.560 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-07-11T18:50:40.561Z,1657565440.561 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-07-11T18:50:40.636Z,1657565440.636 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2022-07-11T18:50:40.639Z,1657565440.639 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2022-07-11T18:50:40.642Z,1657565440.642 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2022-07-11T18:50:40.645Z,1657565440.645 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2022-07-11T18:50:40.648Z,1657565440.648 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2022-07-11T18:50:40.651Z,1657565440.651 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2022-07-11T18:50:40.654Z,1657565440.654 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2022-07-11T18:50:40.656Z,1657565440.656 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2022-07-11T18:50:40.659Z,1657565440.659 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2022-07-11T18:50:40.662Z,1657565440.662 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2022-07-11T18:50:40.665Z,1657565440.665 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2022-07-11T18:50:40.668Z,1657565440.668 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2022-07-11T18:50:40.715Z,1657565440.715 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2022-07-11T18:50:40.718Z,1657565440.718 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2022-07-11T18:50:40.719Z,1657565440.719 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2022-07-11T18:50:41.256Z,1657565441.256 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2022-07-11T18:50:41.265Z,1657565441.265 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2022-07-11T18:50:41.309Z,1657565441.309 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2022-07-11T18:50:41.311Z,1657565441.311 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2022-07-11T18:50:41.314Z,1657565441.314 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2022-07-11T18:50:41.316Z,1657565441.316 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2022-07-11T18:50:41.349Z,1657565441.349 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2022-07-11T18:50:41.352Z,1657565441.352 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2022-07-11T18:50:41.355Z,1657565441.355 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2022-07-11T18:50:41.358Z,1657565441.358 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2022-07-11T18:50:41.391Z,1657565441.391 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2022-07-11T18:50:41.394Z,1657565441.394 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2022-07-11T18:50:41.397Z,1657565441.397 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2022-07-11T18:50:41.424Z,1657565441.424 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2022-07-11T18:50:41.427Z,1657565441.427 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-07-11T18:50:41.430Z,1657565441.430 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2022-07-11T18:50:41.456Z,1657565441.456 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2022-07-11T18:50:41.459Z,1657565441.459 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2022-07-11T18:50:41.462Z,1657565441.462 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2022-07-11T18:50:41.510Z,1657565441.510 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2022-07-11T18:50:41.514Z,1657565441.514 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2022-07-11T18:50:41.541Z,1657565441.541 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2022-07-11T18:50:41.568Z,1657565441.568 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2022-07-11T18:50:41.571Z,1657565441.571 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2022-07-11T18:50:41.574Z,1657565441.574 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2022-07-11T18:50:41.629Z,1657565441.629 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-07-11T18:50:41.641Z,1657565441.641 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2022-07-11T18:50:41.669Z,1657565441.669 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2022-07-11T18:50:41.708Z,1657565441.708 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2022-07-11T18:50:41.711Z,1657565441.711 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2022-07-11T18:50:41.742Z,1657565441.742 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2022-07-11T18:50:41.765Z,1657565441.765 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2022-07-11T18:50:41.773Z,1657565441.773 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2022-07-11T18:50:41.800Z,1657565441.800 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2022-07-11T18:50:41.803Z,1657565441.803 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2022-07-11T18:50:41.806Z,1657565441.806 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2022-07-11T18:50:41.817Z,1657565441.817 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2022-07-11T18:50:41.857Z,1657565441.857 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2022-07-11T18:50:41.860Z,1657565441.860 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2022-07-11T18:50:41.879Z,1657565441.879 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2022-07-11T18:50:41.882Z,1657565441.882 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2022-07-11T18:50:41.886Z,1657565441.886 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2022-07-11T18:50:41.913Z,1657565441.913 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2022-07-11T18:50:41.916Z,1657565441.916 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2022-07-11T18:50:41.919Z,1657565441.919 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2022-07-11T18:50:41.922Z,1657565441.922 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2022-07-11T18:50:41.960Z,1657565441.960 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2022-07-11T18:50:41.963Z,1657565441.963 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2022-07-11T18:50:41.986Z,1657565441.986 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2022-07-11T18:50:42.017Z,1657565442.017 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2022-07-11T18:50:42.029Z,1657565442.029 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2022-07-11T18:50:42.056Z,1657565442.056 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2022-07-11T18:50:42.072Z,1657565442.072 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2022-07-11T18:50:42.075Z,1657565442.075 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2022-07-11T18:50:42.126Z,1657565442.126 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2022-07-11T18:50:42.142Z,1657565442.142 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2022-07-11T18:50:42.169Z,1657565442.169 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2022-07-11T18:50:42.172Z,1657565442.172 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2022-07-11T18:50:42.190Z,1657565442.190 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2022-07-11T18:50:42.204Z,1657565442.204 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2022-07-11T18:50:42.207Z,1657565442.207 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2022-07-11T18:50:42.230Z,1657565442.230 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2022-07-11T18:50:42.233Z,1657565442.233 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2022-07-11T18:50:42.257Z,1657565442.257 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2022-07-11T18:50:42.260Z,1657565442.260 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2022-07-11T18:50:42.319Z,1657565442.319 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T18:50:42.379Z,1657565442.379 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-07-11T18:50:42.445Z,1657565442.445 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T18:50:42.505Z,1657565442.505 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T18:50:42.528Z,1657565442.528 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-07-11T18:50:42.565Z,1657565442.565 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T18:50:42.598Z,1657565442.598 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T18:50:42.645Z,1657565442.645 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-07-11T18:50:42.698Z,1657565442.698 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2022-07-11T18:50:43.058Z,1657565443.058 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2022-07-11T18:50:43.074Z,1657565443.074 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2022-07-11T18:50:43.077Z,1657565443.077 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2022-07-11T18:50:43.093Z,1657565443.093 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2022-07-11T18:50:43.096Z,1657565443.096 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2022-07-11T18:50:43.115Z,1657565443.115 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2022-07-11T18:50:43.118Z,1657565443.118 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2022-07-11T18:50:43.125Z,1657565443.125 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2022-07-11T18:50:43.129Z,1657565443.129 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2022-07-11T18:50:43.148Z,1657565443.148 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2022-07-11T18:50:43.157Z,1657565443.157 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2022-07-11T18:50:43.161Z,1657565443.161 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2022-07-11T18:50:43.180Z,1657565443.180 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2022-07-11T18:50:43.187Z,1657565443.187 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2022-07-11T18:50:43.273Z,1657565443.273 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2022-07-11T18:50:43.320Z,1657565443.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2022-07-11T18:50:43.327Z,1657565443.327 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2022-07-11T18:50:43.349Z,1657565443.349 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2022-07-11T18:50:43.357Z,1657565443.357 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2022-07-11T18:50:43.361Z,1657565443.361 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2022-07-11T18:50:43.394Z,1657565443.394 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2022-07-11T18:50:43.398Z,1657565443.398 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2022-07-11T18:50:43.417Z,1657565443.417 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2022-07-11T18:50:43.460Z,1657565443.460 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2022-07-11T18:50:43.466Z,1657565443.466 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2022-07-11T18:50:43.538Z,1657565443.538 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2022-07-11T18:50:43.540Z,1657565443.540 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2022-07-11T18:50:43.542Z,1657565443.542 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2022-07-11T18:50:43.543Z,1657565443.543 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2022-07-11T18:50:43.545Z,1657565443.545 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2022-07-11T18:50:43.557Z,1657565443.557 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2022-07-11T18:50:43.559Z,1657565443.559 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2022-07-11T18:50:43.561Z,1657565443.561 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2022-07-11T18:50:43.596Z,1657565443.596 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2022-07-11T18:50:43.600Z,1657565443.600 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2022-07-11T18:50:43.697Z,1657565443.697 [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-07-11T18:50:43.740Z,1657565443.740 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-07-11T18:50:44.065Z,1657565444.065 [Default] Stopped
2022-07-11T18:50:44.065Z,1657565444.065 [Default](DEBUG): Aggregate::uninitialize Default
2022-07-11T18:50:44.065Z,1657565444.065 [Default:B.GoToSurface] Stopped
2022-07-11T18:50:44.065Z,1657565444.065 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-07-11T18:50:44.065Z,1657565444.065 [Default:CheckIn] Stopped
2022-07-11T18:50:44.065Z,1657565444.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-11T18:50:44.065Z,1657565444.065 [Default:CheckIn:Read_Iridium] Stopped
2022-07-11T18:50:44.065Z,1657565444.065 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:A] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:B] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:C] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:D] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:E] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:F] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2022-07-11T18:50:44.066Z,1657565444.066 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2022-07-11T18:50:44.067Z,1657565444.067 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2022-07-11T18:50:44.067Z,1657565444.067 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T18:50:44.071Z,1657565444.071 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T18:50:44.073Z,1657565444.073 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2022-07-11T18:50:44.073Z,1657565444.073 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2022-07-11T18:50:44.073Z,1657565444.073 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-07-11T18:50:44.073Z,1657565444.073 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-07-11T18:50:44.074Z,1657565444.074 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2022-07-11T18:50:44.074Z,1657565444.074 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2022-07-11T18:50:44.074Z,1657565444.074 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T18:50:44.074Z,1657565444.074 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T18:50:44.085Z,1657565444.085 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2022-07-11T18:50:44.085Z,1657565444.085 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2022-07-11T18:50:44.085Z,1657565444.085 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T18:50:44.085Z,1657565444.085 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T18:50:44.095Z,1657565444.095 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2022-07-11T18:50:44.095Z,1657565444.095 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2022-07-11T18:50:44.096Z,1657565444.096 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-07-11T18:50:44.096Z,1657565444.096 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-07-11T18:50:44.096Z,1657565444.096 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2022-07-11T18:50:44.096Z,1657565444.096 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2022-07-11T18:50:44.096Z,1657565444.096 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T18:50:44.096Z,1657565444.096 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T18:50:44.098Z,1657565444.098 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2022-07-11T18:50:44.098Z,1657565444.098 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2022-07-11T18:50:44.098Z,1657565444.098 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T18:50:44.099Z,1657565444.099 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T18:50:44.115Z,1657565444.115 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2022-07-11T18:50:44.115Z,1657565444.115 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2022-07-11T18:50:44.116Z,1657565444.116 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-07-11T18:50:44.116Z,1657565444.116 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-07-11T18:50:44.116Z,1657565444.116 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2022-07-11T18:50:44.116Z,1657565444.116 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2022-07-11T18:50:44.116Z,1657565444.116 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-07-11T18:50:44.116Z,1657565444.116 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-07-11T18:50:44.117Z,1657565444.117 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2022-07-11T18:50:44.117Z,1657565444.117 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2022-07-11T18:50:44.117Z,1657565444.117 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2022-07-11T18:50:44.117Z,1657565444.117 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-07-11T18:50:44.117Z,1657565444.117 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2022-07-11T18:50:44.118Z,1657565444.118 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2022-07-11T18:50:44.118Z,1657565444.118 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2022-07-11T18:50:44.118Z,1657565444.118 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-07-11T18:50:44.118Z,1657565444.118 [ballast_and_trim:Science] Running Loop=1
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2022-07-11T18:50:44.124Z,1657565444.124 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectOil] Stopped
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2022-07-11T18:50:44.125Z,1657565444.125 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:PeakDetectChl] Stopped
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T18:50:44.126Z,1657565444.126 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-07-11T18:50:44.127Z,1657565444.127 [ballast_and_trim:Science:Read_Oil] Stopped
2022-07-11T18:50:44.127Z,1657565444.127 [ballast_and_trim:Science:F] Stopped
2022-07-11T18:50:44.127Z,1657565444.127 [ballast_and_trim:Science:E] Running Loop=1
2022-07-11T18:50:44.132Z,1657565444.132 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-07-11T18:50:44.132Z,1657565444.132 [ballast_and_trim:Science:D] Stopped
2022-07-11T18:50:44.132Z,1657565444.132 [ballast_and_trim:Science:C] Running Loop=1
2022-07-11T18:50:44.132Z,1657565444.132 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-07-11T18:50:44.133Z,1657565444.133 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-07-11T18:50:44.133Z,1657565444.133 [ballast_and_trim:Science:B] Running Loop=1
2022-07-11T18:50:44.134Z,1657565444.134 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-07-11T18:50:44.134Z,1657565444.134 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-07-11T18:50:44.135Z,1657565444.135 [ballast_and_trim:Science:A] Stopped
2022-07-11T18:50:44.436Z,1657565444.436 [WetLabsBB2FL](INFO): Powering up
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-07-11T18:50:44.457Z,1657565444.457 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2022-07-11T18:50:44.463Z,1657565444.463 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2022-07-11T18:50:44.463Z,1657565444.463 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-07-11T18:50:44.464Z,1657565444.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2022-07-11T18:50:44.465Z,1657565444.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2022-07-11T18:50:44.465Z,1657565444.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2022-07-11T18:50:44.465Z,1657565444.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-07-11T18:50:44.465Z,1657565444.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2022-07-11T18:50:44.465Z,1657565444.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-07-11T18:50:44.465Z,1657565444.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-07-11T18:50:44.466Z,1657565444.466 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-07-11T18:50:44.466Z,1657565444.466 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-07-11T18:50:44.466Z,1657565444.466 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-07-11T18:50:44.881Z,1657565444.881 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2022-07-11T18:50:44.881Z,1657565444.881 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2022-07-11T18:50:44.881Z,1657565444.881 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2022-07-11T18:50:44.881Z,1657565444.881 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2022-07-11T18:50:44.881Z,1657565444.881 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2022-07-11T18:50:44.881Z,1657565444.881 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-07-11T18:50:44.882Z,1657565444.882 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2022-07-11T18:50:44.882Z,1657565444.882 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2022-07-11T18:50:44.882Z,1657565444.882 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-07-11T18:50:44.882Z,1657565444.882 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2022-07-11T18:50:44.882Z,1657565444.882 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2022-07-11T18:50:45.252Z,1657565445.252 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-07-11T18:50:45.253Z,1657565445.253 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-07-11T18:50:45.253Z,1657565445.253 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2022-07-11T18:50:45.253Z,1657565445.253 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2022-07-11T18:50:45.670Z,1657565445.670 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-07-11T18:50:45.675Z,1657565445.675 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2022-07-11T18:50:45.675Z,1657565445.675 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2022-07-11T18:50:45.675Z,1657565445.675 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2022-07-11T18:50:45.675Z,1657565445.675 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2022-07-11T18:50:47.536Z,1657565447.536 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-07-11T18:50:49.690Z,1657565449.690 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T024610/Express0001.lzma
2022-07-11T18:50:50.693Z,1657565450.693 [DataOverHttps](INFO): Moved sent file to Logs/19241126T024610/Express0001.lzma.bak
2022-07-11T18:50:50.693Z,1657565450.693 [DataOverHttps](INFO): SBD MOMSN=16961344
2022-07-11T18:52:33.615Z,1657565553.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T18:52:35.624Z,1657565555.624 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239239
2022-07-11T18:53:03.347Z,1657565583.347 [Radio_Surface](INFO): Powering down
2022-07-11T18:53:08.755Z,1657565588.755 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T18:53:09.759Z,1657565589.759 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-07-11T19:10:45.956Z,1657566645.956 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](INFO): Timed out from 2022-07-11T18:50:45.7Z
2022-07-11T19:10:45.956Z,1657566645.956 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout] Running Loop=1
2022-07-11T19:10:45.956Z,1657566645.956 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout
2022-07-11T19:10:45.956Z,1657566645.956 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:A] Running Loop=1
2022-07-11T19:10:45.957Z,1657566645.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:A](IMPORTANT): Timed out trying to reach the target depth. Stopping mission at current depth of 5.165126 m 
2022-07-11T19:10:45.957Z,1657566645.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:A] Stopped
2022-07-11T19:10:45.957Z,1657566645.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute] Running Loop=1
2022-07-11T19:10:46.360Z,1657566646.360 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](INFO): Executing command stop
2022-07-11T19:10:46.360Z,1657566646.360 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute] Stopped
2022-07-11T19:10:46.360Z,1657566646.360 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout
2022-07-11T19:10:46.360Z,1657566646.360 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2022-07-11T19:10:46.361Z,1657566646.361 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-07-11T19:10:46.361Z,1657566646.361 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2022-07-11T19:10:46.361Z,1657566646.361 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-07-11T19:10:46.361Z,1657566646.361 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2022-07-11T19:10:46.361Z,1657566646.361 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-07-11T19:10:46.362Z,1657566646.362 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2022-07-11T19:10:46.363Z,1657566646.363 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2022-07-11T19:10:46.400Z,1657566646.400 [CommandExec](IMPORTANT): got command stop
2022-07-11T19:10:46.400Z,1657566646.400 [CommandExec](IMPORTANT): Scheduling is paused
2022-07-11T19:10:46.400Z,1657566646.400 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2022-07-11T19:10:46.776Z,1657566646.776 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 5.159940 m 
2022-07-11T19:10:46.776Z,1657566646.776 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2022-07-11T19:10:46.776Z,1657566646.776 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2022-07-11T19:10:46.776Z,1657566646.776 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2022-07-11T19:10:46.776Z,1657566646.776 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-07-11T19:10:46.776Z,1657566646.776 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-07-11T19:10:46.779Z,1657566646.779 [MissionManager](INFO): MissionManager is completed.
2022-07-11T19:10:46.779Z,1657566646.779 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim] Stopped
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim:Science] Stopped
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim:Science:B] Stopped
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim:Science:C] Stopped
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim:Science:E] Stopped
2022-07-11T19:10:46.779Z,1657566646.779 [ballast_and_trim:Science:OceanCurrent] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-07-11T19:10:46.780Z,1657566646.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2022-07-11T19:10:46.781Z,1657566646.781 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-07-11T19:10:47.165Z,1657566647.165 [MissionManager](IMPORTANT): Started mission Default
2022-07-11T19:10:47.165Z,1657566647.165 [Default] Running Loop=1
2022-07-11T19:10:47.165Z,1657566647.165 [Default](DEBUG): Aggregate::initialize Default
2022-07-11T19:10:47.165Z,1657566647.165 [Default:B.GoToSurface] Running Loop=1
2022-07-11T19:10:47.165Z,1657566647.165 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-07-11T19:10:47.166Z,1657566647.166 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-07-11T19:10:47.166Z,1657566647.166 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-07-11T19:10:47.166Z,1657566647.166 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-07-11T19:10:47.166Z,1657566647.166 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-07-11T19:10:47.167Z,1657566647.167 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-07-11T19:10:47.167Z,1657566647.167 [Default:A.Wait] Running Loop=1
2022-07-11T19:10:47.167Z,1657566647.167 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-07-11T19:10:47.252Z,1657566647.252 [WetLabsBB2FL](INFO): Powering down
2022-07-11T19:10:47.563Z,1657566647.563 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -28.00 mm (1 active estimators).
2022-07-11T19:10:47.904Z,1657566647.904 [Radio_Surface](INFO): Powering up
2022-07-11T19:10:53.207Z,1657566653.207 [DataOverHttps](INFO): Radio surface powered ON.
2022-07-11T19:11:00.500Z,1657566660.500 [Default:A.Wait](INFO): Done Waiting.
2022-07-11T19:11:00.500Z,1657566660.500 [Default:A.Wait] Stopped
2022-07-11T19:11:00.500Z,1657566660.500 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T19:11:23.335Z,1657566683.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:11:42.107Z,1657566702.107 [Default:CheckIn] Running Loop=1
2022-07-11T19:11:42.117Z,1657566702.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-11T19:11:42.117Z,1657566702.117 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-11T19:11:58.475Z,1657566718.475 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:12:14.821Z,1657566734.821 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-07-11T19:12:14.903Z,1657566734.903 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-07-11T19:12:33.615Z,1657566753.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:13:08.755Z,1657566788.755 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:13:43.895Z,1657566823.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:13:56.363Z,1657566836.363 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002864
2022-07-11T19:16:42.368Z,1657567002.368 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-07-11T19:11:42.1Z
2022-07-11T19:16:42.368Z,1657567002.368 [Default:CheckIn:Read_GPS] Stopped
2022-07-11T19:16:42.368Z,1657567002.368 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-11T19:16:46.392Z,1657567006.392 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-11T19:16:53.022Z,1657567013.022 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20220711T183445/Courier0007.lzma
2022-07-11T19:16:54.025Z,1657567014.025 [DataOverHttps](INFO): Moved sent file to Logs/20220711T183445/Courier0007.lzma.bak
2022-07-11T19:16:54.025Z,1657567014.025 [DataOverHttps](INFO): SBD MOMSN=16961474
2022-07-11T19:17:13.503Z,1657567033.503 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220711T183445/Courier0010.lzma
2022-07-11T19:17:14.501Z,1657567034.501 [DataOverHttps](INFO): Moved sent file to Logs/20220711T183445/Courier0010.lzma.bak
2022-07-11T19:17:14.501Z,1657567034.501 [DataOverHttps](INFO): SBD MOMSN=16961477
2022-07-11T19:17:17.113Z,1657567037.113 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-11T19:17:50.358Z,1657567070.358 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T024934/Express0001.lzma
2022-07-11T19:17:51.361Z,1657567071.361 [DataOverHttps](INFO): Moved sent file to Logs/19241126T024934/Express0001.lzma.bak
2022-07-11T19:17:51.361Z,1657567071.361 [DataOverHttps](INFO): SBD MOMSN=16961479
2022-07-11T19:17:54.620Z,1657567074.620 [CommandExec](IMPORTANT): got command failComponent
2022-07-11T19:17:54.620Z,1657567074.620 [CommandExec](IMPORTANT): Failed components:
2022-07-11T19:17:54.620Z,1657567074.620 [CommandExec](IMPORTANT): No failed Components.
2022-07-11T19:18:17.074Z,1657567097.074 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T025321/Express0001.lzma
2022-07-11T19:18:18.077Z,1657567098.077 [DataOverHttps](INFO): Moved sent file to Logs/19241126T025321/Express0001.lzma.bak
2022-07-11T19:18:18.077Z,1657567098.077 [DataOverHttps](INFO): SBD MOMSN=16961481
2022-07-11T19:18:52.482Z,1657567132.482 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T025652/Express0001.lzma
2022-07-11T19:18:53.485Z,1657567133.485 [DataOverHttps](INFO): Moved sent file to Logs/19241126T025652/Express0001.lzma.bak
2022-07-11T19:18:53.485Z,1657567133.485 [DataOverHttps](INFO): SBD MOMSN=16961483
2022-07-11T19:19:21.146Z,1657567161.146 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T025908/Express0001.lzma
2022-07-11T19:19:22.149Z,1657567162.149 [DataOverHttps](INFO): Moved sent file to Logs/19241126T025908/Express0001.lzma.bak
2022-07-11T19:19:22.149Z,1657567162.149 [DataOverHttps](INFO): SBD MOMSN=16961504
2022-07-11T19:19:43.146Z,1657567183.146 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T030407/Express0001.lzma
2022-07-11T19:19:44.149Z,1657567184.149 [DataOverHttps](INFO): Moved sent file to Logs/19241126T030407/Express0001.lzma.bak
2022-07-11T19:19:44.149Z,1657567184.149 [DataOverHttps](INFO): SBD MOMSN=16961567
2022-07-11T19:20:13.947Z,1657567213.947 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T030758/Express0001.lzma
2022-07-11T19:20:14.949Z,1657567214.949 [DataOverHttps](INFO): Moved sent file to Logs/19241126T030758/Express0001.lzma.bak
2022-07-11T19:20:14.949Z,1657567214.949 [DataOverHttps](INFO): SBD MOMSN=16961677
2022-07-11T19:20:46.971Z,1657567246.971 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T031008/Express0001.lzma
2022-07-11T19:20:47.973Z,1657567247.973 [DataOverHttps](INFO): Moved sent file to Logs/19241126T031008/Express0001.lzma.bak
2022-07-11T19:20:47.973Z,1657567247.973 [DataOverHttps](INFO): SBD MOMSN=16961750
2022-07-11T19:21:10.542Z,1657567270.542 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T031358/Express0001.lzma
2022-07-11T19:21:11.545Z,1657567271.545 [DataOverHttps](INFO): Moved sent file to Logs/19241126T031358/Express0001.lzma.bak
2022-07-11T19:21:11.545Z,1657567271.545 [DataOverHttps](INFO): SBD MOMSN=16961804
2022-07-11T19:21:37.731Z,1657567297.731 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T031723/Express0001.lzma
2022-07-11T19:21:38.733Z,1657567298.733 [DataOverHttps](INFO): Moved sent file to Logs/19241126T031723/Express0001.lzma.bak
2022-07-11T19:21:38.733Z,1657567298.733 [DataOverHttps](INFO): SBD MOMSN=16961992
2022-07-11T19:21:59.090Z,1657567319.090 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T031941/Express0001.lzma
2022-07-11T19:22:00.093Z,1657567320.093 [DataOverHttps](INFO): Moved sent file to Logs/19241126T031941/Express0001.lzma.bak
2022-07-11T19:22:00.093Z,1657567320.093 [DataOverHttps](INFO): SBD MOMSN=16961994
2022-07-11T19:22:35.903Z,1657567355.903 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T032324/Express0001.lzma
2022-07-11T19:22:36.905Z,1657567356.905 [DataOverHttps](INFO): Moved sent file to Logs/19241126T032324/Express0001.lzma.bak
2022-07-11T19:22:36.905Z,1657567356.905 [DataOverHttps](INFO): SBD MOMSN=16961996
2022-07-11T19:22:57.802Z,1657567377.802 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T032713/Express0001.lzma
2022-07-11T19:22:58.805Z,1657567378.805 [DataOverHttps](INFO): Moved sent file to Logs/19241126T032713/Express0001.lzma.bak
2022-07-11T19:22:58.805Z,1657567378.805 [DataOverHttps](IMPORTANT): SBD MOMSN=16961999, MTMSN=20220711T192257
2022-07-11T19:23:10.651Z,1657567390.651 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2022-07-11T19:23:10.756Z,1657567390.756 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-07-11T19:23:10.757Z,1657567390.757 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-07-11T19:23:10.909Z,1657567390.909 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2022-07-11T19:23:10.912Z,1657567390.912 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2022-07-11T19:23:10.914Z,1657567390.914 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2022-07-11T19:23:10.974Z,1657567390.974 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2022-07-11T19:23:10.976Z,1657567390.976 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2022-07-11T19:23:10.979Z,1657567390.979 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2022-07-11T19:23:10.997Z,1657567390.997 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2022-07-11T19:23:11.003Z,1657567391.003 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2022-07-11T19:23:11.005Z,1657567391.005 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2022-07-11T19:23:11.014Z,1657567391.014 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2022-07-11T19:23:11.065Z,1657567391.065 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2022-07-11T19:23:11.088Z,1657567391.088 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2022-07-11T19:23:11.090Z,1657567391.090 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2022-07-11T19:23:11.109Z,1657567391.109 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2022-07-11T19:23:11.109Z,1657567391.109 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2022-07-11T19:23:12.216Z,1657567392.216 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2022-07-11T19:23:12.218Z,1657567392.218 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2022-07-11T19:23:12.224Z,1657567392.224 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2022-07-11T19:23:12.226Z,1657567392.226 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2022-07-11T19:23:12.232Z,1657567392.232 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2022-07-11T19:23:12.234Z,1657567392.234 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2022-07-11T19:23:12.299Z,1657567392.299 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2022-07-11T19:23:12.316Z,1657567392.316 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2022-07-11T19:23:12.319Z,1657567392.319 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2022-07-11T19:23:12.389Z,1657567392.389 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2022-07-11T19:23:12.391Z,1657567392.391 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2022-07-11T19:23:12.417Z,1657567392.417 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2022-07-11T19:23:12.456Z,1657567392.456 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2022-07-11T19:23:12.458Z,1657567392.458 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2022-07-11T19:23:12.533Z,1657567392.533 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-07-11T19:23:12.556Z,1657567392.556 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2022-07-11T19:23:12.590Z,1657567392.590 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2022-07-11T19:23:12.641Z,1657567392.641 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2022-07-11T19:23:12.666Z,1657567392.666 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2022-07-11T19:23:12.712Z,1657567392.712 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2022-07-11T19:23:12.735Z,1657567392.735 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2022-07-11T19:23:12.824Z,1657567392.824 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2022-07-11T19:23:12.826Z,1657567392.826 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2022-07-11T19:23:12.841Z,1657567392.841 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2022-07-11T19:23:12.859Z,1657567392.859 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2022-07-11T19:23:12.885Z,1657567392.885 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-07-11T19:23:12.905Z,1657567392.905 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2022-07-11T19:23:12.919Z,1657567392.919 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2022-07-11T19:23:12.921Z,1657567392.921 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2022-07-11T19:23:12.931Z,1657567392.931 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2022-07-11T19:23:12.934Z,1657567392.934 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2022-07-11T19:23:12.948Z,1657567392.948 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2022-07-11T19:23:12.951Z,1657567392.951 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2022-07-11T19:23:12.961Z,1657567392.961 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2022-07-11T19:23:12.972Z,1657567392.972 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2022-07-11T19:23:12.996Z,1657567392.996 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2022-07-11T19:23:12.998Z,1657567392.998 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2022-07-11T19:23:13.012Z,1657567393.012 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2022-07-11T19:23:13.015Z,1657567393.015 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2022-07-11T19:23:13.045Z,1657567393.045 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2022-07-11T19:23:13.055Z,1657567393.055 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2022-07-11T19:23:13.156Z,1657567393.156 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2022-07-11T19:23:13.187Z,1657567393.187 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2022-07-11T19:23:13.189Z,1657567393.189 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2022-07-11T19:23:13.194Z,1657567393.194 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2022-07-11T19:23:13.201Z,1657567393.201 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2022-07-11T19:23:13.205Z,1657567393.205 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2022-07-11T19:23:13.264Z,1657567393.264 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2022-07-11T19:23:13.266Z,1657567393.266 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2022-07-11T19:23:13.276Z,1657567393.276 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2022-07-11T19:23:13.279Z,1657567393.279 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2022-07-11T19:23:13.309Z,1657567393.309 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2022-07-11T19:23:13.311Z,1657567393.311 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2022-07-11T19:23:13.314Z,1657567393.314 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2022-07-11T19:23:13.317Z,1657567393.317 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2022-07-11T19:23:13.327Z,1657567393.327 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2022-07-11T19:23:13.333Z,1657567393.333 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2022-07-11T19:23:13.335Z,1657567393.335 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2022-07-11T19:23:13.337Z,1657567393.337 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2022-07-11T19:23:13.342Z,1657567393.342 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2022-07-11T19:23:13.351Z,1657567393.351 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2022-07-11T19:23:13.360Z,1657567393.360 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2022-07-11T19:23:13.368Z,1657567393.368 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2022-07-11T19:23:13.376Z,1657567393.376 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2022-07-11T19:23:13.378Z,1657567393.378 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2022-07-11T19:23:13.470Z,1657567393.470 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T19:23:13.489Z,1657567393.489 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-07-11T19:23:13.508Z,1657567393.508 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T19:23:13.517Z,1657567393.517 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T19:23:13.527Z,1657567393.527 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-07-11T19:23:13.540Z,1657567393.540 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T19:23:13.552Z,1657567393.552 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-07-11T19:23:13.563Z,1657567393.563 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-07-11T19:23:13.574Z,1657567393.574 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2022-07-11T19:23:14.131Z,1657567394.131 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2022-07-11T19:23:14.153Z,1657567394.153 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2022-07-11T19:23:14.180Z,1657567394.180 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2022-07-11T19:23:14.183Z,1657567394.183 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2022-07-11T19:23:14.193Z,1657567394.193 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2022-07-11T19:23:14.202Z,1657567394.202 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2022-07-11T19:23:14.224Z,1657567394.224 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2022-07-11T19:23:14.226Z,1657567394.226 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2022-07-11T19:23:14.236Z,1657567394.236 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2022-07-11T19:23:14.238Z,1657567394.238 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2022-07-11T19:23:14.249Z,1657567394.249 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2022-07-11T19:23:14.251Z,1657567394.251 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2022-07-11T19:23:14.295Z,1657567394.295 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2022-07-11T19:23:14.298Z,1657567394.298 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2022-07-11T19:23:14.389Z,1657567394.389 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2022-07-11T19:23:14.482Z,1657567394.482 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2022-07-11T19:23:14.502Z,1657567394.502 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2022-07-11T19:23:14.554Z,1657567394.554 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2022-07-11T19:23:14.579Z,1657567394.579 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2022-07-11T19:23:14.589Z,1657567394.589 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2022-07-11T19:23:14.647Z,1657567394.647 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2022-07-11T19:23:14.651Z,1657567394.651 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2022-07-11T19:23:14.697Z,1657567394.697 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2022-07-11T19:23:14.768Z,1657567394.768 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2022-07-11T19:23:14.771Z,1657567394.771 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2022-07-11T19:23:14.812Z,1657567394.812 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2022-07-11T19:23:14.814Z,1657567394.814 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2022-07-11T19:23:14.859Z,1657567394.859 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2022-07-11T19:23:14.912Z,1657567394.912 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2022-07-11T19:23:14.914Z,1657567394.914 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2022-07-11T19:23:14.916Z,1657567394.916 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2022-07-11T19:23:14.917Z,1657567394.917 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2022-07-11T19:23:14.919Z,1657567394.919 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2022-07-11T19:23:14.971Z,1657567394.971 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2022-07-11T19:23:14.974Z,1657567394.974 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2022-07-11T19:23:15.158Z,1657567395.158 [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-07-11T19:23:15.175Z,1657567395.175 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-07-11T19:23:15.275Z,1657567395.275 [Default] Stopped
2022-07-11T19:23:15.277Z,1657567395.277 [Default](DEBUG): Aggregate::uninitialize Default
2022-07-11T19:23:15.277Z,1657567395.277 [Default:B.GoToSurface] Stopped
2022-07-11T19:23:15.277Z,1657567395.277 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-07-11T19:23:15.277Z,1657567395.277 [Default:CheckIn] Stopped
2022-07-11T19:23:15.278Z,1657567395.278 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-07-11T19:23:15.278Z,1657567395.278 [Default:CheckIn:Read_Iridium] Stopped
2022-07-11T19:23:15.278Z,1657567395.278 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:A] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:B] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:C] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:D] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:E] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:F] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2022-07-11T19:23:15.280Z,1657567395.280 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2022-07-11T19:23:15.281Z,1657567395.281 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2022-07-11T19:23:15.281Z,1657567395.281 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T19:23:15.281Z,1657567395.281 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T19:23:15.282Z,1657567395.282 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2022-07-11T19:23:15.282Z,1657567395.282 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2022-07-11T19:23:15.282Z,1657567395.282 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-07-11T19:23:15.282Z,1657567395.282 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-07-11T19:23:15.283Z,1657567395.283 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2022-07-11T19:23:15.291Z,1657567395.291 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2022-07-11T19:23:15.292Z,1657567395.292 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T19:23:15.293Z,1657567395.293 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T19:23:15.319Z,1657567395.319 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2022-07-11T19:23:15.319Z,1657567395.319 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2022-07-11T19:23:15.319Z,1657567395.319 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T19:23:15.321Z,1657567395.321 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T19:23:15.332Z,1657567395.332 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2022-07-11T19:23:15.332Z,1657567395.332 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2022-07-11T19:23:15.332Z,1657567395.332 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-07-11T19:23:15.333Z,1657567395.333 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-07-11T19:23:15.335Z,1657567395.335 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2022-07-11T19:23:15.335Z,1657567395.335 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2022-07-11T19:23:15.347Z,1657567395.347 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T19:23:15.347Z,1657567395.347 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T19:23:15.348Z,1657567395.348 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2022-07-11T19:23:15.349Z,1657567395.349 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2022-07-11T19:23:15.349Z,1657567395.349 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-07-11T19:23:15.349Z,1657567395.349 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-07-11T19:23:15.350Z,1657567395.350 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2022-07-11T19:23:15.350Z,1657567395.350 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2022-07-11T19:23:15.350Z,1657567395.350 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-07-11T19:23:15.350Z,1657567395.350 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-07-11T19:23:15.351Z,1657567395.351 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2022-07-11T19:23:15.363Z,1657567395.363 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2022-07-11T19:23:15.363Z,1657567395.363 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-07-11T19:23:15.363Z,1657567395.363 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-07-11T19:23:15.363Z,1657567395.363 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2022-07-11T19:23:15.364Z,1657567395.364 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2022-07-11T19:23:15.364Z,1657567395.364 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2022-07-11T19:23:15.364Z,1657567395.364 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-07-11T19:23:15.364Z,1657567395.364 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2022-07-11T19:23:15.365Z,1657567395.365 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2022-07-11T19:23:15.365Z,1657567395.365 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2022-07-11T19:23:15.365Z,1657567395.365 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-07-11T19:23:15.365Z,1657567395.365 [ballast_and_trim:Science] Running Loop=1
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T19:23:15.366Z,1657567395.366 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2022-07-11T19:23:15.367Z,1657567395.367 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2022-07-11T19:23:15.367Z,1657567395.367 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-07-11T19:23:15.367Z,1657567395.367 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T19:23:15.367Z,1657567395.367 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2022-07-11T19:23:15.451Z,1657567395.451 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2022-07-11T19:23:15.451Z,1657567395.451 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-07-11T19:23:15.451Z,1657567395.451 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-07-11T19:23:15.451Z,1657567395.451 [ballast_and_trim:Science:PeakDetectOil] Stopped
2022-07-11T19:23:15.451Z,1657567395.451 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectChl] Stopped
2022-07-11T19:23:15.452Z,1657567395.452 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:Read_Oil] Stopped
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:F] Stopped
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:E] Running Loop=1
2022-07-11T19:23:15.453Z,1657567395.453 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-07-11T19:23:15.454Z,1657567395.454 [ballast_and_trim:Science:D] Stopped
2022-07-11T19:23:15.454Z,1657567395.454 [ballast_and_trim:Science:C] Running Loop=1
2022-07-11T19:23:15.454Z,1657567395.454 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-07-11T19:23:15.454Z,1657567395.454 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-07-11T19:23:15.455Z,1657567395.455 [ballast_and_trim:Science:B] Running Loop=1
2022-07-11T19:23:15.485Z,1657567395.485 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-07-11T19:23:15.486Z,1657567395.486 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-07-11T19:23:15.486Z,1657567395.486 [ballast_and_trim:Science:A] Stopped
2022-07-11T19:23:15.736Z,1657567395.736 [WetLabsBB2FL](INFO): Powering up
2022-07-11T19:23:15.796Z,1657567395.796 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-07-11T19:23:15.797Z,1657567395.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2022-07-11T19:23:15.823Z,1657567395.823 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2022-07-11T19:23:15.823Z,1657567395.823 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2022-07-11T19:23:15.823Z,1657567395.823 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2022-07-11T19:23:15.824Z,1657567395.824 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2022-07-11T19:23:15.825Z,1657567395.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2022-07-11T19:23:15.825Z,1657567395.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-07-11T19:23:15.825Z,1657567395.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2022-07-11T19:23:15.825Z,1657567395.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-07-11T19:23:15.825Z,1657567395.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-07-11T19:23:15.825Z,1657567395.825 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-07-11T19:23:15.826Z,1657567395.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-07-11T19:23:15.826Z,1657567395.826 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-07-11T19:23:16.121Z,1657567396.121 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2022-07-11T19:23:16.121Z,1657567396.121 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2022-07-11T19:23:16.122Z,1657567396.122 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2022-07-11T19:23:16.122Z,1657567396.122 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2022-07-11T19:23:16.127Z,1657567396.127 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2022-07-11T19:23:16.128Z,1657567396.128 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-07-11T19:23:16.128Z,1657567396.128 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2022-07-11T19:23:16.128Z,1657567396.128 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2022-07-11T19:23:16.129Z,1657567396.129 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-07-11T19:23:16.131Z,1657567396.131 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2022-07-11T19:23:16.131Z,1657567396.131 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-07-11T19:23:16.514Z,1657567396.514 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2022-07-11T19:23:16.514Z,1657567396.514 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2022-07-11T19:23:16.514Z,1657567396.514 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T19:23:16.515Z,1657567396.515 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-07-11T19:23:16.515Z,1657567396.515 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2022-07-11T19:23:16.515Z,1657567396.515 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-07-11T19:23:16.523Z,1657567396.523 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2022-07-11T19:23:16.523Z,1657567396.523 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-07-11T19:23:16.523Z,1657567396.523 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-07-11T19:23:16.523Z,1657567396.523 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2022-07-11T19:23:16.524Z,1657567396.524 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2022-07-11T19:23:16.890Z,1657567396.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-07-11T19:23:16.890Z,1657567396.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2022-07-11T19:23:16.890Z,1657567396.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2022-07-11T19:23:16.891Z,1657567396.891 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2022-07-11T19:23:16.891Z,1657567396.891 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2022-07-11T19:23:18.932Z,1657567398.932 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-07-11T19:23:26.526Z,1657567406.526 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T033038/Express0001.lzma
2022-07-11T19:23:27.529Z,1657567407.529 [DataOverHttps](INFO): Moved sent file to Logs/19241126T033038/Express0001.lzma.bak
2022-07-11T19:23:27.529Z,1657567407.529 [DataOverHttps](INFO): SBD MOMSN=16962002
2022-07-11T19:24:11.267Z,1657567451.267 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2022-07-11T19:24:11.267Z,1657567451.267 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2022-07-11T19:24:11.267Z,1657567451.267 [BuoyancyServo] Communications Fault, FailCount= 1
2022-07-11T19:24:11.267Z,1657567451.267 [BuoyancyServo](ERROR): Communications Fault
2022-07-11T19:24:11.284Z,1657567451.284 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2022-07-11T19:24:13.132Z,1657567453.132 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-07-11T19:24:13.133Z,1657567453.133 [BuoyancyServo](INFO): Powering down
2022-07-11T19:24:13.965Z,1657567453.965 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2022-07-11T19:24:13.965Z,1657567453.965 [BuoyancyServo] No Fault, FailCount= 1
2022-07-11T19:24:14.338Z,1657567454.338 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-07-11T19:24:14.368Z,1657567454.368 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-07-11T19:25:08.889Z,1657567508.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:25:14.912Z,1657567514.912 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.298866
2022-07-11T19:25:33.988Z,1657567533.988 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-07-11T19:25:34.235Z,1657567534.235 [Radio_Surface](INFO): Powering down
2022-07-11T19:43:16.957Z,1657568596.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](INFO): Timed out from 2022-07-11T19:23:16.9Z
2022-07-11T19:43:16.957Z,1657568596.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout] Running Loop=1
2022-07-11T19:43:16.957Z,1657568596.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout
2022-07-11T19:43:16.958Z,1657568596.958 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:A] Running Loop=1
2022-07-11T19:43:16.958Z,1657568596.958 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:A](IMPORTANT): Timed out trying to reach the target depth. Stopping mission at current depth of 5.168584 m 
2022-07-11T19:43:16.958Z,1657568596.958 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:A] Stopped
2022-07-11T19:43:16.959Z,1657568596.959 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute] Running Loop=1
2022-07-11T19:43:17.342Z,1657568597.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](INFO): Executing command stop
2022-07-11T19:43:17.342Z,1657568597.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute] Stopped
2022-07-11T19:43:17.342Z,1657568597.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout
2022-07-11T19:43:17.342Z,1657568597.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2022-07-11T19:43:17.343Z,1657568597.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-07-11T19:43:17.343Z,1657568597.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2022-07-11T19:43:17.343Z,1657568597.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-07-11T19:43:17.343Z,1657568597.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2022-07-11T19:43:17.343Z,1657568597.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-07-11T19:43:17.344Z,1657568597.344 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2022-07-11T19:43:17.345Z,1657568597.345 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2022-07-11T19:43:17.392Z,1657568597.392 [CommandExec](IMPORTANT): got command stop
2022-07-11T19:43:17.392Z,1657568597.392 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2022-07-11T19:43:17.746Z,1657568597.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 5.169448 m 
2022-07-11T19:43:17.746Z,1657568597.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2022-07-11T19:43:17.746Z,1657568597.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2022-07-11T19:43:17.746Z,1657568597.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2022-07-11T19:43:17.746Z,1657568597.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-07-11T19:43:17.746Z,1657568597.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-07-11T19:43:17.749Z,1657568597.749 [MissionManager](INFO): MissionManager is completed.
2022-07-11T19:43:17.749Z,1657568597.749 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim] Stopped
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim:Science] Stopped
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim:Science:B] Stopped
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim:Science:C] Stopped
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim:Science:E] Stopped
2022-07-11T19:43:17.749Z,1657568597.749 [ballast_and_trim:Science:OceanCurrent] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-07-11T19:43:17.750Z,1657568597.750 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2022-07-11T19:43:17.751Z,1657568597.751 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-07-11T19:43:18.031Z,1657568598.031 [WetLabsBB2FL](INFO): Powering down
2022-07-11T19:43:18.149Z,1657568598.149 [MissionManager](IMPORTANT): Started mission Default
2022-07-11T19:43:18.149Z,1657568598.149 [Default] Running Loop=1
2022-07-11T19:43:18.149Z,1657568598.149 [Default](DEBUG): Aggregate::initialize Default
2022-07-11T19:43:18.149Z,1657568598.149 [Default:B.GoToSurface] Running Loop=1
2022-07-11T19:43:18.149Z,1657568598.149 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-07-11T19:43:18.150Z,1657568598.150 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-07-11T19:43:18.150Z,1657568598.150 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-07-11T19:43:18.150Z,1657568598.150 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-07-11T19:43:18.150Z,1657568598.150 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-07-11T19:43:18.151Z,1657568598.151 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-07-11T19:43:18.151Z,1657568598.151 [Default:A.Wait] Running Loop=1
2022-07-11T19:43:18.151Z,1657568598.151 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-07-11T19:43:18.787Z,1657568598.787 [Radio_Surface](INFO): Powering up
2022-07-11T19:43:24.407Z,1657568604.407 [DataOverHttps](INFO): Radio surface powered ON.
2022-07-11T19:43:31.488Z,1657568611.488 [Default:A.Wait](INFO): Done Waiting.
2022-07-11T19:43:31.488Z,1657568611.488 [Default:A.Wait] Stopped
2022-07-11T19:43:31.488Z,1657568611.488 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-07-11T19:43:54.536Z,1657568634.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:44:17.126Z,1657568657.126 [Default:CheckIn] Running Loop=1
2022-07-11T19:44:17.126Z,1657568657.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-07-11T19:44:17.126Z,1657568657.126 [Default:CheckIn:Read_GPS] Running Loop=1
2022-07-11T19:44:18.732Z,1657568658.732 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-07-11T19:44:18.733Z,1657568658.733 [NAL9602] Data Fault, FailCount= 1
2022-07-11T19:44:18.733Z,1657568658.733 [NAL9602](ERROR): Data Fault
2022-07-11T19:44:18.844Z,1657568658.844 [CBIT](ERROR): Data Fault in component: NAL9602
2022-07-11T19:44:19.143Z,1657568659.143 [NAL9602](INFO): Powering down
2022-07-11T19:44:20.031Z,1657568660.031 [CBIT](INFO): Clearing failed state for component NAL9602
2022-07-11T19:44:20.031Z,1657568660.031 [NAL9602] No Fault, FailCount= 1
2022-07-11T19:44:29.708Z,1657568669.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:44:49.434Z,1657568689.434 [NAL9602](INFO): Powering up NAL9602
2022-07-11T19:45:00.345Z,1657568700.345 [NAL9602](INFO): NAL9602 initialized
2022-07-11T19:45:04.859Z,1657568704.859 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:45:39.999Z,1657568739.999 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:46:15.139Z,1657568775.139 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-07-11T19:46:30.095Z,1657568790.095 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002903
2022-07-11T19:49:17.402Z,1657568957.402 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-07-11T19:44:17.1Z
2022-07-11T19:49:17.402Z,1657568957.402 [Default:CheckIn:Read_GPS] Stopped
2022-07-11T19:49:17.403Z,1657568957.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-07-11T19:49:27.886Z,1657568967.886 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20220711T183445/Courier0013.lzma
2022-07-11T19:49:28.889Z,1657568968.889 [DataOverHttps](INFO): Moved sent file to Logs/20220711T183445/Courier0013.lzma.bak
2022-07-11T19:49:28.889Z,1657568968.889 [DataOverHttps](INFO): SBD MOMSN=16962315
2022-07-11T19:49:57.059Z,1657568997.059 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220711T183445/Courier0016.lzma
2022-07-11T19:49:58.061Z,1657568998.061 [DataOverHttps](INFO): Moved sent file to Logs/20220711T183445/Courier0016.lzma.bak
2022-07-11T19:49:58.061Z,1657568998.061 [DataOverHttps](INFO): SBD MOMSN=16962318
2022-07-11T19:50:03.870Z,1657569003.870 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-07-11T19:50:33.235Z,1657569033.235 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T033608/Express0001.lzma
2022-07-11T19:50:34.237Z,1657569034.237 [DataOverHttps](INFO): Moved sent file to Logs/19241126T033608/Express0001.lzma.bak
2022-07-11T19:50:34.237Z,1657569034.237 [DataOverHttps](INFO): SBD MOMSN=16962321
2022-07-11T19:50:34.368Z,1657569034.368 [NAL9602](INFO): Not Powering down - fast GPS
2022-07-11T19:51:08.335Z,1657569068.335 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T033752/Express0001.lzma
2022-07-11T19:51:09.337Z,1657569069.337 [DataOverHttps](INFO): Moved sent file to Logs/19241126T033752/Express0001.lzma.bak
2022-07-11T19:51:09.337Z,1657569069.337 [DataOverHttps](INFO): SBD MOMSN=16962323
2022-07-11T19:51:36.215Z,1657569096.215 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T034139/Express0001.lzma
2022-07-11T19:51:37.217Z,1657569097.217 [DataOverHttps](INFO): Moved sent file to Logs/19241126T034139/Express0001.lzma.bak
2022-07-11T19:51:37.217Z,1657569097.217 [DataOverHttps](INFO): SBD MOMSN=16962325
2022-07-11T19:51:57.122Z,1657569117.122 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T034352/Express0001.lzma
2022-07-11T19:51:58.125Z,1657569118.125 [DataOverHttps](INFO): Moved sent file to Logs/19241126T034352/Express0001.lzma.bak
2022-07-11T19:51:58.125Z,1657569118.125 [DataOverHttps](INFO): SBD MOMSN=16962327
2022-07-11T19:52:31.259Z,1657569151.259 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T034740/Express0001.lzma
2022-07-11T19:52:32.261Z,1657569152.261 [DataOverHttps](INFO): Moved sent file to Logs/19241126T034740/Express0001.lzma.bak
2022-07-11T19:52:32.261Z,1657569152.261 [DataOverHttps](INFO): SBD MOMSN=16962329
2022-07-11T19:52:53.326Z,1657569173.326 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T035108/Express0001.lzma
2022-07-11T19:52:54.330Z,1657569174.330 [DataOverHttps](INFO): Moved sent file to Logs/19241126T035108/Express0001.lzma.bak
2022-07-11T19:52:54.330Z,1657569174.330 [DataOverHttps](INFO): SBD MOMSN=16962331
2022-07-11T19:53:20.307Z,1657569200.307 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T035458/Express0001.lzma
2022-07-11T19:53:21.309Z,1657569201.309 [DataOverHttps](INFO): Moved sent file to Logs/19241126T035458/Express0001.lzma.bak
2022-07-11T19:53:21.309Z,1657569201.309 [DataOverHttps](INFO): SBD MOMSN=16962333
2022-07-11T19:53:42.159Z,1657569222.159 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T035822/Express0001.lzma
2022-07-11T19:53:43.162Z,1657569223.162 [DataOverHttps](INFO): Moved sent file to Logs/19241126T035822/Express0001.lzma.bak
2022-07-11T19:53:43.162Z,1657569223.162 [DataOverHttps](INFO): SBD MOMSN=16962335
2022-07-11T19:54:10.742Z,1657569250.742 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T040211/Express0001.lzma
2022-07-11T19:54:11.745Z,1657569251.745 [DataOverHttps](INFO): Moved sent file to Logs/19241126T040211/Express0001.lzma.bak
2022-07-11T19:54:11.745Z,1657569251.745 [DataOverHttps](INFO): SBD MOMSN=16962337
2022-07-11T19:54:38.618Z,1657569278.618 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T040536/Express0001.lzma
2022-07-11T19:54:39.621Z,1657569279.621 [DataOverHttps](INFO): Moved sent file to Logs/19241126T040536/Express0001.lzma.bak
2022-07-11T19:54:39.621Z,1657569279.621 [DataOverHttps](INFO): SBD MOMSN=16962339
2022-07-11T19:55:18.226Z,1657569318.226 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T040923/Express0001.lzma
2022-07-11T19:55:19.229Z,1657569319.229 [DataOverHttps](INFO): Moved sent file to Logs/19241126T040923/Express0001.lzma.bak
2022-07-11T19:55:19.229Z,1657569319.229 [DataOverHttps](INFO): SBD MOMSN=16962348
2022-07-11T19:55:39.242Z,1657569339.242 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T041252/Express0001.lzma
2022-07-11T19:55:40.245Z,1657569340.245 [DataOverHttps](INFO): Moved sent file to Logs/19241126T041252/Express0001.lzma.bak
2022-07-11T19:55:40.245Z,1657569340.245 [DataOverHttps](INFO): SBD MOMSN=16962352
2022-07-11T19:56:09.799Z,1657569369.799 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T041641/Express0001.lzma
2022-07-11T19:56:10.801Z,1657569370.801 [DataOverHttps](INFO): Moved sent file to Logs/19241126T041641/Express0001.lzma.bak
2022-07-11T19:56:10.801Z,1657569370.801 [DataOverHttps](INFO): SBD MOMSN=16962354
2022-07-11T19:56:37.890Z,1657569397.890 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T042006/Express0001.lzma
2022-07-11T19:56:38.893Z,1657569398.893 [DataOverHttps](INFO): Moved sent file to Logs/19241126T042006/Express0001.lzma.bak
2022-07-11T19:56:38.893Z,1657569398.893 [DataOverHttps](INFO): SBD MOMSN=16962356
2022-07-11T19:57:14.819Z,1657569434.819 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T042354/Express0001.lzma
2022-07-11T19:57:15.821Z,1657569435.821 [DataOverHttps](INFO): Moved sent file to Logs/19241126T042354/Express0001.lzma.bak
2022-07-11T19:57:15.821Z,1657569435.821 [DataOverHttps](INFO): SBD MOMSN=16962358
2022-07-11T19:57:37.022Z,1657569457.022 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T042723/Express0001.lzma
2022-07-11T19:57:38.025Z,1657569458.025 [DataOverHttps](INFO): Moved sent file to Logs/19241126T042723/Express0001.lzma.bak
2022-07-11T19:57:38.025Z,1657569458.025 [DataOverHttps](INFO): SBD MOMSN=16962360
2022-07-11T19:58:13.718Z,1657569493.718 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T043110/Express0001.lzma
2022-07-11T19:58:14.721Z,1657569494.721 [DataOverHttps](INFO): Moved sent file to Logs/19241126T043110/Express0001.lzma.bak
2022-07-11T19:58:14.721Z,1657569494.721 [DataOverHttps](INFO): SBD MOMSN=16962363
2022-07-11T19:58:53.598Z,1657569533.598 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T043321/Express0001.lzma
2022-07-11T19:58:54.601Z,1657569534.601 [DataOverHttps](INFO): Moved sent file to Logs/19241126T043321/Express0001.lzma.bak
2022-07-11T19:58:54.601Z,1657569534.601 [DataOverHttps](INFO): SBD MOMSN=16962368
2022-07-11T19:59:14.626Z,1657569554.626 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T043709/Express0001.lzma
2022-07-11T19:59:15.629Z,1657569555.629 [DataOverHttps](INFO): Moved sent file to Logs/19241126T043709/Express0001.lzma.bak
2022-07-11T19:59:15.629Z,1657569555.629 [DataOverHttps](INFO): SBD MOMSN=16962378
2022-07-11T19:59:46.034Z,1657569586.034 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T044037/Express0001.lzma
2022-07-11T19:59:47.037Z,1657569587.037 [DataOverHttps](INFO): Moved sent file to Logs/19241126T044037/Express0001.lzma.bak
2022-07-11T19:59:47.037Z,1657569587.037 [DataOverHttps](INFO): SBD MOMSN=16962380
2022-07-11T20:00:10.711Z,1657569610.711 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T044427/Express0001.lzma
2022-07-11T20:00:11.713Z,1657569611.713 [DataOverHttps](INFO): Moved sent file to Logs/19241126T044427/Express0001.lzma.bak
2022-07-11T20:00:11.713Z,1657569611.713 [DataOverHttps](INFO): SBD MOMSN=16962382
2022-07-11T20:00:56.726Z,1657569656.726 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T044752/Express0001.lzma
2022-07-11T20:00:57.729Z,1657569657.729 [DataOverHttps](INFO): Moved sent file to Logs/19241126T044752/Express0001.lzma.bak
2022-07-11T20:00:57.729Z,1657569657.729 [DataOverHttps](INFO): SBD MOMSN=16962387
2022-07-11T20:01:24.043Z,1657569684.043 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T045142/Express0001.lzma
2022-07-11T20:01:25.045Z,1657569685.045 [DataOverHttps](INFO): Moved sent file to Logs/19241126T045142/Express0001.lzma.bak
2022-07-11T20:01:25.045Z,1657569685.045 [DataOverHttps](INFO): SBD MOMSN=16962417
2022-07-11T20:01:44.982Z,1657569704.982 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T045508/Express0001.lzma
2022-07-11T20:01:45.985Z,1657569705.985 [DataOverHttps](INFO): Moved sent file to Logs/19241126T045508/Express0001.lzma.bak
2022-07-11T20:01:45.985Z,1657569705.985 [DataOverHttps](INFO): SBD MOMSN=16962419
2022-07-11T20:02:11.063Z,1657569731.063 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T045859/Express0001.lzma
2022-07-11T20:02:12.065Z,1657569732.065 [DataOverHttps](INFO): Moved sent file to Logs/19241126T045859/Express0001.lzma.bak
2022-07-11T20:02:12.066Z,1657569732.066 [DataOverHttps](INFO): SBD MOMSN=16962421
2022-07-11T20:02:46.279Z,1657569766.279 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T050226/Express0001.lzma
2022-07-11T20:02:47.281Z,1657569767.281 [DataOverHttps](INFO): Moved sent file to Logs/19241126T050226/Express0001.lzma.bak
2022-07-11T20:02:47.281Z,1657569767.281 [DataOverHttps](INFO): SBD MOMSN=16962423
2022-07-11T20:03:13.858Z,1657569793.858 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T050440/Express0001.lzma
2022-07-11T20:03:14.861Z,1657569794.861 [DataOverHttps](INFO): Moved sent file to Logs/19241126T050440/Express0001.lzma.bak
2022-07-11T20:03:14.861Z,1657569794.861 [DataOverHttps](INFO): SBD MOMSN=16962425
2022-07-11T20:03:34.911Z,1657569814.911 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T050939/Express0001.lzma
2022-07-11T20:03:35.913Z,1657569815.913 [DataOverHttps](INFO): Moved sent file to Logs/19241126T050939/Express0001.lzma.bak
2022-07-11T20:03:35.913Z,1657569815.913 [DataOverHttps](INFO): SBD MOMSN=16962427
2022-07-11T20:04:00.927Z,1657569840.927 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T051330/Express0001.lzma
2022-07-11T20:04:01.929Z,1657569841.929 [DataOverHttps](INFO): Moved sent file to Logs/19241126T051330/Express0001.lzma.bak
2022-07-11T20:04:01.930Z,1657569841.930 [DataOverHttps](INFO): SBD MOMSN=16962429
2022-07-11T20:04:30.298Z,1657569870.298 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T051540/Express0001.lzma
2022-07-11T20:04:31.301Z,1657569871.301 [DataOverHttps](INFO): Moved sent file to Logs/19241126T051540/Express0001.lzma.bak
2022-07-11T20:04:31.301Z,1657569871.301 [DataOverHttps](INFO): SBD MOMSN=16962431
2022-07-11T20:05:07.730Z,1657569907.730 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T051927/Express0001.lzma
2022-07-11T20:05:08.733Z,1657569908.733 [DataOverHttps](INFO): Moved sent file to Logs/19241126T051927/Express0001.lzma.bak
2022-07-11T20:05:08.733Z,1657569908.733 [DataOverHttps](INFO): SBD MOMSN=16962433
2022-07-11T20:05:29.922Z,1657569929.922 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T052254/Express0001.lzma
2022-07-11T20:05:30.925Z,1657569930.925 [DataOverHttps](INFO): Moved sent file to Logs/19241126T052254/Express0001.lzma.bak
2022-07-11T20:05:30.925Z,1657569930.925 [DataOverHttps](INFO): SBD MOMSN=16962435
2022-07-11T20:06:02.247Z,1657569962.247 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T052823/Express0001.lzma
2022-07-11T20:06:03.249Z,1657569963.249 [DataOverHttps](INFO): Moved sent file to Logs/19241126T052823/Express0001.lzma.bak
2022-07-11T20:06:03.249Z,1657569963.249 [DataOverHttps](INFO): SBD MOMSN=16962438
2022-07-11T20:06:45.587Z,1657570005.587 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T053011/Express0001.lzma
2022-07-11T20:06:46.589Z,1657570006.589 [DataOverHttps](INFO): Moved sent file to Logs/19241126T053011/Express0001.lzma.bak
2022-07-11T20:06:46.589Z,1657570006.589 [DataOverHttps](INFO): SBD MOMSN=16962440
2022-07-11T20:07:10.674Z,1657570030.674 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T053400/Express0001.lzma
2022-07-11T20:07:11.677Z,1657570031.677 [DataOverHttps](INFO): Moved sent file to Logs/19241126T053400/Express0001.lzma.bak
2022-07-11T20:07:11.677Z,1657570031.677 [DataOverHttps](INFO): SBD MOMSN=16962443
2022-07-11T20:07:38.822Z,1657570058.822 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T053610/Express0001.lzma
2022-07-11T20:07:39.825Z,1657570059.825 [DataOverHttps](INFO): Moved sent file to Logs/19241126T053610/Express0001.lzma.bak
2022-07-11T20:07:39.825Z,1657570059.825 [DataOverHttps](INFO): SBD MOMSN=16962445
2022-07-11T20:08:08.387Z,1657570088.387 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T053957/Express0001.lzma
2022-07-11T20:08:09.389Z,1657570089.389 [DataOverHttps](INFO): Moved sent file to Logs/19241126T053957/Express0001.lzma.bak
2022-07-11T20:08:09.389Z,1657570089.389 [DataOverHttps](INFO): SBD MOMSN=16962447
2022-07-11T20:08:24.808Z,1657570104.808 [CommandExec](IMPORTANT): got command show stack
2022-07-11T20:08:24.809Z,1657570104.809 [CommandExec](IMPORTANT): Behavior Stack:

2022-07-11T20:08:24.809Z,1657570104.809 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2022-07-11T20:08:24.809Z,1657570104.809 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium
2022-07-11T20:08:36.303Z,1657570116.303 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T054321/Express0001.lzma
2022-07-11T20:08:37.305Z,1657570117.305 [DataOverHttps](INFO): Moved sent file to Logs/19241126T054321/Express0001.lzma.bak
2022-07-11T20:08:37.305Z,1657570117.305 [DataOverHttps](INFO): SBD MOMSN=16962449
2022-07-11T20:09:08.887Z,1657570148.887 [DataOverHttps](INFO): Sending 18 bytes from file Logs/19241126T054708/Express0001.lzma
2022-07-11T20:09:09.889Z,1657570149.889 [DataOverHttps](INFO): Moved sent file to Logs/19241126T054708/Express0001.lzma.bak
2022-07-11T20:09:09.889Z,1657570149.889 [DataOverHttps](INFO): SBD MOMSN=16962632