2025-03-07T00:41:15.351Z,1741308075.351 [Supervisor](DEBUG): Initializing supervisor.
2025-03-07T00:41:15.356Z,1741308075.356 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-07T00:41:15.357Z,1741308075.357 [SyncHandler](INFO): Protected caller Thread ID is 1991
2025-03-07T00:41:15.357Z,1741308075.357 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-07T00:41:15.359Z,1741308075.359 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-07T00:41:15.359Z,1741308075.359 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1992
2025-03-07T00:41:15.364Z,1741308075.364 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-07T00:41:15.383Z,1741308075.383 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-07T00:41:15.384Z,1741308075.384 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-07T00:41:15.384Z,1741308075.384 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1993
2025-03-07T00:41:15.388Z,1741308075.388 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-07T00:41:15.389Z,1741308075.389 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-07T00:41:15.390Z,1741308075.390 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1994
2025-03-07T00:41:15.392Z,1741308075.392 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-07T00:41:15.393Z,1741308075.393 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-07T00:41:15.393Z,1741308075.393 [logger ThreadHandler](INFO): Protected caller Thread ID is 1995
2025-03-07T00:41:15.397Z,1741308075.397 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-07T00:41:15.398Z,1741308075.398 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-07T00:41:15.399Z,1741308075.399 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-07T00:41:15.626Z,1741308075.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-07T00:41:15.626Z,1741308075.626 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-07T00:41:15.745Z,1741308075.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-07T00:41:15.746Z,1741308075.746 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-07T00:41:15.853Z,1741308075.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-07T00:41:15.853Z,1741308075.853 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-07T00:41:16.364Z,1741308076.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-07T00:41:16.365Z,1741308076.365 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-07T00:41:16.734Z,1741308076.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-07T00:41:16.735Z,1741308076.735 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-07T00:41:17.104Z,1741308077.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-07T00:41:17.104Z,1741308077.104 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-07T00:41:17.315Z,1741308077.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-07T00:41:17.315Z,1741308077.315 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-07T00:41:17.406Z,1741308077.406 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-07T00:41:17.498Z,1741308077.498 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-07T00:41:17.935Z,1741308077.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-07T00:41:17.935Z,1741308077.935 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-07T00:41:18.245Z,1741308078.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-07T00:41:18.245Z,1741308078.245 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-07T00:41:18.827Z,1741308078.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-07T00:41:18.828Z,1741308078.828 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-07T00:41:19.088Z,1741308079.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-07T00:41:19.088Z,1741308079.088 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-07T00:41:19.351Z,1741308079.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-07T00:41:19.352Z,1741308079.352 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-07T00:41:19.504Z,1741308079.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-07T00:41:19.504Z,1741308079.504 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-07T00:41:20.448Z,1741308080.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-07T00:41:20.450Z,1741308080.450 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-03-07T00:41:20.451Z,1741308080.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-03-07T00:41:20.622Z,1741308080.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-03-07T00:41:21.012Z,1741308081.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-03-07T00:41:21.507Z,1741308081.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-07T00:41:21.507Z,1741308081.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-03-07T00:41:21.644Z,1741308081.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-03-07T00:41:21.752Z,1741308081.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-03-07T00:41:21.858Z,1741308081.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-03-07T00:41:21.977Z,1741308081.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-03-07T00:41:22.073Z,1741308082.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-03-07T00:41:22.170Z,1741308082.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-03-07T00:41:22.274Z,1741308082.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-03-07T00:41:22.459Z,1741308082.459 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-03-07T00:41:22.471Z,1741308082.471 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-07T00:41:22.525Z,1741308082.525 [DepthRateCalculator] Loaded
2025-03-07T00:41:22.525Z,1741308082.525 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-07T00:41:22.531Z,1741308082.531 [PitchRateCalculator] Loaded
2025-03-07T00:41:22.531Z,1741308082.531 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-07T00:41:22.541Z,1741308082.541 [SpeedCalculator] Loaded
2025-03-07T00:41:22.541Z,1741308082.541 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-07T00:41:22.546Z,1741308082.546 [YawRateCalculator] Loaded
2025-03-07T00:41:22.547Z,1741308082.547 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-07T00:41:22.569Z,1741308082.569 [ElevatorOffsetCalculator] Loaded
2025-03-07T00:41:22.569Z,1741308082.569 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-07T00:41:22.569Z,1741308082.569 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-07T00:41:22.570Z,1741308082.570 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-07T00:41:22.599Z,1741308082.599 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-07T00:41:22.600Z,1741308082.600 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-07T00:41:22.610Z,1741308082.610 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-07T00:41:22.611Z,1741308082.611 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-07T00:41:23.119Z,1741308083.119 [AHRS_M2] Loaded
2025-03-07T00:41:23.119Z,1741308083.119 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-07T00:41:23.164Z,1741308083.164 [BackseatComponent] Loaded
2025-03-07T00:41:23.164Z,1741308083.164 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-07T00:41:23.166Z,1741308083.166 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-03-07T00:41:23.166Z,1741308083.166 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2078
2025-03-07T00:41:23.169Z,1741308083.169 [LcmUniversalReporter] Loaded
2025-03-07T00:41:23.169Z,1741308083.169 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-07T00:41:23.965Z,1741308083.965 [BPC1] Loaded
2025-03-07T00:41:23.966Z,1741308083.966 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-07T00:41:24.041Z,1741308084.041 [DataOverHttps] Loaded
2025-03-07T00:41:24.042Z,1741308084.042 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-07T00:41:24.043Z,1741308084.043 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-03-07T00:41:24.043Z,1741308084.043 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2079
2025-03-07T00:41:24.066Z,1741308084.066 [Depth_Keller] Loaded
2025-03-07T00:41:24.066Z,1741308084.066 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-07T00:41:24.071Z,1741308084.071 [DropWeight] Loaded
2025-03-07T00:41:24.071Z,1741308084.071 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-07T00:41:24.092Z,1741308084.092 [MultiRay] Loaded
2025-03-07T00:41:24.092Z,1741308084.092 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-03-07T00:41:24.156Z,1741308084.156 [NAL9602] Loaded
2025-03-07T00:41:24.156Z,1741308084.156 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-07T00:41:24.186Z,1741308084.186 [Onboard] Loaded
2025-03-07T00:41:24.186Z,1741308084.186 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-07T00:41:24.187Z,1741308084.187 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-03-07T00:41:24.187Z,1741308084.187 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2080
2025-03-07T00:41:24.193Z,1741308084.193 [PowerOnly] Loaded
2025-03-07T00:41:24.194Z,1741308084.194 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-03-07T00:41:24.207Z,1741308084.207 [Power24vConverter] Loaded
2025-03-07T00:41:24.207Z,1741308084.207 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-07T00:41:24.225Z,1741308084.225 [Radio_Surface] Loaded
2025-03-07T00:41:24.225Z,1741308084.225 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-07T00:41:24.226Z,1741308084.226 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-03-07T00:41:24.226Z,1741308084.226 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2081
2025-03-07T00:41:24.251Z,1741308084.251 [Waterlinked] Loaded
2025-03-07T00:41:24.252Z,1741308084.252 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-03-07T00:41:24.252Z,1741308084.252 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-07T00:41:24.252Z,1741308084.252 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-07T00:41:24.366Z,1741308084.366 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-07T00:41:24.366Z,1741308084.366 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-07T00:41:24.380Z,1741308084.380 [NavChart] Loaded
2025-03-07T00:41:24.380Z,1741308084.380 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-07T00:41:24.386Z,1741308084.386 [UniversalFixResidualReporter] Loaded
2025-03-07T00:41:24.386Z,1741308084.386 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-07T00:41:24.387Z,1741308084.387 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-07T00:41:24.387Z,1741308084.387 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-07T00:41:24.555Z,1741308084.555 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-07T00:41:24.569Z,1741308084.569 [SBIT] Loaded
2025-03-07T00:41:24.569Z,1741308084.569 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-07T00:41:24.572Z,1741308084.572 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-07T00:41:24.585Z,1741308084.585 [IBIT] Loaded
2025-03-07T00:41:24.586Z,1741308084.586 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-07T00:41:24.591Z,1741308084.591 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-07T00:41:24.670Z,1741308084.670 [CBIT] Loaded
2025-03-07T00:41:24.671Z,1741308084.671 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-07T00:41:24.702Z,1741308084.702 [GFScanner] Loaded
2025-03-07T00:41:24.703Z,1741308084.703 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-07T00:41:24.703Z,1741308084.703 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-07T00:41:24.703Z,1741308084.703 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-07T00:41:24.874Z,1741308084.874 [CTD_Seabird] Loaded
2025-03-07T00:41:24.874Z,1741308084.874 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-07T00:41:24.875Z,1741308084.875 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2025-03-07T00:41:24.876Z,1741308084.876 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2082
2025-03-07T00:41:24.898Z,1741308084.898 [PAR_Licor] Loaded
2025-03-07T00:41:24.898Z,1741308084.898 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-07T00:41:24.945Z,1741308084.945 [WetLabsBB2FL] Loaded
2025-03-07T00:41:24.945Z,1741308084.945 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-07T00:41:24.946Z,1741308084.946 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2025-03-07T00:41:24.946Z,1741308084.946 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2083
2025-03-07T00:41:24.947Z,1741308084.947 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-07T00:41:24.948Z,1741308084.948 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-07T00:41:25.089Z,1741308085.089 [BuoyancyServo] Loaded
2025-03-07T00:41:25.089Z,1741308085.089 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-07T00:41:25.113Z,1741308085.113 [ElevatorServo] Loaded
2025-03-07T00:41:25.113Z,1741308085.113 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-07T00:41:25.136Z,1741308085.136 [MassServo] Loaded
2025-03-07T00:41:25.137Z,1741308085.137 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-07T00:41:25.159Z,1741308085.159 [RudderServo] Loaded
2025-03-07T00:41:25.159Z,1741308085.159 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-07T00:41:25.178Z,1741308085.178 [ThrusterHE] Loaded
2025-03-07T00:41:25.178Z,1741308085.178 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-07T00:41:25.179Z,1741308085.179 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-07T00:41:25.179Z,1741308085.179 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-07T00:41:25.234Z,1741308085.234 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-07T00:41:25.287Z,1741308085.287 [VerticalControl] Loaded
2025-03-07T00:41:25.287Z,1741308085.287 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-07T00:41:25.290Z,1741308085.290 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-07T00:41:25.336Z,1741308085.336 [HorizontalControl] Loaded
2025-03-07T00:41:25.336Z,1741308085.336 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-07T00:41:25.338Z,1741308085.338 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-07T00:41:25.340Z,1741308085.340 [SpeedControl] Loaded
2025-03-07T00:41:25.340Z,1741308085.340 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-07T00:41:25.343Z,1741308085.343 [LoopControl](DEBUG): Construct LoopControl.
2025-03-07T00:41:25.344Z,1741308085.344 [LoopControl] Loaded
2025-03-07T00:41:25.344Z,1741308085.344 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-07T00:41:25.344Z,1741308085.344 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-07T00:41:25.345Z,1741308085.345 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-07T00:41:25.481Z,1741308085.481 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-07T00:41:25.482Z,1741308085.482 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-07T00:41:25.778Z,1741308085.778 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-07T00:41:25.778Z,1741308085.778 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-07T00:41:26.133Z,1741308086.133 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-07T00:41:26.134Z,1741308086.134 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-07T00:41:26.193Z,1741308086.193 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-07T00:41:26.200Z,1741308086.200 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-07T00:41:26.202Z,1741308086.202 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-07T00:41:26.214Z,1741308086.214 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-07T00:41:26.215Z,1741308086.215 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0
2025-03-07T00:41:26.215Z,1741308086.215 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2084
2025-03-07T00:41:26.220Z,1741308086.220 [Supervisor](INFO): Main Thread ID is 829
2025-03-07T00:41:26.220Z,1741308086.220 [Supervisor](DEBUG): Running supervisor.
2025-03-07T00:41:26.220Z,1741308086.220 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2085
2025-03-07T00:41:26.221Z,1741308086.221 [CommandExec](INFO): Initializing the command executive.
2025-03-07T00:41:26.222Z,1741308086.222 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2086
2025-03-07T00:41:26.225Z,1741308086.225 [controlThread ThreadHandler](INFO): Handler Thread ID is 2087
2025-03-07T00:41:26.225Z,1741308086.225 [controlThread](DEBUG): Initializing ControlThread
2025-03-07T00:41:26.226Z,1741308086.226 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-07T00:41:26.226Z,1741308086.226 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-07T00:41:26.227Z,1741308086.227 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-07T00:41:26.227Z,1741308086.227 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-07T00:41:26.227Z,1741308086.227 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-07T00:41:26.236Z,1741308086.236 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-07T00:41:26.236Z,1741308086.236 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-07T00:41:26.236Z,1741308086.236 [SBIT](INFO): Initialize SBIT Component.
2025-03-07T00:41:26.237Z,1741308086.237 [SBIT](IMPORTANT): git: 2025-03-06_B-3-g06c58acc0
2025-03-07T00:41:26.237Z,1741308086.237 [SBIT](INFO): git hash: 06c58acc0c21dac1722dadabfa9aaff283a6c1e9
2025-03-07T00:41:26.237Z,1741308086.237 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-07T00:41:26.239Z,1741308086.239 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-03-07T00:41:26.240Z,1741308086.240 [SBIT](INFO): Beginning SBIT in 73.000000 seconds.
2025-03-07T00:41:26.240Z,1741308086.240 [IBIT](INFO): Initialize IBIT Component.
2025-03-07T00:41:26.241Z,1741308086.241 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-07T00:41:26.242Z,1741308086.242 [logger ThreadHandler](INFO): Handler Thread ID is 2088
2025-03-07T00:41:26.253Z,1741308086.253 [CBIT](DEBUG): Initialized mux pins.
2025-03-07T00:41:26.253Z,1741308086.253 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-07T00:41:26.261Z,1741308086.261 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2089
2025-03-07T00:41:26.273Z,1741308086.273 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2090
2025-03-07T00:41:26.275Z,1741308086.275 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-07T00:41:26.277Z,1741308086.277 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-07T00:41:26.278Z,1741308086.278 [CBIT](DEBUG): Initializing heartbeat.
2025-03-07T00:41:26.285Z,1741308086.285 [Onboard ThreadHandler](INFO): Handler Thread ID is 2091
2025-03-07T00:41:26.303Z,1741308086.303 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2092
2025-03-07T00:41:26.326Z,1741308086.326 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2093
2025-03-07T00:41:26.326Z,1741308086.326 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-07T00:41:26.330Z,1741308086.330 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2095
2025-03-07T00:41:26.333Z,1741308086.333 [WetLabsBB2FL](INFO): Powering up
2025-03-07T00:41:26.335Z,1741308086.335 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2097
2025-03-07T00:41:26.337Z,1741308086.337 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-07T00:41:26.337Z,1741308086.337 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-07T00:41:26.337Z,1741308086.337 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-07T00:41:26.338Z,1741308086.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-07T00:41:26.338Z,1741308086.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-07T00:41:26.338Z,1741308086.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-07T00:41:26.338Z,1741308086.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-07T00:41:26.338Z,1741308086.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-07T00:41:26.349Z,1741308086.349 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-07T00:41:26.389Z,1741308086.389 [CBIT](DEBUG): Backplane powered.
2025-03-07T00:41:26.389Z,1741308086.389 [GFScanner](DEBUG): Initializing GFScanner
2025-03-07T00:41:26.389Z,1741308086.389 [GFScanner](DEBUG): Deactivating GF circuits.
2025-03-07T00:41:26.398Z,1741308086.398 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-07T00:41:26.400Z,1741308086.400 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-07T00:41:26.401Z,1741308086.401 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-07T00:41:26.402Z,1741308086.402 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-07T00:41:26.402Z,1741308086.402 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-07T00:41:26.403Z,1741308086.403 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-07T00:41:26.403Z,1741308086.403 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-07T00:41:26.404Z,1741308086.404 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-07T00:41:26.467Z,1741308086.467 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-03-07T00:41:26.471Z,1741308086.471 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-07T00:41:26.495Z,1741308086.495 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-03-07T00:41:26.496Z,1741308086.496 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-07T00:41:26.496Z,1741308086.496 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-07T00:41:26.497Z,1741308086.497 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-07T00:41:26.704Z,1741308086.704 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-03-07T00:41:26.709Z,1741308086.709 [Radio_Surface](INFO): Powering up
2025-03-07T00:41:26.718Z,1741308086.718 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-07T00:41:26.719Z,1741308086.719 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-07T00:41:26.722Z,1741308086.722 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-07T00:41:26.751Z,1741308086.751 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-07T00:41:26.754Z,1741308086.754 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-07T00:41:26.760Z,1741308086.760 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-07T00:41:26.771Z,1741308086.771 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-03-07T00:41:26.776Z,1741308086.776 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-07T00:41:26.793Z,1741308086.793 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-07T00:41:26.842Z,1741308086.842 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m
2025-03-07T00:41:26.843Z,1741308086.843 [MultiRay](INFO): Powering up MultiRay Lights
2025-03-07T00:41:26.862Z,1741308086.862 [Power24vConverter](INFO): Powering up.
2025-03-07T00:41:26.882Z,1741308086.882 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-07T00:41:26.899Z,1741308086.899 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-07T00:41:26.906Z,1741308086.906 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-07T00:41:26.907Z,1741308086.907 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-07T00:41:26.913Z,1741308086.913 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-07T00:41:26.914Z,1741308086.914 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-07T00:41:26.921Z,1741308086.921 [MassServo](DEBUG): Initializing MassServo.
2025-03-07T00:41:26.922Z,1741308086.922 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-07T00:41:26.929Z,1741308086.929 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-07T00:41:26.930Z,1741308086.930 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-07T00:41:26.937Z,1741308086.937 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-07T00:41:27.215Z,1741308087.215 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-03-07T00:41:27.215Z,1741308087.215 [DropWeight] Hardware Fault, FailCount= 1
2025-03-07T00:41:27.215Z,1741308087.215 [DropWeight](ERROR): Hardware Fault
2025-03-07T00:41:27.265Z,1741308087.265 [CommandExec](FAULT): Scheduling is paused
2025-03-07T00:41:27.266Z,1741308087.266 [CBIT](INFO): Critical error at 20250307T004127
2025-03-07T00:41:27.266Z,1741308087.266 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-07T00:41:27.268Z,1741308087.268 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-07T00:41:27.273Z,1741308087.273 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-07T00:41:27.603Z,1741308087.603 [CBIT](INFO): Critical error at 20250307T004127
2025-03-07T00:41:28.945Z,1741308088.945 [WetLabsBB2FL](INFO): Powering down
2025-03-07T00:41:51.199Z,1741308111.199 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2025-03-07T00:41:51.203Z,1741308111.203 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2025-03-07T00:41:51.203Z,1741308111.203 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.0 degree
2025-03-07T00:41:51.207Z,1741308111.207 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2025-03-07T00:41:51.512Z,1741308111.512 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-07T00:41:54.339Z,1741308114.339 [NAL9602](INFO): Powering up NAL9602
2025-03-07T00:42:04.450Z,1741308124.450 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1741308114 second
2025-03-07T00:42:05.105Z,1741308125.105 [Waterlinked](INFO): Powering down
2025-03-07T00:42:05.407Z,1741308125.407 [NAL9602](INFO): NAL9602 initialized
2025-03-07T00:42:09.522Z,1741308129.522 [CommandExec](IMPORTANT): got command get latitude
2025-03-07T00:42:09.523Z,1741308129.523 [CommandExec](IMPORTANT): latitude 36.799999 arcdeg
2025-03-07T00:42:20.408Z,1741308140.408 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2025-03-07T00:42:36.518Z,1741308156.518 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-07T00:42:39.763Z,1741308159.763 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-07T00:42:39.784Z,1741308159.784 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-07T00:42:44.294Z,1741308164.294 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006023
CHAN A1 (24V): 0.042304
CHAN A2 (12V): -0.005101
CHAN A3 (5V): -0.003122
CHAN B0 (3.3V): -0.001008
CHAN B1 (3.15aV): -0.001275
CHAN B2 (3.15bV): -0.001226
CHAN B3 (GND): -0.000563
OPEN: 0.004834
Full Scale: +/- 1 mA
2025-03-07T00:42:58.355Z,1741308178.355 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1741308164 second
2025-03-07T00:43:24.064Z,1741308204.064 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2025-03-07T00:43:29.998Z,1741308209.998 [CommandExec](IMPORTANT): got command strobe off
2025-03-07T00:43:29.999Z,1741308209.999 [CommandExec](IMPORTANT): Deactivating strobe
2025-03-07T00:43:33.674Z,1741308213.674 [SBIT](IMPORTANT): SBIT PASSED
2025-03-07T00:43:33.674Z,1741308213.674 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2025-03-07T00:43:33.675Z,1741308213.675 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;
2025-03-07T00:43:33.676Z,1741308213.676 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;
2025-03-07T00:43:33.676Z,1741308213.676 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;
2025-03-07T00:43:33.676Z,1741308213.676 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2025-03-07T00:43:33.676Z,1741308213.676 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2025-03-07T00:43:33.676Z,1741308213.676 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=185.902950 cubic_centimeter;
2025-03-07T00:43:33.676Z,1741308213.676 [SBIT](IMPORTANT): VerticalControl.massDefault=13.432478 millimeter;
2025-03-07T00:43:34.070Z,1741308214.070 [MissionManager](IMPORTANT): Started mission Startup
2025-03-07T00:43:34.071Z,1741308214.071 [Startup] Running Loop=1
2025-03-07T00:43:34.071Z,1741308214.071 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-07T00:43:34.071Z,1741308214.071 [Startup:A.GoToSurface] Running Loop=1
2025-03-07T00:43:34.071Z,1741308214.071 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-07T00:43:34.072Z,1741308214.072 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-07T00:43:34.072Z,1741308214.072 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-07T00:43:34.072Z,1741308214.072 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-07T00:43:34.073Z,1741308214.073 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-07T00:43:34.073Z,1741308214.073 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-07T00:43:34.074Z,1741308214.074 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-07T00:43:34.075Z,1741308214.075 [Startup:StartupSatComms] Running Loop=1
2025-03-07T00:43:34.075Z,1741308214.075 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-07T00:43:34.076Z,1741308214.076 [Startup:StartupSatComms:A] Running Loop=1
2025-03-07T00:43:34.466Z,1741308214.466 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-07T00:43:34.467Z,1741308214.467 [Startup:StartupSatComms:A] Stopped
2025-03-07T00:43:34.467Z,1741308214.467 [Startup:StartupSatComms:B] Running Loop=1
2025-03-07T00:43:34.922Z,1741308214.922 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-07T00:44:00.665Z,1741308240.665 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1741308214 second
2025-03-07T00:44:04.520Z,1741308244.520 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005611
2025-03-07T00:44:13.521Z,1741308253.521 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20250307T001746/Courier0000.lzma
2025-03-07T00:44:14.511Z,1741308254.511 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001746/Courier0000.lzma.bak
2025-03-07T00:44:14.511Z,1741308254.511 [DataOverHttps](INFO): SBD MOMSN=24136394
2025-03-07T00:44:15.984Z,1741308255.984 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2025-03-07T00:44:16.082Z,1741308256.082 [Startup:StartupSatComms:B] Stopped
2025-03-07T00:44:16.082Z,1741308256.082 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-07T00:44:16.082Z,1741308256.082 [Startup:StartupSatComms] Stopped
2025-03-07T00:44:16.082Z,1741308256.082 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-07T00:44:16.083Z,1741308256.083 [Startup](INFO): Completed Startup
2025-03-07T00:44:16.083Z,1741308256.083 [MissionManager](INFO): Startup is completed.
2025-03-07T00:44:16.083Z,1741308256.083 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-07T00:44:16.083Z,1741308256.083 [Startup] Stopped
2025-03-07T00:44:16.083Z,1741308256.083 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-07T00:44:16.084Z,1741308256.084 [Startup:A.GoToSurface] Stopped
2025-03-07T00:44:16.084Z,1741308256.084 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-07T00:44:16.476Z,1741308256.476 [MissionManager](IMPORTANT): Started mission Default
2025-03-07T00:44:16.477Z,1741308256.477 [Default] Running Loop=1
2025-03-07T00:44:16.503Z,1741308256.503 [Default](DEBUG): Aggregate::initialize Default
2025-03-07T00:44:16.503Z,1741308256.503 [Default:B.GoToSurface] Running Loop=1
2025-03-07T00:44:16.503Z,1741308256.503 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-07T00:44:16.503Z,1741308256.503 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-07T00:44:16.503Z,1741308256.503 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-07T00:44:16.504Z,1741308256.504 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-07T00:44:16.504Z,1741308256.504 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-07T00:44:16.504Z,1741308256.504 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-07T00:44:16.505Z,1741308256.505 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-07T00:44:16.520Z,1741308256.520 [Default:A.Wait] Running Loop=1
2025-03-07T00:44:16.520Z,1741308256.520 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:23.922Z,1741308263.922 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/multiray_test.tl
2025-03-07T00:44:23.922Z,1741308263.922 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/multiray_test.tl
2025-03-07T00:44:23.922Z,1741308263.922 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/multiray_test.tl
2025-03-07T00:44:23.923Z,1741308263.923 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/multiray_test.tl
2025-03-07T00:44:24.190Z,1741308264.190 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/multiray_test.tl translated into:
5
5
0
1
2
2025-03-07T00:44:24.200Z,1741308264.200 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2025-03-07T00:44:24.207Z,1741308264.207 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2025-03-07T00:44:24.214Z,1741308264.214 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2025-03-07T00:44:24.245Z,1741308264.245 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2025-03-07T00:44:24.248Z,1741308264.248 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2025-03-07T00:44:24.274Z,1741308264.274 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2025-03-07T00:44:24.286Z,1741308264.286 [multiray_test:C.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.302Z,1741308264.302 [multiray_test:E.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.314Z,1741308264.314 [multiray_test:G.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.320Z,1741308264.320 [multiray_test:I.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.327Z,1741308264.327 [multiray_test:K.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.338Z,1741308264.338 [multiray_test:M.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.351Z,1741308264.351 [multiray_test:O.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.360Z,1741308264.360 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.367Z,1741308264.367 [multiray_test:R.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.371Z,1741308264.371 [multiray_test:T.Wait](DEBUG): Construct Wait.
2025-03-07T00:44:24.384Z,1741308264.384 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission multiray_test {
"""
This mission is designed to run in the lab only. Cycles through MultiRay light modes
"""
arguments {
MissionTimeout = 5 minute
"""
How long to let the mission run.
"""
LightDuration = 5 second
"""
How long to wait before changing light modes
"""
LightMode0 = 0 count
"""
Light mode = 0
"""
LightMode1 = 1 count
"""
Light mode = 1
"""
LightMode2 = 2 count
"""
Light mode = 2
"""
EnabledMultiray = Sensor:MultiRay.loadAtStartup
"""
Automatically set to true if the Multiray is enabled.
"""
}
timeout duration=MissionTimeout
readData strategy="MinError" {
while ( EnabledMultiray )
Sensor:MultiRay.lightModeLog
}
#
#
#
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
}
2025-03-07T00:44:24.391Z,1741308264.391 [CommandExec](IMPORTANT): Running ./Missions/Engineering/multiray_test.tl
2025-03-07T00:44:24.570Z,1741308264.570 [Default] Stopped
2025-03-07T00:44:24.570Z,1741308264.570 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-07T00:44:24.570Z,1741308264.570 [Default:A.Wait] Stopped
2025-03-07T00:44:24.570Z,1741308264.570 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:44:24.570Z,1741308264.570 [Default:B.GoToSurface] Stopped
2025-03-07T00:44:24.570Z,1741308264.570 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-07T00:44:24.570Z,1741308264.570 [MissionManager](IMPORTANT): Started mission multiray_test
2025-03-07T00:44:24.570Z,1741308264.570 [multiray_test] Running Loop=1
2025-03-07T00:44:24.571Z,1741308264.571 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2025-03-07T00:44:24.571Z,1741308264.571 [multiray_test:A] Running Loop=1
2025-03-07T00:44:24.571Z,1741308264.571 [multiray_test:B] Running Loop=1
2025-03-07T00:44:24.571Z,1741308264.571 [multiray_test:B] Stopped
2025-03-07T00:44:24.571Z,1741308264.571 [multiray_test:C.Wait] Running Loop=1
2025-03-07T00:44:24.571Z,1741308264.571 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:24.572Z,1741308264.572 [multiray_test:A] Running Loop=1
2025-03-07T00:44:24.572Z,1741308264.572 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2025-03-07T00:44:26.154Z,1741308266.154 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:26.956Z,1741308266.956 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:27.762Z,1741308267.762 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:28.580Z,1741308268.580 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:29.403Z,1741308269.403 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:30.187Z,1741308270.187 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:30.199Z,1741308270.199 [multiray_test:C.Wait](INFO): Done Waiting.
2025-03-07T00:44:30.199Z,1741308270.199 [multiray_test:C.Wait] Stopped
2025-03-07T00:44:30.199Z,1741308270.199 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:44:30.199Z,1741308270.199 [multiray_test:D] Running Loop=1
2025-03-07T00:44:30.624Z,1741308270.624 [multiray_test:D] Stopped
2025-03-07T00:44:30.624Z,1741308270.624 [multiray_test:E.Wait] Running Loop=1
2025-03-07T00:44:30.624Z,1741308270.624 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:36.258Z,1741308276.258 [multiray_test:E.Wait](INFO): Done Waiting.
2025-03-07T00:44:36.258Z,1741308276.258 [multiray_test:E.Wait] Stopped
2025-03-07T00:44:36.258Z,1741308276.258 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:44:36.258Z,1741308276.258 [multiray_test:F] Running Loop=1
2025-03-07T00:44:36.761Z,1741308276.761 [multiray_test:F] Stopped
2025-03-07T00:44:36.761Z,1741308276.761 [multiray_test:G.Wait] Running Loop=1
2025-03-07T00:44:36.761Z,1741308276.761 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:37.910Z,1741308277.910 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-07T00:44:42.324Z,1741308282.324 [multiray_test:G.Wait](INFO): Done Waiting.
2025-03-07T00:44:42.324Z,1741308282.324 [multiray_test:G.Wait] Stopped
2025-03-07T00:44:42.324Z,1741308282.324 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:44:42.324Z,1741308282.324 [multiray_test:H] Running Loop=1
2025-03-07T00:44:42.758Z,1741308282.758 [multiray_test:H] Stopped
2025-03-07T00:44:42.759Z,1741308282.759 [multiray_test:I.Wait] Running Loop=1
2025-03-07T00:44:42.759Z,1741308282.759 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:43.518Z,1741308283.518 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:44.326Z,1741308284.326 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:45.135Z,1741308285.135 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:45.942Z,1741308285.942 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:46.756Z,1741308286.756 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:47.558Z,1741308287.558 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:48.367Z,1741308288.367 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:44:48.378Z,1741308288.378 [multiray_test:I.Wait](INFO): Done Waiting.
2025-03-07T00:44:48.378Z,1741308288.378 [multiray_test:I.Wait] Stopped
2025-03-07T00:44:48.378Z,1741308288.378 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:44:48.378Z,1741308288.378 [multiray_test:J] Running Loop=1
2025-03-07T00:44:48.863Z,1741308288.863 [multiray_test:J] Stopped
2025-03-07T00:44:48.863Z,1741308288.863 [multiray_test:K.Wait] Running Loop=1
2025-03-07T00:44:48.864Z,1741308288.864 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:54.473Z,1741308294.473 [multiray_test:K.Wait](INFO): Done Waiting.
2025-03-07T00:44:54.473Z,1741308294.473 [multiray_test:K.Wait] Stopped
2025-03-07T00:44:54.473Z,1741308294.473 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:44:54.474Z,1741308294.474 [multiray_test:L] Running Loop=1
2025-03-07T00:44:54.905Z,1741308294.905 [multiray_test:L] Stopped
2025-03-07T00:44:54.905Z,1741308294.905 [multiray_test:M.Wait] Running Loop=1
2025-03-07T00:44:54.905Z,1741308294.905 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:44:57.612Z,1741308297.612 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250307T001746/Courier0004.lzma
2025-03-07T00:44:58.615Z,1741308298.615 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001746/Courier0004.lzma.bak
2025-03-07T00:44:58.615Z,1741308298.615 [DataOverHttps](INFO): SBD MOMSN=24136399
2025-03-07T00:45:00.497Z,1741308300.497 [multiray_test:M.Wait](INFO): Done Waiting.
2025-03-07T00:45:00.497Z,1741308300.497 [multiray_test:M.Wait] Stopped
2025-03-07T00:45:00.497Z,1741308300.497 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:00.498Z,1741308300.498 [multiray_test:N] Running Loop=1
2025-03-07T00:45:00.985Z,1741308300.985 [multiray_test:N] Stopped
2025-03-07T00:45:00.985Z,1741308300.985 [multiray_test:O.Wait] Running Loop=1
2025-03-07T00:45:00.985Z,1741308300.985 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:01.714Z,1741308301.714 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:02.510Z,1741308302.510 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:03.315Z,1741308303.315 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:04.123Z,1741308304.123 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:04.938Z,1741308304.938 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:05.746Z,1741308305.746 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:06.567Z,1741308306.567 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:06.582Z,1741308306.582 [multiray_test:O.Wait](INFO): Done Waiting.
2025-03-07T00:45:06.582Z,1741308306.582 [multiray_test:O.Wait] Stopped
2025-03-07T00:45:06.582Z,1741308306.582 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:06.582Z,1741308306.582 [multiray_test:P] Running Loop=1
2025-03-07T00:45:06.994Z,1741308306.994 [multiray_test:P] Stopped
2025-03-07T00:45:06.994Z,1741308306.994 [multiray_test:Q.Wait] Running Loop=1
2025-03-07T00:45:06.994Z,1741308306.994 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:12.628Z,1741308312.628 [multiray_test:Q.Wait](INFO): Done Waiting.
2025-03-07T00:45:12.628Z,1741308312.628 [multiray_test:Q.Wait] Stopped
2025-03-07T00:45:12.629Z,1741308312.629 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:12.629Z,1741308312.629 [multiray_test:U] Running Loop=1
2025-03-07T00:45:13.058Z,1741308313.058 [multiray_test:U] Stopped
2025-03-07T00:45:13.058Z,1741308313.058 [multiray_test:R.Wait] Running Loop=1
2025-03-07T00:45:13.059Z,1741308313.059 [multiray_test:R.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:18.697Z,1741308318.697 [multiray_test:R.Wait](INFO): Done Waiting.
2025-03-07T00:45:18.697Z,1741308318.697 [multiray_test:R.Wait] Stopped
2025-03-07T00:45:18.697Z,1741308318.697 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:18.698Z,1741308318.698 [multiray_test:S] Running Loop=1
2025-03-07T00:45:19.122Z,1741308319.122 [multiray_test:S] Stopped
2025-03-07T00:45:19.123Z,1741308319.123 [multiray_test:T.Wait] Running Loop=1
2025-03-07T00:45:19.123Z,1741308319.123 [multiray_test:T.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:19.878Z,1741308319.878 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:20.698Z,1741308320.698 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:21.532Z,1741308321.532 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:22.305Z,1741308322.305 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:23.111Z,1741308323.111 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:23.920Z,1741308323.920 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:24.734Z,1741308324.734 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:24.769Z,1741308324.769 [multiray_test:T.Wait](INFO): Done Waiting.
2025-03-07T00:45:24.770Z,1741308324.770 [multiray_test:T.Wait] Stopped
2025-03-07T00:45:24.770Z,1741308324.770 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:24.770Z,1741308324.770 [multiray_test](INFO): Completed multiray_test
2025-03-07T00:45:24.770Z,1741308324.770 [MissionManager](INFO): multiray_test is completed.
2025-03-07T00:45:24.770Z,1741308324.770 [MissionManager](INFO): Uninitializing Mission multiray_test
2025-03-07T00:45:24.770Z,1741308324.770 [multiray_test] Stopped
2025-03-07T00:45:24.770Z,1741308324.770 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2025-03-07T00:45:24.770Z,1741308324.770 [multiray_test:A] Stopped
2025-03-07T00:45:25.141Z,1741308325.141 [MissionManager](IMPORTANT): Started mission Default
2025-03-07T00:45:25.142Z,1741308325.142 [Default] Running Loop=1
2025-03-07T00:45:25.142Z,1741308325.142 [Default](DEBUG): Aggregate::initialize Default
2025-03-07T00:45:25.142Z,1741308325.142 [Default:B.GoToSurface] Running Loop=1
2025-03-07T00:45:25.142Z,1741308325.142 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-07T00:45:25.142Z,1741308325.142 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-07T00:45:25.143Z,1741308325.143 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-07T00:45:25.143Z,1741308325.143 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-07T00:45:25.143Z,1741308325.143 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-07T00:45:25.143Z,1741308325.143 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-07T00:45:25.144Z,1741308325.144 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-07T00:45:25.144Z,1741308325.144 [Default:A.Wait] Running Loop=1
2025-03-07T00:45:25.144Z,1741308325.144 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:36.489Z,1741308336.489 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/multiray_test.tl
2025-03-07T00:45:36.508Z,1741308336.508 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/multiray_test.tl
2025-03-07T00:45:36.526Z,1741308336.526 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/multiray_test.tl
2025-03-07T00:45:36.527Z,1741308336.527 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/multiray_test.tl
2025-03-07T00:45:36.762Z,1741308336.762 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/multiray_test.tl translated into:
5
5
0
1
2
2025-03-07T00:45:36.767Z,1741308336.767 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2025-03-07T00:45:36.769Z,1741308336.769 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2025-03-07T00:45:36.771Z,1741308336.771 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2025-03-07T00:45:36.773Z,1741308336.773 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2025-03-07T00:45:36.775Z,1741308336.775 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2025-03-07T00:45:36.778Z,1741308336.778 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2025-03-07T00:45:36.781Z,1741308336.781 [multiray_test:C.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.784Z,1741308336.784 [multiray_test:E.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.787Z,1741308336.787 [multiray_test:G.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.789Z,1741308336.789 [multiray_test:I.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.792Z,1741308336.792 [multiray_test:K.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.795Z,1741308336.795 [multiray_test:M.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.797Z,1741308336.797 [multiray_test:O.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.800Z,1741308336.800 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.803Z,1741308336.803 [multiray_test:R.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.873Z,1741308336.873 [multiray_test:T.Wait](DEBUG): Construct Wait.
2025-03-07T00:45:36.887Z,1741308336.887 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission multiray_test {
"""
This mission is designed to run in the lab only. Cycles through MultiRay light modes
"""
arguments {
MissionTimeout = 5 minute
"""
How long to let the mission run.
"""
LightDuration = 5 second
"""
How long to wait before changing light modes
"""
LightMode0 = 0 count
"""
Light mode = 0
"""
LightMode1 = 1 count
"""
Light mode = 1
"""
LightMode2 = 2 count
"""
Light mode = 2
"""
EnabledMultiray = Sensor:MultiRay.loadAtStartup
"""
Automatically set to true if the Multiray is enabled.
"""
}
timeout duration=MissionTimeout
readData strategy="MinError" {
while ( EnabledMultiray )
Sensor:MultiRay.lightModeLog
}
#
#
#
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0
behavior Guidance:Wait {
run in sequence
set duration = LightDuration
}
}
2025-03-07T00:45:36.888Z,1741308336.888 [CommandExec](IMPORTANT): Running ./Missions/Engineering/multiray_test.tl
2025-03-07T00:45:37.273Z,1741308337.273 [Default] Stopped
2025-03-07T00:45:37.274Z,1741308337.274 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-07T00:45:37.274Z,1741308337.274 [Default:A.Wait] Stopped
2025-03-07T00:45:37.274Z,1741308337.274 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:37.274Z,1741308337.274 [Default:B.GoToSurface] Stopped
2025-03-07T00:45:37.274Z,1741308337.274 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-07T00:45:37.274Z,1741308337.274 [MissionManager](IMPORTANT): Started mission multiray_test
2025-03-07T00:45:37.274Z,1741308337.274 [multiray_test] Running Loop=1
2025-03-07T00:45:37.274Z,1741308337.274 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2025-03-07T00:45:37.274Z,1741308337.274 [multiray_test:A] Running Loop=1
2025-03-07T00:45:37.275Z,1741308337.275 [multiray_test:B] Running Loop=1
2025-03-07T00:45:37.275Z,1741308337.275 [multiray_test:B] Stopped
2025-03-07T00:45:37.275Z,1741308337.275 [multiray_test:C.Wait] Running Loop=1
2025-03-07T00:45:37.276Z,1741308337.276 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:37.276Z,1741308337.276 [multiray_test:A] Running Loop=1
2025-03-07T00:45:37.276Z,1741308337.276 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2025-03-07T00:45:38.882Z,1741308338.882 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:39.674Z,1741308339.674 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:40.484Z,1741308340.484 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:41.291Z,1741308341.291 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:42.102Z,1741308342.102 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:42.918Z,1741308342.918 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:42.946Z,1741308342.946 [multiray_test:C.Wait](INFO): Done Waiting.
2025-03-07T00:45:42.946Z,1741308342.946 [multiray_test:C.Wait] Stopped
2025-03-07T00:45:42.946Z,1741308342.946 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:42.946Z,1741308342.946 [multiray_test:D] Running Loop=1
2025-03-07T00:45:43.374Z,1741308343.374 [multiray_test:D] Stopped
2025-03-07T00:45:43.375Z,1741308343.375 [multiray_test:E.Wait] Running Loop=1
2025-03-07T00:45:43.375Z,1741308343.375 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:48.990Z,1741308348.990 [multiray_test:E.Wait](INFO): Done Waiting.
2025-03-07T00:45:48.990Z,1741308348.990 [multiray_test:E.Wait] Stopped
2025-03-07T00:45:48.990Z,1741308348.990 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:48.990Z,1741308348.990 [multiray_test:F] Running Loop=1
2025-03-07T00:45:49.466Z,1741308349.466 [multiray_test:F] Stopped
2025-03-07T00:45:49.466Z,1741308349.466 [multiray_test:G.Wait] Running Loop=1
2025-03-07T00:45:49.466Z,1741308349.466 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:55.046Z,1741308355.046 [multiray_test:G.Wait](INFO): Done Waiting.
2025-03-07T00:45:55.046Z,1741308355.046 [multiray_test:G.Wait] Stopped
2025-03-07T00:45:55.046Z,1741308355.046 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:45:55.046Z,1741308355.046 [multiray_test:H] Running Loop=1
2025-03-07T00:45:55.483Z,1741308355.483 [multiray_test:H] Stopped
2025-03-07T00:45:55.483Z,1741308355.483 [multiray_test:I.Wait] Running Loop=1
2025-03-07T00:45:55.484Z,1741308355.484 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:45:56.246Z,1741308356.246 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:57.046Z,1741308357.046 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:57.858Z,1741308357.858 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:58.663Z,1741308358.663 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:45:59.470Z,1741308359.470 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:00.282Z,1741308360.282 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:01.087Z,1741308361.087 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:01.098Z,1741308361.098 [multiray_test:I.Wait](INFO): Done Waiting.
2025-03-07T00:46:01.098Z,1741308361.098 [multiray_test:I.Wait] Stopped
2025-03-07T00:46:01.098Z,1741308361.098 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:46:01.098Z,1741308361.098 [multiray_test:J] Running Loop=1
2025-03-07T00:46:01.563Z,1741308361.563 [multiray_test:J] Stopped
2025-03-07T00:46:01.563Z,1741308361.563 [multiray_test:K.Wait] Running Loop=1
2025-03-07T00:46:01.563Z,1741308361.563 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:46:07.269Z,1741308367.269 [multiray_test:K.Wait](INFO): Done Waiting.
2025-03-07T00:46:07.269Z,1741308367.269 [multiray_test:K.Wait] Stopped
2025-03-07T00:46:07.269Z,1741308367.269 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:46:07.270Z,1741308367.270 [multiray_test:L] Running Loop=1
2025-03-07T00:46:07.643Z,1741308367.643 [multiray_test:L] Stopped
2025-03-07T00:46:07.644Z,1741308367.644 [multiray_test:M.Wait] Running Loop=1
2025-03-07T00:46:07.644Z,1741308367.644 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:46:13.224Z,1741308373.224 [multiray_test:M.Wait](INFO): Done Waiting.
2025-03-07T00:46:13.224Z,1741308373.224 [multiray_test:M.Wait] Stopped
2025-03-07T00:46:13.224Z,1741308373.224 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:46:13.241Z,1741308373.241 [multiray_test:N] Running Loop=1
2025-03-07T00:46:13.658Z,1741308373.658 [multiray_test:N] Stopped
2025-03-07T00:46:13.658Z,1741308373.658 [multiray_test:O.Wait] Running Loop=1
2025-03-07T00:46:13.658Z,1741308373.658 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:46:14.478Z,1741308374.478 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:15.239Z,1741308375.239 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:16.047Z,1741308376.047 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:16.842Z,1741308376.842 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:17.650Z,1741308377.650 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:18.466Z,1741308378.466 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:19.328Z,1741308379.328 [MultiRay](INFO): All lights off failed! Try again next cycle
2025-03-07T00:46:19.355Z,1741308379.355 [multiray_test:O.Wait](INFO): Done Waiting.
2025-03-07T00:46:19.355Z,1741308379.355 [multiray_test:O.Wait] Stopped
2025-03-07T00:46:19.355Z,1741308379.355 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:46:19.355Z,1741308379.355 [multiray_test:P] Running Loop=1
2025-03-07T00:46:19.508Z,1741308379.508 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:46:19.508Z,1741308379.508 [MissionManager](INFO): Uninitializing Mission multiray_test
2025-03-07T00:46:19.508Z,1741308379.508 [multiray_test] Stopped
2025-03-07T00:46:19.508Z,1741308379.508 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2025-03-07T00:46:19.508Z,1741308379.508 [multiray_test:A] Stopped
2025-03-07T00:46:19.508Z,1741308379.508 [multiray_test:P] Stopped
2025-03-07T00:46:19.513Z,1741308379.513 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:46:19.514Z,1741308379.514 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:46:19.515Z,1741308379.515 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:46:20.098Z,1741308380.098 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lab_test_optim.tl translated into:
15
1
30
1
3
2
100
100
0
Waiting
for backseat to start up...
Disabling Optim recording after
Powering up Optim to try to tide over gap to handoff...
! echo 1 > /dev/loadC3
Optim power on. Spinning for
cycles.
1
! echo 0 > /dev/loadC3
Optim power off. Spinning again.
1
! echo 1 > /dev/loadC3
Optim power on. Handing off to mission.
! echo 1 > /dev/loadC3; sleep 1; echo 0 > /dev/loadC3; sleep 1; echo 1 > dev/loadC3
Hardcoded Optim command sent. Good luck.
Trying to run Optim and Multiray forreal
1
Setting multiray red brightness to
2
Setting muliray white brightness to
Recording for
2025-03-07T00:46:20.105Z,1741308380.105 [MissionManager](INFO): DefineArg lab_test_optim.MissionTimeout = 15.000000 min
2025-03-07T00:46:20.108Z,1741308380.108 [MissionManager](INFO): DefineArg lab_test_optim.RecordTime = 1.000000 min
2025-03-07T00:46:20.111Z,1741308380.111 [MissionManager](INFO): DefineArg lab_test_optim.BackseatWait = 30.000000 s
2025-03-07T00:46:20.114Z,1741308380.114 [MissionManager](INFO): DefineArg lab_test_optim.UseMission = 1 bool
2025-03-07T00:46:20.117Z,1741308380.117 [MissionManager](INFO): DefineArg lab_test_optim.UseHardcode = 0 bool
2025-03-07T00:46:20.120Z,1741308380.120 [MissionManager](INFO): DefineArg lab_test_optim.InitialSampleTime = 1.000000 s
2025-03-07T00:46:20.157Z,1741308380.157 [MissionManager](INFO): DefineArg lab_test_optim.SleepCycles = 3.000000 count
2025-03-07T00:46:20.160Z,1741308380.160 [MissionManager](INFO): DefineArg lab_test_optim.LightMode = 2.000000 count
2025-03-07T00:46:20.172Z,1741308380.172 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessWhite = 100.000000 n/a int32
2025-03-07T00:46:20.182Z,1741308380.182 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessRed = 100.000000 n/a int32
2025-03-07T00:46:20.207Z,1741308380.207 [MissionManager](INFO): DefineOutput lab_test_optim.CyclesWaited = 0.000000 count
2025-03-07T00:46:20.222Z,1741308380.222 [MissionManager](INFO): DefineOutput lab_test_optim.RecordingEnabled = 0 bool
2025-03-07T00:46:20.225Z,1741308380.225 [MissionManager](INFO): DefineOutput lab_test_optim.LightsEnabled = 0 bool
2025-03-07T00:46:20.226Z,1741308380.226 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-03-07T00:46:20.226Z,1741308380.226 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-03-07T00:46:20.246Z,1741308380.246 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-03-07T00:46:20.309Z,1741308380.309 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-03-07T00:46:20.331Z,1741308380.331 [MissionManager](INFO): DefineArg lab_test_optim:BackseatDriver.EnableBackseat = 0 bool
2025-03-07T00:46:20.340Z,1741308380.340 [lab_test_optim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-03-07T00:46:20.364Z,1741308380.364 [lab_test_optim:Startup:B.Wait](DEBUG): Construct Wait.
2025-03-07T00:46:20.373Z,1741308380.373 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Construct Wait.
2025-03-07T00:46:20.394Z,1741308380.394 [lab_test_optim:Startup:UseBackdoor:A.Execute](DEBUG): Construct Execute.
2025-03-07T00:46:20.422Z,1741308380.422 [lab_test_optim:Startup:UseBackdoor:D.Execute](DEBUG): Construct Execute.
2025-03-07T00:46:20.428Z,1741308380.428 [lab_test_optim:Startup:UseBackdoor:G.Execute](DEBUG): Construct Execute.
2025-03-07T00:46:20.449Z,1741308380.449 [lab_test_optim:Startup:UseHardcode:A.Execute](DEBUG): Construct Execute.
2025-03-07T00:46:20.512Z,1741308380.512 [lab_test_optim:Run:H.Wait](DEBUG): Construct Wait.
2025-03-07T00:46:20.522Z,1741308380.522 [lab_test_optim:G.Wait](DEBUG): Construct Wait.
2025-03-07T00:46:20.558Z,1741308380.558 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission lab_test_optim {
arguments {
MissionTimeout = 15 minute
"""
Backseat will stay on for this duration, independent of Optim state
"""
RecordTime = 1 minute
"""
How long to record after startup sequence (hopefully) turns on Optim camera
"""
BackseatWait = 30 second
"""
How long to wait for the backseat to power up before attempting to power the Optim
"""
UseMission = true
"""
If true, tries to go through the application and use components/behaviors for commands.
Set InitialSampleTime to change delays. If false, backdoors app. Use SleepCycles to change delays.
Optim must be on C3.
"""
UseHardcode = false
"""
If true, backdoors app with a powercycle and a hardcoded sleep 1. Try the two other modes first?
Optim must be on C3.
"""
InitialSampleTime = 1 second
"""
PowerOnly sample time for initial record. Will go back to continuous after startup.
"""
SleepCycles = 3 count
"""
How many vehicle computation cycles to wait before commanding the Optim off on initial record
"""
LightMode = 2 count
"""
Mode for Multiray lights. 0 is off, 1 is red, 2 is white
"""
BrightnessWhite = 100 none_int
"""
White Multiray LED brightness, 0-100
"""
BrightnessRed = 100 none_int
"""
Red Multiray LED brightness, 0-100
"""
}
output {
CyclesWaited = 0 count
RecordingEnabled = false
LightsEnabled = false
}
timeout duration=MissionTimeout
insert Insert/BackseatDriver.tl
assign in parallel BackseatDriver:EnableBackseat = true
readData strategy="MinError" {
while ( RecordingEnabled )
Sensor:PowerOnly.sampleLoad1
}
readData strategy="MinError" {
while ( LightsEnabled )
Sensor:MultiRay.lightModeLog
}
aggregate Startup {
run in sequence
syslog important "Waiting " + BackseatWait~s + " for backseat to start up..."
behavior Guidance:Wait {
run in sequence
set duration = BackseatWait
}
aggregate UseMission {
run in sequence
break if (
not UseMission
or UseHardcode
)
assign in parallel Sensor:PowerOnly.sampleTime1 = InitialSampleTime
assign in sequence RecordingEnabled = true
syslog important "Disabling Optim recording after " + InitialSampleTime~s
assign in sequence RecordingEnabled = false
behavior Guidance:Wait {
run in sequence
set duration = InitialSampleTime
}
assign in sequence RecordingEnabled = true
syslog important "Powering up Optim to try to tide over gap to handoff..."
}
aggregate UseBackdoor {
run in sequence
break if (
UseMission
or UseHardcode
)
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3"
}
syslog important "Optim power on. Spinning for " + SleepCycles~count + " cycles."
aggregate WaitForStop {
run in sequence repeat=SleepCycles
assign in sequence CyclesWaited = CyclesWaited + 1 count
}
behavior Guidance:Execute {
run in sequence
set command = "! echo 0 > /dev/loadC3"
}
syslog important "Optim power off. Spinning again."
aggregate WaitForStart {
run in sequence repeat=SleepCycles
assign in sequence CyclesWaited = CyclesWaited + 1 count
}
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3"
}
syslog important "Optim power on. Handing off to mission."
}
aggregate UseHardcode {
run in sequence
break if ( not UseHardcode )
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3; sleep 1; echo 0 > /dev/loadC3; sleep 1; echo 1 > dev/loadC3"
}
syslog important "Hardcoded Optim command sent. Good luck."
}
}
aggregate Run {
run in sequence
syslog important "Trying to run Optim and Multiray forreal"
assign in sequence RecordingEnabled = true
assign in sequence LightsEnabled = true
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode
aggregate setRed {
run in sequence
break if ( LightMode != 1 count )
assign in sequence Sensor:MultiRay.brightnessRed = BrightnessRed
syslog info "Setting multiray red brightness to" + BrightnessRed~count
}
aggregate setWhite {
run in sequence
break if ( LightMode != 2 count )
assign in sequence Sensor:MultiRay.brightnessWhite = BrightnessWhite
syslog info "Setting muliray white brightness to" + BrightnessWhite~count
}
syslog important "Recording for " + RecordTime~min
behavior Guidance:Wait {
run in sequence
set duration = RecordTime
}
}
behavior Guidance:Wait {
run in sequence
set duration = MissionTimeout
}
}
2025-03-07T00:46:20.559Z,1741308380.559 [CommandExec](IMPORTANT): Running ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:46:20.901Z,1741308380.901 [MissionManager](IMPORTANT): Started mission lab_test_optim
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim] Running Loop=1
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim](DEBUG): Aggregate::initialize lab_test_optim
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:BackseatDriver] Running Loop=1
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::initialize lab_test_optim:BackseatDriver
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:B] Running Loop=1
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:C] Running Loop=1
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:D] Running Loop=1
2025-03-07T00:46:20.902Z,1741308380.902 [lab_test_optim:Startup] Running Loop=1
2025-03-07T00:46:20.903Z,1741308380.903 [lab_test_optim:Startup](DEBUG): Aggregate::initialize lab_test_optim:Startup
2025-03-07T00:46:20.903Z,1741308380.903 [lab_test_optim:Startup:A] Running Loop=1
2025-03-07T00:46:20.903Z,1741308380.903 [lab_test_optim:Startup:A](IMPORTANT): Waiting 30.000000 s for backseat to start up...
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:Startup:A] Stopped
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:Startup:B.Wait] Running Loop=1
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:Startup:B.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:D] Stopped
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:C] Stopped
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:B] Running Loop=1
2025-03-07T00:46:20.904Z,1741308380.904 [lab_test_optim:BackseatDriver] Running Loop=1
2025-03-07T00:46:20.905Z,1741308380.905 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-03-07T00:46:21.061Z,1741308381.061 [BackseatComponent](INFO): Powering up
2025-03-07T00:46:21.062Z,1741308381.062 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:B.Wait](INFO): Done Waiting.
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:B.Wait] Stopped
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:B.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:UseMission] Running Loop=1
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:UseMission](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseMission
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:UseMission:A] Running Loop=1
2025-03-07T00:46:51.615Z,1741308411.615 [lab_test_optim:Startup:UseMission:B] Running Loop=1
2025-03-07T00:46:52.001Z,1741308412.001 [lab_test_optim:Startup:UseMission:B] Stopped
2025-03-07T00:46:52.002Z,1741308412.002 [lab_test_optim:Startup:UseMission:C] Running Loop=1
2025-03-07T00:46:52.002Z,1741308412.002 [lab_test_optim:Startup:UseMission:A] Running Loop=1
2025-03-07T00:46:52.408Z,1741308412.408 [lab_test_optim:Startup:UseMission:C](IMPORTANT): Disabling Optim recording after 1.000000 s
2025-03-07T00:46:52.408Z,1741308412.408 [lab_test_optim:Startup:UseMission:C] Stopped
2025-03-07T00:46:52.409Z,1741308412.409 [lab_test_optim:Startup:UseMission:D] Running Loop=1
2025-03-07T00:46:52.409Z,1741308412.409 [lab_test_optim:C] Running Loop=1
2025-03-07T00:46:52.409Z,1741308412.409 [lab_test_optim:C] Running Loop=1
2025-03-07T00:46:52.409Z,1741308412.409 [lab_test_optim:C](CRITICAL): Unable to read non-existent URI:PowerOnly.sampleLoad1
2025-03-07T00:46:52.409Z,1741308412.409 [lab_test_optim:C](CRITICAL): Unable to read non-existent data element: PowerOnly.sampleLoad1
2025-03-07T00:46:52.419Z,1741308412.419 [CBIT](INFO): Critical error at 20250307T004652
2025-03-07T00:46:52.419Z,1741308412.419 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-07T00:46:52.819Z,1741308412.819 [lab_test_optim:Startup:UseMission:D] Stopped
2025-03-07T00:46:52.819Z,1741308412.819 [lab_test_optim:Startup:UseMission:E.Wait] Running Loop=1
2025-03-07T00:46:52.819Z,1741308412.819 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:46:52.820Z,1741308412.820 [lab_test_optim:C](CRITICAL): Unable to read non-existent URI:PowerOnly.sampleLoad1
2025-03-07T00:46:52.820Z,1741308412.820 [lab_test_optim:C](CRITICAL): Unable to read non-existent data element: PowerOnly.sampleLoad1
2025-03-07T00:46:52.821Z,1741308412.821 [MissionManager](INFO): MissionManager is completed.
2025-03-07T00:46:52.821Z,1741308412.821 [MissionManager](INFO): Uninitializing Mission lab_test_optim
2025-03-07T00:46:52.821Z,1741308412.821 [lab_test_optim] Stopped
2025-03-07T00:46:52.821Z,1741308412.821 [lab_test_optim](DEBUG): Aggregate::uninitialize lab_test_optim
2025-03-07T00:46:52.821Z,1741308412.821 [lab_test_optim:BackseatDriver] Stopped
2025-03-07T00:46:52.821Z,1741308412.821 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::uninitialize lab_test_optim:BackseatDriver
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:BackseatDriver:A.BackseatDriver] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:B] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:C] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup](DEBUG): Aggregate::uninitialize lab_test_optim:Startup
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup:UseMission] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup:UseMission](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseMission
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup:UseMission:A] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup:UseMission:E.Wait] Stopped
2025-03-07T00:46:52.822Z,1741308412.822 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:46:52.845Z,1741308412.845 [CBIT](INFO): Critical error at 20250307T004652
2025-03-07T00:46:53.234Z,1741308413.234 [MissionManager](IMPORTANT): Started mission Default
2025-03-07T00:46:53.234Z,1741308413.234 [Default] Running Loop=1
2025-03-07T00:46:53.234Z,1741308413.234 [Default](DEBUG): Aggregate::initialize Default
2025-03-07T00:46:53.234Z,1741308413.234 [Default:B.GoToSurface] Running Loop=1
2025-03-07T00:46:53.234Z,1741308413.234 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-07T00:46:53.234Z,1741308413.234 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-07T00:46:53.235Z,1741308413.235 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-07T00:46:53.235Z,1741308413.235 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-07T00:46:53.235Z,1741308413.235 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-07T00:46:53.236Z,1741308413.236 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-07T00:46:53.236Z,1741308413.236 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-07T00:46:53.236Z,1741308413.236 [Default:A.Wait] Running Loop=1
2025-03-07T00:46:53.236Z,1741308413.236 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:46:54.447Z,1741308414.447 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-03-07T00:46:54.447Z,1741308414.447 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-03-07T00:47:00.110Z,1741308420.110 [CommandExec](IMPORTANT): got command configSet list
2025-03-07T00:47:00.110Z,1741308420.110 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-07T00:47:00.111Z,1741308420.111 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-03-07T00:47:00.112Z,1741308420.112 [CommandExec](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2025-03-07T00:47:00.113Z,1741308420.113 [CommandExec](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-03-07T00:47:00.113Z,1741308420.113 [CommandExec](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-03-07T00:47:00.114Z,1741308420.114 [CommandExec](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-03-07T00:47:00.115Z,1741308420.115 [CommandExec](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2025-03-07T00:47:00.116Z,1741308420.116 [CommandExec](IMPORTANT): GFScanner.loadAtStartup=1 bool;
2025-03-07T00:47:00.116Z,1741308420.116 [CommandExec](IMPORTANT): GFScanner.simulateHardware=0 bool;
2025-03-07T00:47:00.134Z,1741308420.134 [CommandExec](IMPORTANT): MultiRay.loadAtStartup=1 bool;
2025-03-07T00:47:00.135Z,1741308420.135 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2025-03-07T00:47:00.136Z,1741308420.136 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2025-03-07T00:47:00.136Z,1741308420.136 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=185.902950 cubic_centimeter;
2025-03-07T00:47:00.139Z,1741308420.139 [CommandExec](IMPORTANT): VerticalControl.massDefault=13.432478 millimeter;
2025-03-07T00:47:06.553Z,1741308426.553 [Default:A.Wait](INFO): Done Waiting.
2025-03-07T00:47:06.553Z,1741308426.553 [Default:A.Wait] Stopped
2025-03-07T00:47:06.553Z,1741308426.553 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:47:06.969Z,1741308426.969 [Default:CheckIn] Running Loop=1
2025-03-07T00:47:06.969Z,1741308426.969 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-07T00:47:06.969Z,1741308426.969 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-07T00:47:07.361Z,1741308427.361 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-07T00:47:12.634Z,1741308432.634 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-03-07T00:47:12.634Z,1741308432.634 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-03-07T00:47:12.635Z,1741308432.635 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-03-07T00:47:50.719Z,1741308470.719 [CommandExec](IMPORTANT): got command show variable poweronly
2025-03-07T00:47:50.739Z,1741308470.739 [CommandExec](IMPORTANT): PowerOnly.loadControl (none)
2025-03-07T00:47:50.740Z,1741308470.740 [CommandExec](IMPORTANT): PowerOnly.loadControl2 (none)
2025-03-07T00:47:50.740Z,1741308470.740 [CommandExec](IMPORTANT): PowerOnly.loadControl3 (none)
2025-03-07T00:47:50.818Z,1741308470.818 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup (bool)
2025-03-07T00:47:50.818Z,1741308470.818 [CommandExec](IMPORTANT): PowerOnly.simulateHardware (bool)
2025-03-07T00:47:50.818Z,1741308470.818 [CommandExec](IMPORTANT): PowerOnly.sampleTime (second)
2025-03-07T00:47:50.819Z,1741308470.819 [CommandExec](IMPORTANT): PowerOnly.sampleTime1 (second)
2025-03-07T00:47:50.819Z,1741308470.819 [CommandExec](IMPORTANT): PowerOnly.sampleTime2 (second)
2025-03-07T00:47:50.820Z,1741308470.820 [CommandExec](IMPORTANT): PowerOnly.sampleTime3 (second)
2025-03-07T00:47:50.858Z,1741308470.858 [CommandExec](IMPORTANT): PowerOnly.enableBroadcast (bool)
2025-03-07T00:47:50.859Z,1741308470.859 [CommandExec](IMPORTANT): PowerOnly.samplePowerOnly (bool)
2025-03-07T00:48:46.093Z,1741308526.093 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:48:46.094Z,1741308526.094 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:48:46.094Z,1741308526.094 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:48:46.095Z,1741308526.095 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:48:46.613Z,1741308526.613 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/lab_test_optim.tl translated into:
15
1
30
1
3
2
100
100
0
Waiting
for backseat to start up...
Disabling Optim recording after
Powering up Optim to try to tide over gap to handoff...
! echo 1 > /dev/loadC3
Optim power on. Spinning for
cycles.
1
! echo 0 > /dev/loadC3
Optim power off. Spinning again.
1
! echo 1 > /dev/loadC3
Optim power on. Handing off to mission.
! echo 1 > /dev/loadC3; sleep 1; echo 0 > /dev/loadC3; sleep 1; echo 1 > dev/loadC3
Hardcoded Optim command sent. Good luck.
Trying to run Optim and Multiray forreal
1
Setting multiray red brightness to
2
Setting muliray white brightness to
Recording for
2025-03-07T00:48:46.619Z,1741308526.619 [MissionManager](INFO): DefineArg lab_test_optim.MissionTimeout = 15.000000 min
2025-03-07T00:48:46.622Z,1741308526.622 [MissionManager](INFO): DefineArg lab_test_optim.RecordTime = 1.000000 min
2025-03-07T00:48:46.624Z,1741308526.624 [MissionManager](INFO): DefineArg lab_test_optim.BackseatWait = 30.000000 s
2025-03-07T00:48:46.626Z,1741308526.626 [MissionManager](INFO): DefineArg lab_test_optim.UseMission = 1 bool
2025-03-07T00:48:46.628Z,1741308526.628 [MissionManager](INFO): DefineArg lab_test_optim.UseHardcode = 0 bool
2025-03-07T00:48:46.631Z,1741308526.631 [MissionManager](INFO): DefineArg lab_test_optim.InitialSampleTime = 1.000000 s
2025-03-07T00:48:46.633Z,1741308526.633 [MissionManager](INFO): DefineArg lab_test_optim.SleepCycles = 3.000000 count
2025-03-07T00:48:46.635Z,1741308526.635 [MissionManager](INFO): DefineArg lab_test_optim.LightMode = 2.000000 count
2025-03-07T00:48:46.638Z,1741308526.638 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessWhite = 100.000000 n/a int32
2025-03-07T00:48:46.640Z,1741308526.640 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessRed = 100.000000 n/a int32
2025-03-07T00:48:46.642Z,1741308526.642 [MissionManager](INFO): DefineOutput lab_test_optim.CyclesWaited = 0.000000 count
2025-03-07T00:48:46.644Z,1741308526.644 [MissionManager](INFO): DefineOutput lab_test_optim.RecordingEnabled = 0 bool
2025-03-07T00:48:46.683Z,1741308526.683 [MissionManager](INFO): DefineOutput lab_test_optim.LightsEnabled = 0 bool
2025-03-07T00:48:46.683Z,1741308526.683 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-03-07T00:48:46.683Z,1741308526.683 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-03-07T00:48:46.684Z,1741308526.684 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-03-07T00:48:46.800Z,1741308526.800 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-03-07T00:48:46.815Z,1741308526.815 [MissionManager](INFO): DefineArg lab_test_optim:BackseatDriver.EnableBackseat = 0 bool
2025-03-07T00:48:46.826Z,1741308526.826 [lab_test_optim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-03-07T00:48:46.846Z,1741308526.846 [lab_test_optim:Startup:B.Wait](DEBUG): Construct Wait.
2025-03-07T00:48:46.864Z,1741308526.864 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Construct Wait.
2025-03-07T00:48:46.872Z,1741308526.872 [lab_test_optim:Startup:UseBackdoor:A.Execute](DEBUG): Construct Execute.
2025-03-07T00:48:46.882Z,1741308526.882 [lab_test_optim:Startup:UseBackdoor:D.Execute](DEBUG): Construct Execute.
2025-03-07T00:48:46.887Z,1741308526.887 [lab_test_optim:Startup:UseBackdoor:G.Execute](DEBUG): Construct Execute.
2025-03-07T00:48:46.894Z,1741308526.894 [lab_test_optim:Startup:UseHardcode:A.Execute](DEBUG): Construct Execute.
2025-03-07T00:48:46.926Z,1741308526.926 [lab_test_optim:Run:H.Wait](DEBUG): Construct Wait.
2025-03-07T00:48:46.927Z,1741308526.927 [lab_test_optim:G.Wait](DEBUG): Construct Wait.
2025-03-07T00:48:46.936Z,1741308526.936 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission lab_test_optim {
arguments {
MissionTimeout = 15 minute
"""
Backseat will stay on for this duration, independent of Optim state
"""
RecordTime = 1 minute
"""
How long to record after startup sequence (hopefully) turns on Optim camera
"""
BackseatWait = 30 second
"""
How long to wait for the backseat to power up before attempting to power the Optim
"""
UseMission = true
"""
If true, tries to go through the application and use components/behaviors for commands.
Set InitialSampleTime to change delays. If false, backdoors app. Use SleepCycles to change delays.
Optim must be on C3.
"""
UseHardcode = false
"""
If true, backdoors app with a powercycle and a hardcoded sleep 1. Try the two other modes first?
Optim must be on C3.
"""
InitialSampleTime = 1 second
"""
PowerOnly sample time for initial record. Will go back to continuous after startup.
"""
SleepCycles = 3 count
"""
How many vehicle computation cycles to wait before commanding the Optim off on initial record
"""
LightMode = 2 count
"""
Mode for Multiray lights. 0 is off, 1 is red, 2 is white
"""
BrightnessWhite = 100 none_int
"""
White Multiray LED brightness, 0-100
"""
BrightnessRed = 100 none_int
"""
Red Multiray LED brightness, 0-100
"""
}
output {
CyclesWaited = 0 count
RecordingEnabled = false
LightsEnabled = false
}
timeout duration=MissionTimeout
insert Insert/BackseatDriver.tl
assign in parallel BackseatDriver:EnableBackseat = true
readData strategy="MinError" {
while ( RecordingEnabled )
Sensor:PowerOnly.samplePowerOnly
}
readData strategy="MinError" {
while ( LightsEnabled )
Sensor:MultiRay.lightModeLog
}
aggregate Startup {
run in sequence
syslog important "Waiting " + BackseatWait~s + " for backseat to start up..."
behavior Guidance:Wait {
run in sequence
set duration = BackseatWait
}
aggregate UseMission {
run in sequence
break if (
not UseMission
or UseHardcode
)
assign in parallel Sensor:PowerOnly.sampleTime1 = InitialSampleTime
assign in sequence RecordingEnabled = true
syslog important "Disabling Optim recording after " + InitialSampleTime~s
assign in sequence RecordingEnabled = false
behavior Guidance:Wait {
run in sequence
set duration = InitialSampleTime
}
assign in sequence RecordingEnabled = true
syslog important "Powering up Optim to try to tide over gap to handoff..."
}
aggregate UseBackdoor {
run in sequence
break if (
UseMission
or UseHardcode
)
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3"
}
syslog important "Optim power on. Spinning for " + SleepCycles~count + " cycles."
aggregate WaitForStop {
run in sequence repeat=SleepCycles
assign in sequence CyclesWaited = CyclesWaited + 1 count
}
behavior Guidance:Execute {
run in sequence
set command = "! echo 0 > /dev/loadC3"
}
syslog important "Optim power off. Spinning again."
aggregate WaitForStart {
run in sequence repeat=SleepCycles
assign in sequence CyclesWaited = CyclesWaited + 1 count
}
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3"
}
syslog important "Optim power on. Handing off to mission."
}
aggregate UseHardcode {
run in sequence
break if ( not UseHardcode )
behavior Guidance:Execute {
run in sequence
set command = "! echo 1 > /dev/loadC3; sleep 1; echo 0 > /dev/loadC3; sleep 1; echo 1 > dev/loadC3"
}
syslog important "Hardcoded Optim command sent. Good luck."
}
}
aggregate Run {
run in sequence
syslog important "Trying to run Optim and Multiray forreal"
assign in sequence RecordingEnabled = true
assign in sequence LightsEnabled = true
assign in sequence Sensor:MultiRay.lightModeCommand = LightMode
aggregate setRed {
run in sequence
break if ( LightMode != 1 count )
assign in sequence Sensor:MultiRay.brightnessRed = BrightnessRed
syslog info "Setting multiray red brightness to" + BrightnessRed~count
}
aggregate setWhite {
run in sequence
break if ( LightMode != 2 count )
assign in sequence Sensor:MultiRay.brightnessWhite = BrightnessWhite
syslog info "Setting muliray white brightness to" + BrightnessWhite~count
}
syslog important "Recording for " + RecordTime~min
behavior Guidance:Wait {
run in sequence
set duration = RecordTime
}
}
behavior Guidance:Wait {
run in sequence
set duration = MissionTimeout
}
}
2025-03-07T00:48:46.978Z,1741308526.978 [CommandExec](IMPORTANT): Running ./Missions/Engineering/lab_test_optim.tl
2025-03-07T00:48:47.147Z,1741308527.147 [Default] Stopped
2025-03-07T00:48:47.147Z,1741308527.147 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-07T00:48:47.147Z,1741308527.147 [Default:B.GoToSurface] Stopped
2025-03-07T00:48:47.147Z,1741308527.147 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-07T00:48:47.148Z,1741308527.148 [Default:CheckIn] Stopped
2025-03-07T00:48:47.148Z,1741308527.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-07T00:48:47.148Z,1741308527.148 [Default:CheckIn:Read_GPS] Stopped
2025-03-07T00:48:47.148Z,1741308527.148 [MissionManager](IMPORTANT): Started mission lab_test_optim
2025-03-07T00:48:47.148Z,1741308527.148 [lab_test_optim] Running Loop=1
2025-03-07T00:48:47.148Z,1741308527.148 [lab_test_optim](DEBUG): Aggregate::initialize lab_test_optim
2025-03-07T00:48:47.148Z,1741308527.148 [lab_test_optim:BackseatDriver] Running Loop=1
2025-03-07T00:48:47.148Z,1741308527.148 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::initialize lab_test_optim:BackseatDriver
2025-03-07T00:48:47.149Z,1741308527.149 [lab_test_optim:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-03-07T00:48:47.149Z,1741308527.149 [lab_test_optim:B] Running Loop=1
2025-03-07T00:48:47.149Z,1741308527.149 [lab_test_optim:C] Running Loop=1
2025-03-07T00:48:47.149Z,1741308527.149 [lab_test_optim:D] Running Loop=1
2025-03-07T00:48:47.149Z,1741308527.149 [lab_test_optim:Startup] Running Loop=1
2025-03-07T00:48:47.149Z,1741308527.149 [lab_test_optim:Startup](DEBUG): Aggregate::initialize lab_test_optim:Startup
2025-03-07T00:48:47.150Z,1741308527.150 [lab_test_optim:Startup:A] Running Loop=1
2025-03-07T00:48:47.150Z,1741308527.150 [lab_test_optim:Startup:A](IMPORTANT): Waiting 30.000000 s for backseat to start up...
2025-03-07T00:48:47.150Z,1741308527.150 [lab_test_optim:Startup:A] Stopped
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:Startup:B.Wait] Running Loop=1
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:Startup:B.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:D] Stopped
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:C] Stopped
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:B] Running Loop=1
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:BackseatDriver] Running Loop=1
2025-03-07T00:48:47.151Z,1741308527.151 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-03-07T00:48:47.241Z,1741308527.241 [BackseatComponent](INFO): Powering up
2025-03-07T00:48:47.242Z,1741308527.242 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-03-07T00:49:17.853Z,1741308557.853 [lab_test_optim:Startup:B.Wait](INFO): Done Waiting.
2025-03-07T00:49:17.853Z,1741308557.853 [lab_test_optim:Startup:B.Wait] Stopped
2025-03-07T00:49:17.853Z,1741308557.853 [lab_test_optim:Startup:B.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:49:17.853Z,1741308557.853 [lab_test_optim:Startup:UseMission] Running Loop=1
2025-03-07T00:49:17.853Z,1741308557.853 [lab_test_optim:Startup:UseMission](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseMission
2025-03-07T00:49:17.854Z,1741308557.854 [lab_test_optim:Startup:UseMission:A] Running Loop=1
2025-03-07T00:49:17.854Z,1741308557.854 [lab_test_optim:Startup:UseMission:B] Running Loop=1
2025-03-07T00:49:18.254Z,1741308558.254 [lab_test_optim:Startup:UseMission:B] Stopped
2025-03-07T00:49:18.254Z,1741308558.254 [lab_test_optim:Startup:UseMission:C] Running Loop=1
2025-03-07T00:49:18.254Z,1741308558.254 [lab_test_optim:Startup:UseMission:A] Running Loop=1
2025-03-07T00:49:18.664Z,1741308558.664 [lab_test_optim:Startup:UseMission:C](IMPORTANT): Disabling Optim recording after 1.000000 s
2025-03-07T00:49:18.664Z,1741308558.664 [lab_test_optim:Startup:UseMission:C] Stopped
2025-03-07T00:49:18.664Z,1741308558.664 [lab_test_optim:Startup:UseMission:D] Running Loop=1
2025-03-07T00:49:18.664Z,1741308558.664 [lab_test_optim:C] Running Loop=1
2025-03-07T00:49:18.664Z,1741308558.664 [lab_test_optim:C] Running Loop=1
2025-03-07T00:49:18.665Z,1741308558.665 [lab_test_optim:C](DEBUG): Initialize ReadDataComponent to sense PowerOnly.samplePowerOnly
2025-03-07T00:49:19.078Z,1741308559.078 [lab_test_optim:Startup:UseMission:D] Stopped
2025-03-07T00:49:19.078Z,1741308559.078 [lab_test_optim:Startup:UseMission:E.Wait] Running Loop=1
2025-03-07T00:49:19.078Z,1741308559.078 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:49:19.462Z,1741308559.462 [lab_test_optim:C] Stopped
2025-03-07T00:49:20.674Z,1741308560.674 [lab_test_optim:Startup:UseMission:E.Wait](INFO): Done Waiting.
2025-03-07T00:49:20.674Z,1741308560.674 [lab_test_optim:Startup:UseMission:E.Wait] Stopped
2025-03-07T00:49:20.674Z,1741308560.674 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:49:20.674Z,1741308560.674 [lab_test_optim:Startup:UseMission:F] Running Loop=1
2025-03-07T00:49:21.078Z,1741308561.078 [lab_test_optim:Startup:UseMission:F] Stopped
2025-03-07T00:49:21.078Z,1741308561.078 [lab_test_optim:Startup:UseMission:G] Running Loop=1
2025-03-07T00:49:21.502Z,1741308561.502 [lab_test_optim:Startup:UseMission:G](IMPORTANT): Powering up Optim to try to tide over gap to handoff...
2025-03-07T00:49:21.502Z,1741308561.502 [lab_test_optim:Startup:UseMission:G] Stopped
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseMission](INFO): Completed lab_test_optim:Startup:UseMission
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseMission] Stopped
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseMission](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseMission
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseMission:A] Stopped
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseBackdoor] Running Loop=1
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseBackdoor](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseBackdoor
2025-03-07T00:49:21.503Z,1741308561.503 [lab_test_optim:Startup:UseBackdoor:A.Execute] Running Loop=1
2025-03-07T00:49:21.504Z,1741308561.504 [lab_test_optim:C] Running Loop=1
2025-03-07T00:49:21.918Z,1741308561.918 [lab_test_optim:Startup:UseBackdoor] Stopped
2025-03-07T00:49:21.918Z,1741308561.918 [lab_test_optim:Startup:UseBackdoor](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseBackdoor
2025-03-07T00:49:21.918Z,1741308561.918 [lab_test_optim:Startup:UseBackdoor:A.Execute] Stopped
2025-03-07T00:49:21.918Z,1741308561.918 [lab_test_optim:Startup:UseHardcode] Running Loop=1
2025-03-07T00:49:21.918Z,1741308561.918 [lab_test_optim:Startup:UseHardcode](DEBUG): Aggregate::initialize lab_test_optim:Startup:UseHardcode
2025-03-07T00:49:21.919Z,1741308561.919 [lab_test_optim:Startup:UseHardcode:A.Execute] Running Loop=1
2025-03-07T00:49:22.318Z,1741308562.318 [lab_test_optim:Startup:UseHardcode] Stopped
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Startup:UseHardcode](DEBUG): Aggregate::uninitialize lab_test_optim:Startup:UseHardcode
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Startup:UseHardcode:A.Execute] Stopped
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Startup](INFO): Completed lab_test_optim:Startup
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Startup] Stopped
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Startup](DEBUG): Aggregate::uninitialize lab_test_optim:Startup
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Run] Running Loop=1
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Run](DEBUG): Aggregate::initialize lab_test_optim:Run
2025-03-07T00:49:22.319Z,1741308562.319 [lab_test_optim:Run:A] Running Loop=1
2025-03-07T00:49:22.732Z,1741308562.732 [lab_test_optim:Run:A](IMPORTANT): Trying to run Optim and Multiray forreal
2025-03-07T00:49:22.732Z,1741308562.732 [lab_test_optim:Run:A] Stopped
2025-03-07T00:49:22.732Z,1741308562.732 [lab_test_optim:Run:B] Running Loop=1
2025-03-07T00:49:23.165Z,1741308563.165 [lab_test_optim:Run:B] Stopped
2025-03-07T00:49:23.165Z,1741308563.165 [lab_test_optim:Run:C] Running Loop=1
2025-03-07T00:49:23.533Z,1741308563.533 [lab_test_optim:Run:C] Stopped
2025-03-07T00:49:23.534Z,1741308563.534 [lab_test_optim:Run:D] Running Loop=1
2025-03-07T00:49:23.930Z,1741308563.930 [lab_test_optim:Run:D] Stopped
2025-03-07T00:49:23.930Z,1741308563.930 [lab_test_optim:Run:setRed] Running Loop=1
2025-03-07T00:49:23.930Z,1741308563.930 [lab_test_optim:Run:setRed](DEBUG): Aggregate::initialize lab_test_optim:Run:setRed
2025-03-07T00:49:23.931Z,1741308563.931 [lab_test_optim:Run:setRed:A] Running Loop=1
2025-03-07T00:49:23.931Z,1741308563.931 [lab_test_optim:D] Running Loop=1
2025-03-07T00:49:23.931Z,1741308563.931 [lab_test_optim:D] Running Loop=1
2025-03-07T00:49:23.931Z,1741308563.931 [lab_test_optim:D](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2025-03-07T00:49:24.329Z,1741308564.329 [lab_test_optim:Run:setRed] Stopped
2025-03-07T00:49:24.345Z,1741308564.345 [lab_test_optim:Run:setRed](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setRed
2025-03-07T00:49:24.345Z,1741308564.345 [lab_test_optim:Run:setRed:A] Stopped
2025-03-07T00:49:24.345Z,1741308564.345 [lab_test_optim:Run:setWhite] Running Loop=1
2025-03-07T00:49:24.345Z,1741308564.345 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::initialize lab_test_optim:Run:setWhite
2025-03-07T00:49:24.345Z,1741308564.345 [lab_test_optim:Run:setWhite:A] Running Loop=1
2025-03-07T00:49:24.735Z,1741308564.735 [lab_test_optim:Run:setWhite:A] Stopped
2025-03-07T00:49:24.735Z,1741308564.735 [lab_test_optim:Run:setWhite:B] Running Loop=1
2025-03-07T00:49:25.144Z,1741308565.144 [lab_test_optim:Run:setWhite:B](INFO): Setting muliray white brightness to 100.000000 count
2025-03-07T00:49:25.144Z,1741308565.144 [lab_test_optim:Run:setWhite:B] Stopped
2025-03-07T00:49:25.144Z,1741308565.144 [lab_test_optim:Run:setWhite](INFO): Completed lab_test_optim:Run:setWhite
2025-03-07T00:49:25.144Z,1741308565.144 [lab_test_optim:Run:setWhite] Stopped
2025-03-07T00:49:25.144Z,1741308565.144 [lab_test_optim:Run:setWhite](DEBUG): Aggregate::uninitialize lab_test_optim:Run:setWhite
2025-03-07T00:49:25.145Z,1741308565.145 [lab_test_optim:Run:G] Running Loop=1
2025-03-07T00:49:25.522Z,1741308565.522 [lab_test_optim:Run:G](IMPORTANT): Recording for 1.000000 min
2025-03-07T00:49:25.522Z,1741308565.522 [lab_test_optim:Run:G] Stopped
2025-03-07T00:49:25.522Z,1741308565.522 [lab_test_optim:Run:H.Wait] Running Loop=1
2025-03-07T00:49:25.522Z,1741308565.522 [lab_test_optim:Run:H.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:49:37.234Z,1741308577.234 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-03-07T00:49:37.237Z,1741308577.237 [BPC1](INFO): Received data from all battery sticks.
2025-03-07T00:50:26.125Z,1741308626.125 [lab_test_optim:Run:H.Wait](INFO): Done Waiting.
2025-03-07T00:50:26.125Z,1741308626.125 [lab_test_optim:Run:H.Wait] Stopped
2025-03-07T00:50:26.126Z,1741308626.126 [lab_test_optim:Run:H.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:50:26.126Z,1741308626.126 [lab_test_optim:Run](INFO): Completed lab_test_optim:Run
2025-03-07T00:50:26.127Z,1741308626.127 [lab_test_optim:Run] Stopped
2025-03-07T00:50:26.127Z,1741308626.127 [lab_test_optim:Run](DEBUG): Aggregate::uninitialize lab_test_optim:Run
2025-03-07T00:50:26.127Z,1741308626.127 [lab_test_optim:G.Wait] Running Loop=1
2025-03-07T00:50:26.127Z,1741308626.127 [lab_test_optim:G.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:51:41.474Z,1741308701.474 [CommandExec](IMPORTANT): got command show stack
2025-03-07T00:51:41.474Z,1741308701.474 [CommandExec](IMPORTANT): Behavior Stack:
2025-03-07T00:51:41.475Z,1741308701.475 [lab_test_optim:BackseatDriver](IMPORTANT): Priority 0: lab_test_optim:BackseatDriver:A.BackseatDriver
2025-03-07T00:51:41.475Z,1741308701.475 [lab_test_optim](IMPORTANT): Priority 1: lab_test_optim:B
2025-03-07T00:51:41.476Z,1741308701.476 [lab_test_optim](IMPORTANT): Priority 2: lab_test_optim:C
2025-03-07T00:51:41.476Z,1741308701.476 [lab_test_optim](IMPORTANT): Priority 3: lab_test_optim:D
2025-03-07T00:51:41.476Z,1741308701.476 [lab_test_optim](IMPORTANT): Priority 4: lab_test_optim:G.Wait
2025-03-07T00:52:11.155Z,1741308731.155 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-07T00:52:41.882Z,1741308761.882 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-07T00:54:05.613Z,1741308845.613 [BackseatComponent](FAULT): Failed to receive device response within the specified timeout.
2025-03-07T00:54:05.613Z,1741308845.613 [BackseatComponent] Communications Fault, FailCount= 1
2025-03-07T00:54:05.613Z,1741308845.613 [BackseatComponent](ERROR): Communications Fault
2025-03-07T00:54:05.614Z,1741308845.614 [Power24vConverter] Hardware Fault, FailCount= 1
2025-03-07T00:54:05.614Z,1741308845.614 [Power24vConverter](ERROR): Hardware Fault
2025-03-07T00:54:05.952Z,1741308845.952 [Power24vConverter](INFO): Powering down.
2025-03-07T00:54:05.973Z,1741308845.973 [CBIT](ERROR): Communications Fault in component: BackseatComponent
2025-03-07T00:54:05.973Z,1741308845.973 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-03-07T00:54:06.018Z,1741308846.018 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-03-07T00:54:06.019Z,1741308846.019 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-03-07T00:54:06.763Z,1741308846.763 [Power24vConverter](INFO): Powering up.
2025-03-07T00:54:06.785Z,1741308846.785 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-03-07T00:54:06.785Z,1741308846.785 [Power24vConverter] No Fault, FailCount= 1
2025-03-07T00:54:24.213Z,1741308864.213 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-03-07T00:54:24.214Z,1741308864.214 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-03-07T00:54:24.214Z,1741308864.214 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-03-07T00:54:42.805Z,1741308882.805 [BackseatComponent](INFO): Powering up
2025-03-07T00:54:42.806Z,1741308882.806 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-03-07T00:54:43.160Z,1741308883.160 [CBIT](INFO): Clearing failed state for component BackseatComponent
2025-03-07T00:54:43.161Z,1741308883.161 [BackseatComponent] No Fault, FailCount= 1
2025-03-07T00:54:58.534Z,1741308898.534 [CommandExec](IMPORTANT): got command show stack
2025-03-07T00:54:58.534Z,1741308898.534 [CommandExec](IMPORTANT): Behavior Stack:
2025-03-07T00:54:58.535Z,1741308898.535 [lab_test_optim:BackseatDriver](IMPORTANT): Priority 0: lab_test_optim:BackseatDriver:A.BackseatDriver
2025-03-07T00:54:58.535Z,1741308898.535 [lab_test_optim](IMPORTANT): Priority 1: lab_test_optim:B
2025-03-07T00:54:58.535Z,1741308898.535 [lab_test_optim](IMPORTANT): Priority 2: lab_test_optim:C
2025-03-07T00:54:58.536Z,1741308898.536 [lab_test_optim](IMPORTANT): Priority 3: lab_test_optim:D
2025-03-07T00:54:58.536Z,1741308898.536 [lab_test_optim](IMPORTANT): Priority 4: lab_test_optim:G.Wait
2025-03-07T00:55:18.487Z,1741308918.487 [CommandExec](IMPORTANT): got command run
2025-03-07T00:55:18.487Z,1741308918.487 [CommandExec](FAULT): Incomplete syntax. Try: help run
2025-03-07T00:55:21.002Z,1741308921.002 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.tl
2025-03-07T00:55:21.003Z,1741308921.003 [MissionManager](INFO): Uninitializing Mission lab_test_optim
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim](DEBUG): Aggregate::uninitialize lab_test_optim
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:BackseatDriver] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::uninitialize lab_test_optim:BackseatDriver
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:BackseatDriver:A.BackseatDriver] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:B] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:C] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:D] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:G.Wait] Stopped
2025-03-07T00:55:21.003Z,1741308921.003 [lab_test_optim:G.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T00:55:21.020Z,1741308921.020 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.tl
2025-03-07T00:55:21.020Z,1741308921.020 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/run_backseat_on_surface.tl
2025-03-07T00:55:21.031Z,1741308921.031 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/run_backseat_on_surface.tl
2025-03-07T00:55:21.175Z,1741308921.174 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-03-07T00:55:21.175Z,1741308921.175 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-03-07T00:55:23.745Z,1741308923.745 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/run_backseat_on_surface.tl translated into:
0
Burn on
Dropped weight due to communications timeout.
5
run_backseat_on_surface mission has been running for
Restarting logs and run_backseat_on_surface mission.
restart logs
2025-03-07T00:55:23.749Z,1741308923.749 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2025-03-07T00:55:23.750Z,1741308923.750 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2025-03-07T00:55:23.750Z,1741308923.750 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl
2025-03-07T00:55:23.754Z,1741308923.754 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl
2025-03-07T00:55:26.224Z,1741308926.224 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into:
NaN
3
100
80
20
5
NaN
NaN
2.0
NaN
0
NaN
NaN
5
0
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
NaN
PeakDetectVsDepth
EdgeDetectVsDepth
GoDown, ChlPeakThresh, PeakChl, PeakChlDepth, EdgeChl, EdgeChlDepth =
,
,
,
,
,
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
PeakDetectVsDepth
_.planktivore_LM_AvgRois
EdgeDetectVsDepth
_.planktivore_LM_AvgRois
GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth =
,
,
,
,
,
PeakDetectVsDepth
_.planktivore_HM_AvgRois
PeakDetectVsDepth
_.planktivore_diatoms
PeakDetectVsDepth
_.planktivore_dinoflagellates
Triggered. PeakChl values on
consecutive yo-yo profiles fall in [
] (NaN bound is ignored).
2025-03-07T00:55:26.259Z,1741308926.259 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2025-03-07T00:55:26.261Z,1741308926.261 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EdgeDetectChlActive = 0 bool
2025-03-07T00:55:26.264Z,1741308926.264 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2025-03-07T00:55:26.267Z,1741308926.267 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2025-03-07T00:55:26.269Z,1741308926.269 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2025-03-07T00:55:26.272Z,1741308926.272 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2025-03-07T00:55:26.275Z,1741308926.275 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2025-03-07T00:55:26.315Z,1741308926.315 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2025-03-07T00:55:26.318Z,1741308926.318 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2025-03-07T00:55:26.321Z,1741308926.321 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2025-03-07T00:55:26.324Z,1741308926.324 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2025-03-07T00:55:26.363Z,1741308926.363 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2025-03-07T00:55:26.365Z,1741308926.365 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2025-03-07T00:55:26.368Z,1741308926.368 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2025-03-07T00:55:26.391Z,1741308926.391 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectPlanktivoreLMavgROIActive = 0 bool
2025-03-07T00:55:26.394Z,1741308926.394 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EdgeDetectPlanktivoreLMavgROIActive = 0 bool
2025-03-07T00:55:26.397Z,1741308926.397 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectPlanktivoreHMavgROIActive = 0 bool
2025-03-07T00:55:26.420Z,1741308926.420 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectPlanktivoreDiatomsActive = 0 bool
2025-03-07T00:55:26.422Z,1741308926.422 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectPlanktivoreDinoflagellatesActive = 0 bool
2025-03-07T00:55:26.439Z,1741308926.439 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2025-03-07T00:55:26.443Z,1741308926.443 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2025-03-07T00:55:26.468Z,1741308926.468 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2025-03-07T00:55:26.480Z,1741308926.480 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2025-03-07T00:55:26.500Z,1741308926.500 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2025-03-07T00:55:26.508Z,1741308926.508 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2025-03-07T00:55:26.516Z,1741308926.516 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2025-03-07T00:55:26.523Z,1741308926.523 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2025-03-07T00:55:26.542Z,1741308926.542 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2025-03-07T00:55:26.557Z,1741308926.557 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ShallowBound = nan m
2025-03-07T00:55:26.560Z,1741308926.560 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DeepBound = nan m
2025-03-07T00:55:26.567Z,1741308926.567 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2025-03-07T00:55:26.575Z,1741308926.575 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionVertical = nan %
2025-03-07T00:55:26.579Z,1741308926.579 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlPeakThresh = 0.000000 ug/l
2025-03-07T00:55:26.594Z,1741308926.594 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlThreshTriggerActive = 0 bool
2025-03-07T00:55:26.597Z,1741308926.597 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlLowerThresh = nan ug/l
2025-03-07T00:55:26.604Z,1741308926.604 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlUpperThresh = nan ug/l
2025-03-07T00:55:26.620Z,1741308926.620 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesThresh = 5.000000 count
2025-03-07T00:55:26.634Z,1741308926.634 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PlanktivoreLMavgROIPeakThresh = 0.000000 count/s
2025-03-07T00:55:26.654Z,1741308926.654 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.GoDown = 1 bool
2025-03-07T00:55:26.670Z,1741308926.670 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = nan ug/l
2025-03-07T00:55:26.673Z,1741308926.673 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = nan m
2025-03-07T00:55:26.676Z,1741308926.676 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = nan degC
2025-03-07T00:55:26.679Z,1741308926.679 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2025-03-07T00:55:26.683Z,1741308926.683 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2025-03-07T00:55:26.711Z,1741308926.711 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgeChl = nan ug/l
2025-03-07T00:55:26.714Z,1741308926.714 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgeChlDepth = nan m
2025-03-07T00:55:26.717Z,1741308926.717 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgeChlTemperature = nan degC
2025-03-07T00:55:26.720Z,1741308926.720 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgeChlLatitude = nan arcdeg
2025-03-07T00:55:26.772Z,1741308926.772 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgeChlLongitude = nan arcdeg
2025-03-07T00:55:26.775Z,1741308926.775 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = nan umol/l
2025-03-07T00:55:26.786Z,1741308926.786 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = nan m
2025-03-07T00:55:26.795Z,1741308926.795 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2025-03-07T00:55:26.806Z,1741308926.806 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2025-03-07T00:55:26.821Z,1741308926.821 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = nan kg/m3
2025-03-07T00:55:26.827Z,1741308926.827 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = nan m
2025-03-07T00:55:26.839Z,1741308926.839 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2025-03-07T00:55:26.850Z,1741308926.850 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2025-03-07T00:55:26.858Z,1741308926.858 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = nan ppb
2025-03-07T00:55:26.869Z,1741308926.869 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = nan m
2025-03-07T00:55:26.878Z,1741308926.878 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2025-03-07T00:55:26.886Z,1741308926.886 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2025-03-07T00:55:26.891Z,1741308926.891 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2025-03-07T00:55:26.906Z,1741308926.906 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = nan psu
2025-03-07T00:55:26.913Z,1741308926.913 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = nan m
2025-03-07T00:55:26.917Z,1741308926.917 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = nan degC
2025-03-07T00:55:26.924Z,1741308926.924 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2025-03-07T00:55:26.931Z,1741308926.931 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2025-03-07T00:55:26.940Z,1741308926.940 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreLMavgROI = nan count/s
2025-03-07T00:55:26.952Z,1741308926.952 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreLMavgROIDepth = nan m
2025-03-07T00:55:26.955Z,1741308926.955 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreLMavgROITemperature = nan degC
2025-03-07T00:55:26.974Z,1741308926.974 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreLMavgROILatitude = nan arcdeg
2025-03-07T00:55:26.977Z,1741308926.977 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreLMavgROILongitude = nan arcdeg
2025-03-07T00:55:26.980Z,1741308926.980 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgePlanktivoreLMavgROI = nan count/s
2025-03-07T00:55:26.984Z,1741308926.984 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgePlanktivoreLMavgROIDepth = nan m
2025-03-07T00:55:27.017Z,1741308927.017 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgePlanktivoreLMavgROITemperature = nan degC
2025-03-07T00:55:27.020Z,1741308927.020 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgePlanktivoreLMavgROILatitude = nan arcdeg
2025-03-07T00:55:27.024Z,1741308927.024 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.EdgePlanktivoreLMavgROILongitude = nan arcdeg
2025-03-07T00:55:27.041Z,1741308927.041 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreHMavgROI = nan count/s
2025-03-07T00:55:27.052Z,1741308927.052 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreHMavgROIDepth = nan m
2025-03-07T00:55:27.055Z,1741308927.055 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreHMavgROITemperature = nan degC
2025-03-07T00:55:27.078Z,1741308927.078 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreHMavgROILatitude = nan arcdeg
2025-03-07T00:55:27.081Z,1741308927.081 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreHMavgROILongitude = nan arcdeg
2025-03-07T00:55:27.085Z,1741308927.085 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDiatoms = nan count/ml
2025-03-07T00:55:27.088Z,1741308927.088 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDiatomsDepth = nan m
2025-03-07T00:55:27.111Z,1741308927.111 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDiatomsTemperature = nan degC
2025-03-07T00:55:27.114Z,1741308927.114 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDiatomsLatitude = nan arcdeg
2025-03-07T00:55:27.117Z,1741308927.117 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDiatomsLongitude = nan arcdeg
2025-03-07T00:55:27.120Z,1741308927.120 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDinoflagellates = nan count/ml
2025-03-07T00:55:27.139Z,1741308927.139 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDinoflagellatesDepth = nan m
2025-03-07T00:55:27.190Z,1741308927.190 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDinoflagellatesTemperature = nan degC
2025-03-07T00:55:27.206Z,1741308927.206 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDinoflagellatesLatitude = nan arcdeg
2025-03-07T00:55:27.209Z,1741308927.209 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakPlanktivoreDinoflagellatesLongitude = nan arcdeg
2025-03-07T00:55:27.212Z,1741308927.212 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = nan ug/l
2025-03-07T00:55:27.227Z,1741308927.227 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = nan m
2025-03-07T00:55:27.231Z,1741308927.231 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2025-03-07T00:55:27.257Z,1741308927.257 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2025-03-07T00:55:27.261Z,1741308927.261 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2025-03-07T00:55:27.280Z,1741308927.280 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = nan psu
2025-03-07T00:55:27.294Z,1741308927.294 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = nan m
2025-03-07T00:55:27.301Z,1741308927.301 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2025-03-07T00:55:27.305Z,1741308927.305 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2025-03-07T00:55:27.322Z,1741308927.322 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2025-03-07T00:55:27.326Z,1741308927.326 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = nan psu
2025-03-07T00:55:27.341Z,1741308927.341 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = nan m
2025-03-07T00:55:27.344Z,1741308927.344 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2025-03-07T00:55:27.348Z,1741308927.348 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2025-03-07T00:55:27.365Z,1741308927.365 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2025-03-07T00:55:27.368Z,1741308927.368 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.TriggeredOnChl = 0 bool
2025-03-07T00:55:27.371Z,1741308927.371 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.ChlTriggering = nan ug/l
2025-03-07T00:55:27.465Z,1741308927.465 [run_backseat_on_surface:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.517Z,1741308927.517 [run_backseat_on_surface:Science:EdgeDetectChl:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-03-07T00:55:27.587Z,1741308927.587 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2025-03-07T00:55:27.635Z,1741308927.635 [run_backseat_on_surface:Science:PeakDetectNO3:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.673Z,1741308927.673 [run_backseat_on_surface:Science:PeakDetectOil:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.714Z,1741308927.714 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2025-03-07T00:55:27.740Z,1741308927.740 [run_backseat_on_surface:Science:PeakDetectFDOM:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.769Z,1741308927.769 [run_backseat_on_surface:Science:PeakDetectSalinity:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.794Z,1741308927.794 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2025-03-07T00:55:27.847Z,1741308927.847 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.864Z,1741308927.864 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth.
2025-03-07T00:55:27.921Z,1741308927.921 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:27.998Z,1741308927.998 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:28.048Z,1741308928.048 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2025-03-07T00:55:28.093Z,1741308928.093 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2025-03-07T00:55:28.106Z,1741308928.106 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-03-07T00:55:28.106Z,1741308928.106 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-03-07T00:55:28.107Z,1741308928.107 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-03-07T00:55:28.174Z,1741308928.174 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-03-07T00:55:28.187Z,1741308928.187 [MissionManager](INFO): DefineArg run_backseat_on_surface:BackseatDriver.EnableBackseat = 0 bool
2025-03-07T00:55:28.189Z,1741308928.189 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-03-07T00:55:28.206Z,1741308928.206 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-03-07T00:55:28.206Z,1741308928.206 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-03-07T00:55:28.207Z,1741308928.207 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-03-07T00:55:28.487Z,1741308928.487 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-03-07T00:55:28.496Z,1741308928.496 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleAll = 0 bool
2025-03-07T00:55:28.499Z,1741308928.499 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad1 = 0 bool
2025-03-07T00:55:28.530Z,1741308928.530 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad2 = 0 bool
2025-03-07T00:55:28.533Z,1741308928.533 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.SampleLoad3 = 0 bool
2025-03-07T00:55:28.540Z,1741308928.540 [MissionManager](INFO): DefineArg run_backseat_on_surface:PowerOnly.EnabledPowerOnly = 1 bool
2025-03-07T00:55:28.556Z,1741308928.556 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-03-07T00:55:28.563Z,1741308928.563 [run_backseat_on_surface:E.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-07T00:55:28.614Z,1741308928.614 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-07T00:55:28.617Z,1741308928.617 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-07T00:55:28.645Z,1741308928.645 [run_backseat_on_surface:H.Execute](DEBUG): Construct Execute.
2025-03-07T00:55:28.704Z,1741308928.704 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission run_backseat_on_surface {
arguments {
ElapsedSinceMissionStarted = 0 minute
}
# Power the science payload.
insert id="Science" Insert/Science.tl
# Power the Backseat payload.
insert Insert/BackseatDriver.tl
assign in sequence BackseatDriver:EnableBackseat = true
insert Insert/PowerOnly.tl
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P2H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceMissionStarted = elapsed ( Universal:mission_started )
syslog important "run_backseat_on_surface mission has been running for "
+ ElapsedSinceMissionStarted~minute
}
syslog important "Restarting logs and run_backseat_on_surface mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-03-07T00:55:28.716Z,1741308928.716 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.tl
2025-03-07T00:55:28.809Z,1741308928.809 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2025-03-07T00:55:28.809Z,1741308928.809 [run_backseat_on_surface] Running Loop=1
2025-03-07T00:55:28.809Z,1741308928.809 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:A] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:B] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:C] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:D] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:E] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:F] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2025-03-07T00:55:28.810Z,1741308928.810 [run_backseat_on_surface:Science:PeakDetectChl:A] Running Loop=1
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:EdgeDetectChl] Running Loop=1
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:EdgeDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:EdgeDetectChl
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:EdgeDetectChl:A] Running Loop=1
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2025-03-07T00:55:28.811Z,1741308928.811 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:PeakDetectNO3:A] Running Loop=1
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:PeakDetectOil:A] Running Loop=1
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2025-03-07T00:55:28.812Z,1741308928.812 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2025-03-07T00:55:28.813Z,1741308928.813 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2025-03-07T00:55:28.813Z,1741308928.813 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2025-03-07T00:55:28.813Z,1741308928.813 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2025-03-07T00:55:28.813Z,1741308928.813 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2025-03-07T00:55:28.813Z,1741308928.813 [run_backseat_on_surface:Science:PeakDetectFDOM:A] Running Loop=1
2025-03-07T00:55:28.813Z,1741308928.813 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2025-03-07T00:55:28.814Z,1741308928.814 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2025-03-07T00:55:28.814Z,1741308928.814 [run_backseat_on_surface:Science:PeakDetectSalinity:A] Running Loop=1
2025-03-07T00:55:28.814Z,1741308928.814 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2025-03-07T00:55:28.814Z,1741308928.814 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2025-03-07T00:55:28.814Z,1741308928.814 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2025-03-07T00:55:28.814Z,1741308928.814 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2025-03-07T00:55:28.815Z,1741308928.815 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI] Running Loop=1
2025-03-07T00:55:28.815Z,1741308928.815 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI
2025-03-07T00:55:28.815Z,1741308928.815 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI:A] Running Loop=1
2025-03-07T00:55:28.815Z,1741308928.815 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI] Running Loop=1
2025-03-07T00:55:28.815Z,1741308928.815 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI:A] Running Loop=1
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI] Running Loop=1
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI:A] Running Loop=1
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms] Running Loop=1
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms:A] Running Loop=1
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates] Running Loop=1
2025-03-07T00:55:28.816Z,1741308928.816 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates
2025-03-07T00:55:28.817Z,1741308928.817 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates:A] Running Loop=1
2025-03-07T00:55:28.817Z,1741308928.817 [run_backseat_on_surface:Science:ChlThreshTrigger] Running Loop=1
2025-03-07T00:55:28.827Z,1741308928.827 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:ChlThreshTrigger
2025-03-07T00:55:28.828Z,1741308928.828 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2025-03-07T00:55:28.828Z,1741308928.828 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2025-03-07T00:55:28.828Z,1741308928.828 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2025-03-07T00:55:28.828Z,1741308928.828 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::initialize run_backseat_on_surface:BackseatDriver
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:PowerOnly] Running Loop=1
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:PowerOnly](DEBUG): Aggregate::initialize run_backseat_on_surface:PowerOnly
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:PowerOnly:A] Running Loop=1
2025-03-07T00:55:28.829Z,1741308928.829 [run_backseat_on_surface:PowerOnly:B] Running Loop=1
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:PowerOnly:C] Running Loop=1
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:PowerOnly:D] Running Loop=1
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:PowerOnly:E.Wait] Running Loop=1
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:E.GoToSurface] Running Loop=1
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:E.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-07T00:55:28.830Z,1741308928.830 [run_backseat_on_surface:E.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-07T00:55:28.831Z,1741308928.831 [run_backseat_on_surface:E.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-07T00:55:28.831Z,1741308928.831 [run_backseat_on_surface:E.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-07T00:55:28.831Z,1741308928.831 [run_backseat_on_surface:E.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-07T00:55:28.832Z,1741308928.832 [run_backseat_on_surface:E.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-07T00:55:28.832Z,1741308928.832 [run_backseat_on_surface:C] Running Loop=1
2025-03-07T00:55:28.833Z,1741308928.833 [run_backseat_on_surface:PowerOnly] Running Loop=1
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:PowerOnly:D] Stopped
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:PowerOnly:C] Stopped
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:PowerOnly:B] Stopped
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:PowerOnly:A] Stopped
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:C] Stopped
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:BackseatDriver] Running Loop=1
2025-03-07T00:55:28.834Z,1741308928.834 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-03-07T00:55:28.891Z,1741308928.891 [run_backseat_on_surface:Science] Running Loop=1
2025-03-07T00:55:28.892Z,1741308928.892 [run_backseat_on_surface:Science:ChlThreshTrigger] Stopped
2025-03-07T00:55:28.893Z,1741308928.893 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:ChlThreshTrigger
2025-03-07T00:55:28.893Z,1741308928.893 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2025-03-07T00:55:28.893Z,1741308928.893 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates] Stopped
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDinoflagellates:A] Stopped
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms] Stopped
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreDiatoms:A] Stopped
2025-03-07T00:55:28.902Z,1741308928.902 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:PeakDetectPlanktivoreHMavgROI:A] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:EdgeDetectPlanktivoreLMavgROI:A] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:PeakDetectPlanktivoreLMavgROI:A] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2025-03-07T00:55:28.903Z,1741308928.903 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectSalinity:A] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectFDOM:A] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectOil:A] Stopped
2025-03-07T00:55:28.904Z,1741308928.904 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2025-03-07T00:55:28.905Z,1741308928.905 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2025-03-07T00:55:28.905Z,1741308928.905 [run_backseat_on_surface:Science:PeakDetectNO3:A] Stopped
2025-03-07T00:55:28.905Z,1741308928.905 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2025-03-07T00:55:28.913Z,1741308928.913 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2025-03-07T00:55:28.917Z,1741308928.917 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2025-03-07T00:55:28.917Z,1741308928.917 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2025-03-07T00:55:28.917Z,1741308928.917 [run_backseat_on_surface:Science:EdgeDetectChl] Stopped
2025-03-07T00:55:28.917Z,1741308928.917 [run_backseat_on_surface:Science:EdgeDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:EdgeDetectChl
2025-03-07T00:55:28.917Z,1741308928.917 [run_backseat_on_surface:Science:EdgeDetectChl:A] Stopped
2025-03-07T00:55:28.917Z,1741308928.917 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2025-03-07T00:55:28.918Z,1741308928.918 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2025-03-07T00:55:28.918Z,1741308928.918 [run_backseat_on_surface:Science:PeakDetectChl:A] Stopped
2025-03-07T00:55:28.918Z,1741308928.918 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2025-03-07T00:55:28.918Z,1741308928.918 [run_backseat_on_surface:Science:Read_Oil] Stopped
2025-03-07T00:55:28.918Z,1741308928.918 [run_backseat_on_surface:Science:F] Stopped
2025-03-07T00:55:28.918Z,1741308928.918 [run_backseat_on_surface:Science:E] Running Loop=1
2025-03-07T00:55:28.919Z,1741308928.919 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2025-03-07T00:55:28.919Z,1741308928.919 [run_backseat_on_surface:Science:D] Stopped
2025-03-07T00:55:28.919Z,1741308928.919 [run_backseat_on_surface:Science:C] Running Loop=1
2025-03-07T00:55:28.920Z,1741308928.920 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2025-03-07T00:55:28.920Z,1741308928.920 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2025-03-07T00:55:28.920Z,1741308928.920 [run_backseat_on_surface:Science:B] Running Loop=1
2025-03-07T00:55:28.923Z,1741308928.923 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2025-03-07T00:55:28.924Z,1741308928.924 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2025-03-07T00:55:28.924Z,1741308928.924 [run_backseat_on_surface:Science:A] Stopped
2025-03-07T00:55:29.207Z,1741308929.207 [run_backseat_on_surface:CheckIn] Running Loop=1
2025-03-07T00:55:29.207Z,1741308929.207 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2025-03-07T00:55:29.207Z,1741308929.207 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2025-03-07T00:55:29.423Z,1741308929.423 [WetLabsBB2FL](INFO): Powering up
2025-03-07T00:55:29.605Z,1741308929.605 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-07T00:55:39.341Z,1741308939.341 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-03-07T00:55:39.342Z,1741308939.342 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-03-07T00:55:39.342Z,1741308939.342 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-03-07T00:55:53.947Z,1741308953.947 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000000 psu
2025-03-07T00:55:53.949Z,1741308953.949 [CTD_Seabird](INFO): some bad data, not updating bins
2025-03-07T00:55:57.713Z,1741308957.713 [BackseatComponent](INFO): Powering up
2025-03-07T00:55:57.714Z,1741308957.714 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-03-07T00:56:01.907Z,1741308961.907 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-03-07T00:56:01.985Z,1741308961.985 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-03-07T00:56:27.088Z,1741308987.088 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000000 psu
2025-03-07T00:56:27.089Z,1741308987.089 [CTD_Seabird](INFO): some bad data, not updating bins
2025-03-07T00:56:27.397Z,1741308987.397 [CBIT](INFO): Clearing failed state for component DropWeight
2025-03-07T00:56:27.397Z,1741308987.397 [DropWeight] No Fault, FailCount= 1
2025-03-07T00:57:30.339Z,1741309050.339 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000000 psu
2025-03-07T00:57:30.340Z,1741309050.340 [CTD_Seabird](INFO): some bad data, not updating bins
2025-03-07T00:58:42.647Z,1741309122.647 [BackseatComponent](IMPORTANT): ikshana: running backseat application.
2025-03-07T00:58:45.351Z,1741309125.351 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2025-03-07T00:58:45.351Z,1741309125.351 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2025-03-07T01:00:29.253Z,1741309229.253 [run_backseat_on_surface:CheckIn:Read_GPS](INFO): Timed out from 2025-03-07T00:55:29.2Z
2025-03-07T01:00:29.253Z,1741309229.253 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2025-03-07T01:00:29.253Z,1741309229.253 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2025-03-07T01:00:29.658Z,1741309229.658 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-07T01:00:32.855Z,1741309232.855 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-07T01:00:36.977Z,1741309236.977 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20250307T002245/Courier0000.lzma
2025-03-07T01:00:37.979Z,1741309237.979 [DataOverHttps](INFO): Moved sent file to Logs/20250307T002245/Courier0000.lzma.bak
2025-03-07T01:00:37.979Z,1741309237.979 [DataOverHttps](INFO): SBD MOMSN=24136429
2025-03-07T01:00:55.345Z,1741309255.345 [DataOverHttps](INFO): Sending 293 bytes from file Logs/20250307T002933/Courier0000.lzma
2025-03-07T01:00:56.347Z,1741309256.347 [DataOverHttps](INFO): Moved sent file to Logs/20250307T002933/Courier0000.lzma.bak
2025-03-07T01:00:56.347Z,1741309256.347 [DataOverHttps](INFO): SBD MOMSN=24136439
2025-03-07T01:01:03.563Z,1741309263.563 [NAL9602](INFO): Not Powering down - fast GPS
2025-03-07T01:01:12.509Z,1741309272.509 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20250307T002933/Courier0004.lzma
2025-03-07T01:01:13.511Z,1741309273.511 [DataOverHttps](INFO): Moved sent file to Logs/20250307T002933/Courier0004.lzma.bak
2025-03-07T01:01:13.511Z,1741309273.511 [DataOverHttps](INFO): SBD MOMSN=24136444
2025-03-07T01:01:29.773Z,1741309289.773 [DataOverHttps](INFO): Sending 293 bytes from file Logs/20250307T003332/Courier0000.lzma
2025-03-07T01:01:30.775Z,1741309290.775 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003332/Courier0000.lzma.bak
2025-03-07T01:01:30.775Z,1741309290.775 [DataOverHttps](INFO): SBD MOMSN=24136448
2025-03-07T01:01:47.070Z,1741309307.070 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20250307T003332/Courier0004.lzma
2025-03-07T01:01:48.071Z,1741309308.071 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003332/Courier0004.lzma.bak
2025-03-07T01:01:48.071Z,1741309308.071 [DataOverHttps](INFO): SBD MOMSN=24136456
2025-03-07T01:02:04.181Z,1741309324.181 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20250307T003741/Courier0000.lzma
2025-03-07T01:02:05.183Z,1741309325.183 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003741/Courier0000.lzma.bak
2025-03-07T01:02:05.183Z,1741309325.183 [DataOverHttps](INFO): SBD MOMSN=24136462
2025-03-07T01:02:24.102Z,1741309344.102 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250307T003741/Courier0004.lzma
2025-03-07T01:02:25.099Z,1741309345.099 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003741/Courier0004.lzma.bak
2025-03-07T01:02:25.099Z,1741309345.099 [DataOverHttps](INFO): SBD MOMSN=24136468
2025-03-07T01:02:44.733Z,1741309364.733 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20250307T004115/Courier0000.lzma
2025-03-07T01:02:45.735Z,1741309365.735 [DataOverHttps](INFO): Moved sent file to Logs/20250307T004115/Courier0000.lzma.bak
2025-03-07T01:02:45.735Z,1741309365.735 [DataOverHttps](INFO): SBD MOMSN=24136471
2025-03-07T01:03:01.650Z,1741309381.650 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20250307T004115/Courier0004.lzma
2025-03-07T01:03:02.651Z,1741309382.651 [DataOverHttps](INFO): Moved sent file to Logs/20250307T004115/Courier0004.lzma.bak
2025-03-07T01:03:02.651Z,1741309382.651 [DataOverHttps](INFO): SBD MOMSN=24136476
2025-03-07T01:03:13.223Z,1741309393.223 [BackseatComponent](IMPORTANT): ikshana: running backseat application.
2025-03-07T01:03:18.702Z,1741309398.702 [DataOverHttps](INFO): Sending 482 bytes from file Logs/20250307T000704/Express0005.lzma
2025-03-07T01:03:19.703Z,1741309399.703 [DataOverHttps](INFO): Moved sent file to Logs/20250307T000704/Express0005.lzma.bak
2025-03-07T01:03:19.703Z,1741309399.703 [DataOverHttps](INFO): SBD MOMSN=24136482
2025-03-07T01:03:38.670Z,1741309418.670 [DataOverHttps](INFO): Sending 856 bytes from file Logs/20250307T001244/Express0001.lzma
2025-03-07T01:03:39.671Z,1741309419.671 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001244/Express0001.lzma.bak
2025-03-07T01:03:39.671Z,1741309419.671 [DataOverHttps](INFO): SBD MOMSN=24136493
2025-03-07T01:03:56.849Z,1741309436.849 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250307T001244/Express0005.lzma
2025-03-07T01:03:57.851Z,1741309437.851 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001244/Express0005.lzma.bak
2025-03-07T01:03:57.851Z,1741309437.851 [DataOverHttps](INFO): SBD MOMSN=24136518
2025-03-07T01:04:13.922Z,1741309453.922 [DataOverHttps](INFO): Sending 656 bytes from file Logs/20250307T001612/Express0001.lzma
2025-03-07T01:04:14.923Z,1741309454.923 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001612/Express0001.lzma.bak
2025-03-07T01:04:14.923Z,1741309454.923 [DataOverHttps](INFO): SBD MOMSN=24136523
2025-03-07T01:04:31.107Z,1741309471.107 [DataOverHttps](INFO): Sending 1131 bytes from file Logs/20250307T001746/Express0001.lzma
2025-03-07T01:04:32.107Z,1741309472.107 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001746/Express0001.lzma.bak
2025-03-07T01:04:32.107Z,1741309472.107 [DataOverHttps](INFO): SBD MOMSN=24136538
2025-03-07T01:04:48.185Z,1741309488.185 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20250307T001746/Express0005.lzma
2025-03-07T01:04:49.187Z,1741309489.187 [DataOverHttps](INFO): Moved sent file to Logs/20250307T001746/Express0005.lzma.bak
2025-03-07T01:04:49.187Z,1741309489.187 [DataOverHttps](INFO): SBD MOMSN=24136569
2025-03-07T01:05:05.240Z,1741309505.240 [DataOverHttps](INFO): Sending 1424 bytes from file Logs/20250307T002245/Express0001.lzma
2025-03-07T01:05:06.239Z,1741309506.239 [DataOverHttps](INFO): Moved sent file to Logs/20250307T002245/Express0001.lzma.bak
2025-03-07T01:05:06.239Z,1741309506.239 [DataOverHttps](INFO): SBD MOMSN=24136573
2025-03-07T01:05:22.252Z,1741309522.252 [DataOverHttps](INFO): Sending 1375 bytes from file Logs/20250307T002933/Express0001.lzma
2025-03-07T01:05:23.251Z,1741309523.251 [DataOverHttps](INFO): Moved sent file to Logs/20250307T002933/Express0001.lzma.bak
2025-03-07T01:05:23.251Z,1741309523.251 [DataOverHttps](INFO): SBD MOMSN=24136615
2025-03-07T01:05:39.388Z,1741309539.388 [DataOverHttps](INFO): Sending 454 bytes from file Logs/20250307T002933/Express0005.lzma
2025-03-07T01:05:40.259Z,1741309540.259 [DataOverHttps](INFO): Moved sent file to Logs/20250307T002933/Express0005.lzma.bak
2025-03-07T01:05:40.259Z,1741309540.259 [DataOverHttps](INFO): SBD MOMSN=24136656
2025-03-07T01:05:55.376Z,1741309555.376 [DataOverHttps](INFO): Sending 1445 bytes from file Logs/20250307T003332/Express0001.lzma
2025-03-07T01:05:56.375Z,1741309556.375 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003332/Express0001.lzma.bak
2025-03-07T01:05:56.375Z,1741309556.375 [DataOverHttps](INFO): SBD MOMSN=24136703
2025-03-07T01:06:12.673Z,1741309572.673 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20250307T003332/Express0005.lzma
2025-03-07T01:06:13.675Z,1741309573.675 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003332/Express0005.lzma.bak
2025-03-07T01:06:13.675Z,1741309573.675 [DataOverHttps](INFO): SBD MOMSN=24136738
2025-03-07T01:06:29.789Z,1741309589.789 [DataOverHttps](INFO): Sending 1898 bytes from file Logs/20250307T003741/Express0001.lzma
2025-03-07T01:06:30.787Z,1741309590.787 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003741/Express0001.lzma.bak
2025-03-07T01:06:30.787Z,1741309590.787 [DataOverHttps](INFO): SBD MOMSN=24136747
2025-03-07T01:06:46.762Z,1741309606.762 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20250307T003741/Express0005.lzma
2025-03-07T01:06:47.763Z,1741309607.763 [DataOverHttps](INFO): Moved sent file to Logs/20250307T003741/Express0005.lzma.bak
2025-03-07T01:06:47.763Z,1741309607.763 [DataOverHttps](INFO): SBD MOMSN=24136832
2025-03-07T01:07:03.752Z,1741309623.752 [DataOverHttps](INFO): Sending 1249 bytes from file Logs/20250307T004115/Express0001.lzma
2025-03-07T01:07:04.751Z,1741309624.751 [DataOverHttps](INFO): Moved sent file to Logs/20250307T004115/Express0001.lzma.bak
2025-03-07T01:07:04.751Z,1741309624.751 [DataOverHttps](INFO): SBD MOMSN=24136867
2025-03-07T01:07:20.709Z,1741309640.709 [DataOverHttps](INFO): Sending 1611 bytes from file Logs/20250307T004115/Express0005.lzma
2025-03-07T01:07:21.707Z,1741309641.707 [DataOverHttps](INFO): Moved sent file to Logs/20250307T004115/Express0005.lzma.bak
2025-03-07T01:07:21.707Z,1741309641.707 [DataOverHttps](INFO): SBD MOMSN=24136908
2025-03-07T01:07:22.991Z,1741309642.991 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2025-03-07T01:07:22.992Z,1741309642.992 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2025-03-07T01:07:22.992Z,1741309642.992 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-07T01:08:27.066Z,1741309707.066 [CommandExec](IMPORTANT): got command restart system
2025-03-07T01:08:29.181Z,1741309709.181 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-03-07T01:08:29.182Z,1741309709.182 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:29.182Z,1741309709.182 [CommandExec](INFO): Uninitializing the command executive.
2025-03-07T01:08:29.182Z,1741309709.182 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-07T01:08:29.182Z,1741309709.182 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:29.287Z,1741309709.287 [MissionManager](INFO): MissionManager is completed.
2025-03-07T01:08:29.287Z,1741309709.287 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface] Stopped
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface:Science] Stopped
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface:Science:B] Stopped
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface:Science:C] Stopped
2025-03-07T01:08:29.287Z,1741309709.287 [run_backseat_on_surface:Science:E] Stopped
2025-03-07T01:08:29.288Z,1741309709.288 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2025-03-07T01:08:29.288Z,1741309709.288 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2025-03-07T01:08:29.288Z,1741309709.288 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2025-03-07T01:08:29.288Z,1741309709.288 [run_backseat_on_surface:BackseatDriver] Stopped
2025-03-07T01:08:29.288Z,1741309709.288 [run_backseat_on_surface:BackseatDriver](DEBUG): Aggregate::uninitialize run_backseat_on_surface:BackseatDriver
2025-03-07T01:08:29.288Z,1741309709.288 [run_backseat_on_surface:BackseatDriver:A.BackseatDriver] Stopped
2025-03-07T01:08:29.289Z,1741309709.289 [run_backseat_on_surface:PowerOnly] Stopped
2025-03-07T01:08:29.289Z,1741309709.289 [run_backseat_on_surface:PowerOnly](DEBUG): Aggregate::uninitialize run_backseat_on_surface:PowerOnly
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:PowerOnly:E.Wait] Stopped
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:E.GoToSurface] Stopped
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:CheckIn] Stopped
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2025-03-07T01:08:29.293Z,1741309709.293 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2025-03-07T01:08:29.294Z,1741309709.294 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T01:08:29.341Z,1741309709.341 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-03-07T01:08:29.341Z,1741309709.341 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-07T01:08:29.342Z,1741309709.342 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:29.342Z,1741309709.342 [NavChartDb](INFO): Join timeout helper Thread ID is 2193
2025-03-07T01:08:29.617Z,1741309709.617 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:29.618Z,1741309709.618 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:29.621Z,1741309709.621 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-07T01:08:29.621Z,1741309709.621 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:29.622Z,1741309709.622 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2194
2025-03-07T01:08:29.641Z,1741309709.641 [MissionManager](IMPORTANT): Started mission Default
2025-03-07T01:08:29.641Z,1741309709.641 [Default] Running Loop=1
2025-03-07T01:08:29.641Z,1741309709.641 [Default](DEBUG): Aggregate::initialize Default
2025-03-07T01:08:29.641Z,1741309709.641 [Default:B.GoToSurface] Running Loop=1
2025-03-07T01:08:29.642Z,1741309709.642 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-07T01:08:29.642Z,1741309709.642 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-07T01:08:29.642Z,1741309709.642 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-07T01:08:29.642Z,1741309709.642 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-07T01:08:29.643Z,1741309709.643 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-07T01:08:29.643Z,1741309709.643 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-07T01:08:29.643Z,1741309709.643 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-07T01:08:29.644Z,1741309709.644 [Default:A.Wait] Running Loop=1
2025-03-07T01:08:29.644Z,1741309709.644 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-07T01:08:29.673Z,1741309709.673 [WetLabsBB2FL](INFO): Powering down
2025-03-07T01:08:29.674Z,1741309709.674 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:29.674Z,1741309709.674 [WetLabsBB2FL](INFO): Powering down
2025-03-07T01:08:29.675Z,1741309709.675 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:29.677Z,1741309709.677 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-07T01:08:29.677Z,1741309709.677 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:29.678Z,1741309709.678 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2195
2025-03-07T01:08:30.110Z,1741309710.110 [CTD_Seabird](INFO): Powering down
2025-03-07T01:08:30.237Z,1741309710.237 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:30.238Z,1741309710.238 [CTD_Seabird](INFO): Powering down
2025-03-07T01:08:30.249Z,1741309710.249 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:30.257Z,1741309710.257 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-07T01:08:30.257Z,1741309710.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:30.257Z,1741309710.257 [Radio_Surface](INFO): Join timeout helper Thread ID is 2196
2025-03-07T01:08:30.553Z,1741309710.553 [Radio_Surface](INFO): Powering down
2025-03-07T01:08:30.554Z,1741309710.554 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:30.554Z,1741309710.554 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:30.565Z,1741309710.565 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-07T01:08:30.565Z,1741309710.565 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:30.565Z,1741309710.565 [Onboard](INFO): Join timeout helper Thread ID is 2197
2025-03-07T01:08:30.837Z,1741309710.837 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-07T01:08:30.970Z,1741309710.970 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-03-07T01:08:30.971Z,1741309710.971 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-03-07T01:08:32.625Z,1741309712.625 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:32.626Z,1741309712.626 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:32.646Z,1741309712.646 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-07T01:08:32.646Z,1741309712.646 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:32.646Z,1741309712.646 [DataOverHttps](INFO): Join timeout helper Thread ID is 2198
2025-03-07T01:08:32.845Z,1741309712.845 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:32.845Z,1741309712.845 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:32.854Z,1741309712.854 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-03-07T01:08:32.854Z,1741309712.854 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:32.855Z,1741309712.855 [BackseatComponent](INFO): Join timeout helper Thread ID is 2199
2025-03-07T01:08:33.009Z,1741309713.009 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:33.010Z,1741309713.010 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.026Z,1741309713.026 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-07T01:08:33.026Z,1741309713.026 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.026Z,1741309713.026 [logger](INFO): Join timeout helper Thread ID is 2200
2025-03-07T01:08:33.053Z,1741309713.053 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:33.054Z,1741309713.054 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.066Z,1741309713.066 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-07T01:08:33.066Z,1741309713.066 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.066Z,1741309713.066 [CommandLine](INFO): Join timeout helper Thread ID is 2201
2025-03-07T01:08:33.153Z,1741309713.153 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:33.153Z,1741309713.153 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.166Z,1741309713.166 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-07T01:08:33.166Z,1741309713.166 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.166Z,1741309713.166 [CommandExec](INFO): Join timeout helper Thread ID is 2202
2025-03-07T01:08:33.167Z,1741309713.167 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-07T01:08:33.167Z,1741309713.167 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.168Z,1741309713.168 [controlThread](INFO): Join timeout helper Thread ID is 2203
2025-03-07T01:08:33.245Z,1741309713.245 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-07T01:08:33.245Z,1741309713.245 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-07T01:08:33.246Z,1741309713.246 [AHRS_M2](INFO): Powering down
2025-03-07T01:08:33.318Z,1741309713.318 [MultiRay](INFO): Powering down.
2025-03-07T01:08:33.319Z,1741309713.319 [NAL9602](INFO): Powering down
2025-03-07T01:08:33.320Z,1741309713.320 [Waterlinked](INFO): Powering down
2025-03-07T01:08:33.322Z,1741309713.322 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-07T01:08:33.323Z,1741309713.323 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-07T01:08:33.324Z,1741309713.324 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-07T01:08:33.324Z,1741309713.324 [MissionManager](INFO): Uninitializing Mission Default
2025-03-07T01:08:33.324Z,1741309713.324 [Default] Stopped
2025-03-07T01:08:33.324Z,1741309713.324 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-07T01:08:33.324Z,1741309713.324 [Default:A.Wait] Stopped
2025-03-07T01:08:33.324Z,1741309713.324 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-07T01:08:33.325Z,1741309713.325 [Default:B.GoToSurface] Stopped
2025-03-07T01:08:33.325Z,1741309713.325 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-07T01:08:33.328Z,1741309713.328 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-07T01:08:33.328Z,1741309713.328 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-07T01:08:33.329Z,1741309713.329 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-07T01:08:33.329Z,1741309713.329 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-07T01:08:33.329Z,1741309713.329 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-07T01:08:33.330Z,1741309713.330 [BuoyancyServo](INFO): Powering down
2025-03-07T01:08:33.341Z,1741309713.341 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-07T01:08:33.341Z,1741309713.341 [ElevatorServo](INFO): Powering down
2025-03-07T01:08:33.342Z,1741309713.342 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-07T01:08:33.342Z,1741309713.342 [MassServo](INFO): Powering down
2025-03-07T01:08:33.343Z,1741309713.343 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-07T01:08:33.343Z,1741309713.343 [RudderServo](INFO): Powering down
2025-03-07T01:08:33.344Z,1741309713.344 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-07T01:08:33.344Z,1741309713.344 [ThrusterHE](INFO): Powering down
2025-03-07T01:08:33.353Z,1741309713.353 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-07T01:08:33.353Z,1741309713.353 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-07T01:08:33.354Z,1741309713.354 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-07T01:08:33.354Z,1741309713.354 [CBIT](DEBUG): Powering off loads.
2025-03-07T01:08:33.365Z,1741309713.365 [CBIT](DEBUG): Disabling WDT.
2025-03-07T01:08:33.377Z,1741309713.377 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-07T01:08:33.377Z,1741309713.377 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-07T01:08:33.378Z,1741309713.378 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.416Z,1741309713.416 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.420Z,1741309713.420 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.427Z,1741309713.427 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.478Z,1741309713.478 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.516Z,1741309713.516 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-07T01:08:33.516Z,1741309713.516 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-07T01:08:33.532Z,1741309713.532 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.537Z,1741309713.537 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.602Z,1741309713.602 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-07T01:08:33.651Z,1741309713.651 [logger ThreadHandler](INFO): Thread cancelled.