2025-03-01T00:24:40.869Z,1740788680.869 [Supervisor](DEBUG): Initializing supervisor.
2025-03-01T00:24:40.873Z,1740788680.873 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-01T00:24:40.873Z,1740788680.873 [SyncHandler](INFO): Protected caller Thread ID is 843
2025-03-01T00:24:40.874Z,1740788680.874 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-01T00:24:40.875Z,1740788680.875 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-01T00:24:40.875Z,1740788680.875 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 844
2025-03-01T00:24:40.879Z,1740788680.879 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-01T00:24:40.899Z,1740788680.899 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-01T00:24:40.900Z,1740788680.900 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-01T00:24:40.900Z,1740788680.900 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 845
2025-03-01T00:24:40.904Z,1740788680.904 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-01T00:24:40.907Z,1740788680.907 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-01T00:24:40.908Z,1740788680.908 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 846
2025-03-01T00:24:40.910Z,1740788680.910 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-01T00:24:40.911Z,1740788680.911 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-01T00:24:40.911Z,1740788680.911 [logger ThreadHandler](INFO): Protected caller Thread ID is 847
2025-03-01T00:24:40.915Z,1740788680.915 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-01T00:24:40.915Z,1740788680.915 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-01T00:24:40.917Z,1740788680.917 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-01T00:24:41.148Z,1740788681.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-01T00:24:41.148Z,1740788681.148 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-01T00:24:41.270Z,1740788681.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-01T00:24:41.271Z,1740788681.271 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-01T00:24:41.379Z,1740788681.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-01T00:24:41.380Z,1740788681.380 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-01T00:24:41.966Z,1740788681.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-01T00:24:41.966Z,1740788681.966 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-01T00:24:42.334Z,1740788682.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-01T00:24:42.335Z,1740788682.335 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-01T00:24:42.704Z,1740788682.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-01T00:24:42.705Z,1740788682.705 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-01T00:24:42.908Z,1740788682.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-01T00:24:42.908Z,1740788682.908 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-01T00:24:43.000Z,1740788683.000 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-01T00:24:43.095Z,1740788683.095 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-01T00:24:43.538Z,1740788683.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-01T00:24:43.538Z,1740788683.538 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-01T00:24:43.845Z,1740788683.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-01T00:24:43.846Z,1740788683.846 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-01T00:24:44.437Z,1740788684.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-01T00:24:44.437Z,1740788684.437 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-01T00:24:44.682Z,1740788684.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-01T00:24:44.683Z,1740788684.683 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-01T00:24:44.967Z,1740788684.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-01T00:24:44.968Z,1740788684.968 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-01T00:24:45.120Z,1740788685.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-01T00:24:45.120Z,1740788685.120 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-01T00:24:46.024Z,1740788686.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-01T00:24:46.026Z,1740788686.026 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-03-01T00:24:46.027Z,1740788686.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-03-01T00:24:46.284Z,1740788686.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-03-01T00:24:46.524Z,1740788686.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-03-01T00:24:46.930Z,1740788686.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-01T00:24:46.931Z,1740788686.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-03-01T00:24:47.068Z,1740788687.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-03-01T00:24:47.181Z,1740788687.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-03-01T00:24:47.287Z,1740788687.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-03-01T00:24:47.408Z,1740788687.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-03-01T00:24:47.517Z,1740788687.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-03-01T00:24:47.616Z,1740788687.616 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-03-01T00:24:47.719Z,1740788687.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-03-01T00:24:47.903Z,1740788687.903 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-03-01T00:24:47.922Z,1740788687.922 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-01T00:24:47.973Z,1740788687.973 [DepthRateCalculator] Loaded
2025-03-01T00:24:47.974Z,1740788687.974 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-01T00:24:47.979Z,1740788687.979 [PitchRateCalculator] Loaded
2025-03-01T00:24:47.979Z,1740788687.979 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-01T00:24:47.989Z,1740788687.989 [SpeedCalculator] Loaded
2025-03-01T00:24:47.989Z,1740788687.989 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-01T00:24:47.994Z,1740788687.994 [YawRateCalculator] Loaded
2025-03-01T00:24:47.994Z,1740788687.994 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-01T00:24:48.015Z,1740788688.015 [ElevatorOffsetCalculator] Loaded
2025-03-01T00:24:48.015Z,1740788688.015 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-01T00:24:48.016Z,1740788688.016 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-01T00:24:48.016Z,1740788688.016 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-01T00:24:48.042Z,1740788688.042 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-01T00:24:48.043Z,1740788688.043 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-01T00:24:48.053Z,1740788688.053 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-01T00:24:48.053Z,1740788688.053 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-01T00:24:48.504Z,1740788688.504 [AHRS_M2] Loaded
2025-03-01T00:24:48.504Z,1740788688.504 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-01T00:24:48.542Z,1740788688.542 [BackseatComponent] Loaded
2025-03-01T00:24:48.542Z,1740788688.542 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-01T00:24:48.543Z,1740788688.543 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2025-03-01T00:24:48.544Z,1740788688.544 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 930
2025-03-01T00:24:48.546Z,1740788688.546 [LcmUniversalReporter] Loaded
2025-03-01T00:24:48.547Z,1740788688.547 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-01T00:24:49.339Z,1740788689.339 [BPC1] Loaded
2025-03-01T00:24:49.339Z,1740788689.339 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-01T00:24:49.422Z,1740788689.422 [DATMMP] Loaded
2025-03-01T00:24:49.422Z,1740788689.422 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2025-03-01T00:24:49.423Z,1740788689.423 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408A04E0
2025-03-01T00:24:49.423Z,1740788689.423 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 931
2025-03-01T00:24:49.501Z,1740788689.501 [DataOverHttps] Loaded
2025-03-01T00:24:49.502Z,1740788689.502 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-01T00:24:49.503Z,1740788689.503 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2025-03-01T00:24:49.503Z,1740788689.503 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 932
2025-03-01T00:24:49.526Z,1740788689.526 [Depth_Keller] Loaded
2025-03-01T00:24:49.526Z,1740788689.526 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-01T00:24:49.531Z,1740788689.531 [DropWeight] Loaded
2025-03-01T00:24:49.531Z,1740788689.531 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-01T00:24:49.594Z,1740788689.594 [NAL9602] Loaded
2025-03-01T00:24:49.594Z,1740788689.594 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-01T00:24:49.623Z,1740788689.623 [Onboard] Loaded
2025-03-01T00:24:49.623Z,1740788689.623 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-01T00:24:49.624Z,1740788689.624 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2025-03-01T00:24:49.625Z,1740788689.625 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 933
2025-03-01T00:24:49.638Z,1740788689.638 [Power24vConverter] Loaded
2025-03-01T00:24:49.639Z,1740788689.639 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-01T00:24:49.655Z,1740788689.655 [Radio_Surface] Loaded
2025-03-01T00:24:49.655Z,1740788689.655 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-01T00:24:49.656Z,1740788689.656 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2025-03-01T00:24:49.656Z,1740788689.656 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 934
2025-03-01T00:24:49.671Z,1740788689.671 [Sonardyne_Nano] Loaded
2025-03-01T00:24:49.671Z,1740788689.671 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-01T00:24:49.695Z,1740788689.695 [Waterlinked] Loaded
2025-03-01T00:24:49.695Z,1740788689.695 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-03-01T00:24:49.695Z,1740788689.695 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-01T00:24:49.696Z,1740788689.696 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-01T00:24:49.803Z,1740788689.803 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-01T00:24:49.804Z,1740788689.804 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-01T00:24:49.844Z,1740788689.844 [DeadReckonUsingSpeedCalculator] Loaded
2025-03-01T00:24:49.845Z,1740788689.845 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2025-03-01T00:24:49.858Z,1740788689.858 [NavChart] Loaded
2025-03-01T00:24:49.858Z,1740788689.858 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-01T00:24:49.863Z,1740788689.863 [UniversalFixResidualReporter] Loaded
2025-03-01T00:24:49.864Z,1740788689.864 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-01T00:24:49.864Z,1740788689.864 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-01T00:24:49.865Z,1740788689.865 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-01T00:24:50.004Z,1740788690.004 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-01T00:24:50.018Z,1740788690.018 [SBIT] Loaded
2025-03-01T00:24:50.018Z,1740788690.018 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-01T00:24:50.021Z,1740788690.021 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-01T00:24:50.033Z,1740788690.033 [IBIT] Loaded
2025-03-01T00:24:50.033Z,1740788690.033 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-01T00:24:50.039Z,1740788690.039 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-01T00:24:50.139Z,1740788690.139 [CBIT] Loaded
2025-03-01T00:24:50.139Z,1740788690.139 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-01T00:24:50.140Z,1740788690.140 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-01T00:24:50.140Z,1740788690.140 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-01T00:24:50.274Z,1740788690.274 [CANONSampler] Loaded
2025-03-01T00:24:50.274Z,1740788690.274 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-03-01T00:24:50.337Z,1740788690.337 [CTD_Seabird] Loaded
2025-03-01T00:24:50.337Z,1740788690.337 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-01T00:24:50.338Z,1740788690.338 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A384E0
2025-03-01T00:24:50.339Z,1740788690.339 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 935
2025-03-01T00:24:50.360Z,1740788690.360 [PAR_Licor] Loaded
2025-03-01T00:24:50.360Z,1740788690.360 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-01T00:24:50.405Z,1740788690.405 [WetLabsBB2FL] Loaded
2025-03-01T00:24:50.405Z,1740788690.405 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-01T00:24:50.406Z,1740788690.406 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A684E0
2025-03-01T00:24:50.406Z,1740788690.406 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 936
2025-03-01T00:24:50.407Z,1740788690.407 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-01T00:24:50.408Z,1740788690.408 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-01T00:24:50.540Z,1740788690.540 [BuoyancyServo] Loaded
2025-03-01T00:24:50.540Z,1740788690.540 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-01T00:24:50.564Z,1740788690.564 [ElevatorServo] Loaded
2025-03-01T00:24:50.564Z,1740788690.564 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-01T00:24:50.586Z,1740788690.586 [MassServo] Loaded
2025-03-01T00:24:50.586Z,1740788690.586 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-01T00:24:50.609Z,1740788690.609 [RudderServo] Loaded
2025-03-01T00:24:50.609Z,1740788690.609 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-01T00:24:50.627Z,1740788690.627 [ThrusterHE] Loaded
2025-03-01T00:24:50.627Z,1740788690.627 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-01T00:24:50.627Z,1740788690.627 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-01T00:24:50.628Z,1740788690.628 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-01T00:24:50.679Z,1740788690.679 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-01T00:24:50.728Z,1740788690.728 [VerticalControl] Loaded
2025-03-01T00:24:50.728Z,1740788690.728 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-01T00:24:50.731Z,1740788690.731 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-01T00:24:50.774Z,1740788690.774 [HorizontalControl] Loaded
2025-03-01T00:24:50.774Z,1740788690.774 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-01T00:24:50.777Z,1740788690.777 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-01T00:24:50.778Z,1740788690.778 [SpeedControl] Loaded
2025-03-01T00:24:50.779Z,1740788690.779 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-01T00:24:50.781Z,1740788690.781 [LoopControl](DEBUG): Construct LoopControl.
2025-03-01T00:24:50.782Z,1740788690.782 [LoopControl] Loaded
2025-03-01T00:24:50.782Z,1740788690.782 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-01T00:24:50.783Z,1740788690.783 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-01T00:24:50.783Z,1740788690.783 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-01T00:24:50.892Z,1740788690.892 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-01T00:24:50.892Z,1740788690.892 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-01T00:24:51.062Z,1740788691.062 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-01T00:24:51.062Z,1740788691.062 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-01T00:24:51.783Z,1740788691.783 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-01T00:24:51.783Z,1740788691.783 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-01T00:24:51.853Z,1740788691.853 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-01T00:24:51.859Z,1740788691.859 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-01T00:24:51.862Z,1740788691.862 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-01T00:24:51.873Z,1740788691.873 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-01T00:24:51.874Z,1740788691.874 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2025-03-01T00:24:51.875Z,1740788691.875 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 937
2025-03-01T00:24:51.879Z,1740788691.879 [Supervisor](INFO): Main Thread ID is 842
2025-03-01T00:24:51.879Z,1740788691.879 [Supervisor](DEBUG): Running supervisor.
2025-03-01T00:24:51.880Z,1740788691.880 [CommandExec ThreadHandler](INFO): Handler Thread ID is 938
2025-03-01T00:24:51.880Z,1740788691.880 [CommandExec](INFO): Initializing the command executive.
2025-03-01T00:24:51.882Z,1740788691.882 [CommandLine ThreadHandler](INFO): Handler Thread ID is 939
2025-03-01T00:24:51.885Z,1740788691.885 [controlThread ThreadHandler](INFO): Handler Thread ID is 940
2025-03-01T00:24:51.886Z,1740788691.886 [controlThread](DEBUG): Initializing ControlThread
2025-03-01T00:24:51.886Z,1740788691.886 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-01T00:24:51.887Z,1740788691.887 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-01T00:24:51.887Z,1740788691.887 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-01T00:24:51.887Z,1740788691.887 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-01T00:24:51.888Z,1740788691.888 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-01T00:24:51.892Z,1740788691.892 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2025-03-01T00:24:51.893Z,1740788691.893 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-01T00:24:51.893Z,1740788691.893 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-01T00:24:51.893Z,1740788691.893 [SBIT](INFO): Initialize SBIT Component.
2025-03-01T00:24:51.894Z,1740788691.894 [SBIT](IMPORTANT): git: 2024-09-17
2025-03-01T00:24:51.894Z,1740788691.894 [SBIT](INFO): git hash: 40e9ce87bff78b617219447905233bfcf6062b37
2025-03-01T00:24:51.894Z,1740788691.894 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-01T00:24:51.895Z,1740788691.895 [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-01T00:24:51.896Z,1740788691.896 [SBIT](INFO): Beginning SBIT in 97.000000 seconds.
2025-03-01T00:24:51.897Z,1740788691.897 [IBIT](INFO): Initialize IBIT Component.
2025-03-01T00:24:51.898Z,1740788691.898 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-01T00:24:51.899Z,1740788691.899 [logger ThreadHandler](INFO): Handler Thread ID is 941
2025-03-01T00:24:51.909Z,1740788691.909 [CBIT](DEBUG): Initialized mux pins.
2025-03-01T00:24:51.910Z,1740788691.910 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-01T00:24:51.918Z,1740788691.918 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 942
2025-03-01T00:24:51.930Z,1740788691.930 [DATMMP ThreadHandler](INFO): Handler Thread ID is 943
2025-03-01T00:24:51.931Z,1740788691.931 [DATMMP](INFO): Start
2025-03-01T00:24:51.931Z,1740788691.931 [DATMMP](INFO): Powering up
2025-03-01T00:24:51.931Z,1740788691.931 [DATMMP](DEBUG): Initializing DATMMP.
2025-03-01T00:24:51.934Z,1740788691.934 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-01T00:24:51.934Z,1740788691.934 [CBIT](DEBUG): Initializing heartbeat.
2025-03-01T00:24:51.936Z,1740788691.936 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 945
2025-03-01T00:24:51.937Z,1740788691.937 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-01T00:24:51.946Z,1740788691.946 [Onboard ThreadHandler](INFO): Handler Thread ID is 946
2025-03-01T00:24:51.964Z,1740788691.964 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 947
2025-03-01T00:24:51.989Z,1740788691.989 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 948
2025-03-01T00:24:51.990Z,1740788691.990 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-01T00:24:51.992Z,1740788691.992 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 950
2025-03-01T00:24:51.999Z,1740788691.999 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 952
2025-03-01T00:24:52.001Z,1740788692.001 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-01T00:24:52.002Z,1740788692.002 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-01T00:24:52.002Z,1740788692.002 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-01T00:24:52.002Z,1740788692.002 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-01T00:24:52.002Z,1740788692.002 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-01T00:24:52.002Z,1740788692.002 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-01T00:24:52.002Z,1740788692.002 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-01T00:24:52.003Z,1740788692.003 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-01T00:24:52.005Z,1740788692.005 [CBIT](DEBUG): Deactivating GF circuits.
2025-03-01T00:24:52.006Z,1740788692.006 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-01T00:24:52.006Z,1740788692.006 [WetLabsBB2FL](INFO): Powering up
2025-03-01T00:24:52.045Z,1740788692.045 [CBIT](DEBUG): Backplane powered.
2025-03-01T00:24:52.051Z,1740788692.051 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-01T00:24:52.053Z,1740788692.053 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-01T00:24:52.053Z,1740788692.053 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-01T00:24:52.054Z,1740788692.054 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-01T00:24:52.054Z,1740788692.054 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-01T00:24:52.055Z,1740788692.055 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-01T00:24:52.055Z,1740788692.055 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-01T00:24:52.056Z,1740788692.056 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-01T00:24:52.114Z,1740788692.114 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-03-01T00:24:52.125Z,1740788692.125 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-01T00:24:52.149Z,1740788692.149 [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-01T00:24:52.150Z,1740788692.150 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-01T00:24:52.150Z,1740788692.150 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-01T00:24:52.151Z,1740788692.151 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-01T00:24:52.182Z,1740788692.182 [DATMMP](INFO): Starting
2025-03-01T00:24:52.368Z,1740788692.368 [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-01T00:24:52.370Z,1740788692.370 [Radio_Surface](INFO): Powering up
2025-03-01T00:24:52.394Z,1740788692.394 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-01T00:24:52.395Z,1740788692.395 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-01T00:24:52.397Z,1740788692.397 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-01T00:24:52.421Z,1740788692.421 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-01T00:24:52.424Z,1740788692.424 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-01T00:24:52.434Z,1740788692.434 [DATMMP](INFO): Starting
2025-03-01T00:24:52.445Z,1740788692.445 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-01T00:24:52.448Z,1740788692.448 [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-01T00:24:52.453Z,1740788692.453 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-01T00:24:52.460Z,1740788692.460 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-01T00:24:52.514Z,1740788692.514 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m
2025-03-01T00:24:52.516Z,1740788692.516 [Power24vConverter](INFO): Powering up.
2025-03-01T00:24:52.516Z,1740788692.516 [Sonardyne_Nano](INFO): Initializing.
2025-03-01T00:24:52.542Z,1740788692.542 [CANONSampler](INFO): Powering down
2025-03-01T00:24:52.554Z,1740788692.554 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-01T00:24:52.573Z,1740788692.573 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-01T00:24:52.590Z,1740788692.590 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-01T00:24:52.591Z,1740788692.591 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-01T00:24:52.598Z,1740788692.598 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-01T00:24:52.599Z,1740788692.599 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-01T00:24:52.606Z,1740788692.606 [MassServo](DEBUG): Initializing MassServo.
2025-03-01T00:24:52.607Z,1740788692.607 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-01T00:24:52.614Z,1740788692.614 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-01T00:24:52.615Z,1740788692.615 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-01T00:24:52.622Z,1740788692.622 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-01T00:24:52.686Z,1740788692.686 [DATMMP](INFO): Starting
2025-03-01T00:24:52.916Z,1740788692.916 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-03-01T00:24:52.916Z,1740788692.916 [DropWeight] Hardware Fault, FailCount= 1
2025-03-01T00:24:52.916Z,1740788692.916 [DropWeight](ERROR): Hardware Fault
2025-03-01T00:24:52.951Z,1740788692.951 [DATMMP](INFO): Starting
2025-03-01T00:24:52.954Z,1740788692.954 [CommandExec](FAULT): Scheduling is paused
2025-03-01T00:24:52.954Z,1740788692.954 [CBIT](INFO): Critical error at 20250301T002452
2025-03-01T00:24:52.954Z,1740788692.954 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-01T00:24:52.957Z,1740788692.957 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-01T00:24:52.962Z,1740788692.962 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-01T00:24:53.202Z,1740788693.202 [DATMMP](INFO): Starting
2025-03-01T00:24:53.304Z,1740788693.304 [CBIT](INFO): Critical error at 20250301T002452
2025-03-01T00:24:53.453Z,1740788693.453 [DATMMP](INFO): Starting
2025-03-01T00:24:53.706Z,1740788693.706 [DATMMP](INFO): Starting
2025-03-01T00:24:53.958Z,1740788693.958 [DATMMP](INFO): Starting
2025-03-01T00:24:54.210Z,1740788694.210 [DATMMP](INFO): Starting
2025-03-01T00:24:54.462Z,1740788694.462 [DATMMP](INFO): Starting
2025-03-01T00:24:54.484Z,1740788694.484 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-01T00:24:54.606Z,1740788694.606 [WetLabsBB2FL](INFO): Powering down
2025-03-01T00:24:54.714Z,1740788694.714 [DATMMP](INFO): Starting
2025-03-01T00:24:54.966Z,1740788694.966 [DATMMP](INFO): Starting
2025-03-01T00:24:55.218Z,1740788695.218 [DATMMP](INFO): Starting
2025-03-01T00:24:55.470Z,1740788695.470 [DATMMP](INFO): Starting
2025-03-01T00:24:55.723Z,1740788695.723 [DATMMP](INFO): Starting
2025-03-01T00:24:55.974Z,1740788695.974 [DATMMP](INFO): Starting
2025-03-01T00:24:56.126Z,1740788696.126 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2105
2025-03-01T00:24:56.226Z,1740788696.226 [DATMMP](INFO): Starting
2025-03-01T00:24:56.478Z,1740788696.478 [DATMMP](INFO): Starting
2025-03-01T00:24:56.541Z,1740788696.541 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-01T00:24:56.730Z,1740788696.730 [DATMMP](INFO): Starting
2025-03-01T00:24:56.983Z,1740788696.983 [DATMMP](INFO): Starting
2025-03-01T00:24:57.234Z,1740788697.234 [DATMMP](INFO): Starting
2025-03-01T00:24:57.486Z,1740788697.486 [DATMMP](INFO): Starting
2025-03-01T00:24:57.739Z,1740788697.739 [DATMMP](INFO): Starting
2025-03-01T00:24:57.990Z,1740788697.990 [DATMMP](INFO): Starting
2025-03-01T00:24:58.242Z,1740788698.242 [DATMMP](INFO): Starting
2025-03-01T00:24:58.494Z,1740788698.494 [DATMMP](INFO): Starting
2025-03-01T00:24:58.746Z,1740788698.746 [DATMMP](INFO): Starting
2025-03-01T00:24:58.998Z,1740788698.998 [DATMMP](INFO): Starting
2025-03-01T00:24:59.022Z,1740788699.022 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-03-01T00:24:59.250Z,1740788699.250 [DATMMP](INFO): Starting
2025-03-01T00:24:59.502Z,1740788699.502 [DATMMP](INFO): Starting
2025-03-01T00:24:59.755Z,1740788699.755 [DATMMP](INFO): Starting
2025-03-01T00:25:00.006Z,1740788700.006 [DATMMP](INFO): Starting
2025-03-01T00:25:00.259Z,1740788700.259 [DATMMP](INFO): Starting
2025-03-01T00:25:00.510Z,1740788700.510 [DATMMP](INFO): Starting
2025-03-01T00:25:00.762Z,1740788700.762 [DATMMP](INFO): Starting
2025-03-01T00:25:01.014Z,1740788701.014 [DATMMP](INFO): Starting
2025-03-01T00:25:01.266Z,1740788701.266 [DATMMP](INFO): Starting
2025-03-01T00:25:01.518Z,1740788701.518 [DATMMP](INFO): Starting
2025-03-01T00:25:01.770Z,1740788701.770 [DATMMP](INFO): Starting
2025-03-01T00:25:02.022Z,1740788702.022 [DATMMP](INFO): Starting
2025-03-01T00:25:02.274Z,1740788702.274 [DATMMP](INFO): Starting
2025-03-01T00:25:02.526Z,1740788702.526 [DATMMP](INFO): Starting
2025-03-01T00:25:02.778Z,1740788702.778 [DATMMP](INFO): Starting
2025-03-01T00:25:02.000Z,1740788703.000 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004652
2025-03-01T00:25:03.030Z,1740788703.030 [DATMMP](INFO): Starting
2025-03-01T00:25:03.284Z,1740788703.284 [DATMMP](INFO): Starting
2025-03-01T00:25:03.538Z,1740788703.538 [DATMMP](INFO): Starting
2025-03-01T00:25:03.789Z,1740788703.789 [DATMMP](INFO): Starting
2025-03-01T00:25:04.042Z,1740788704.042 [DATMMP](INFO): Starting
2025-03-01T00:25:04.294Z,1740788704.294 [DATMMP](INFO): Starting
2025-03-01T00:25:04.550Z,1740788704.550 [DATMMP](INFO): Starting
2025-03-01T00:25:04.551Z,1740788704.551 [DATMMP](INFO): DAT read:
2025-03-01T00:25:04.552Z,1740788704.552 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-03-01T00:25:04.802Z,1740788704.802 [DATMMP](INFO): Starting
2025-03-01T00:25:05.054Z,1740788705.054 [DATMMP](INFO): Starting
2025-03-01T00:25:05.306Z,1740788705.306 [DATMMP](INFO): Starting
2025-03-01T00:25:05.558Z,1740788705.558 [DATMMP](INFO): Starting
2025-03-01T00:25:05.810Z,1740788705.810 [DATMMP](INFO): Starting
2025-03-01T00:25:06.062Z,1740788706.062 [DATMMP](INFO): Starting
2025-03-01T00:25:06.315Z,1740788706.315 [DATMMP](INFO): Starting
2025-03-01T00:25:06.317Z,1740788706.317 [DATMMP](INFO): DAT read: MF Frequency Band
2025-03-01T00:25:06.320Z,1740788706.320 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-03-01T00:25:06.566Z,1740788706.566 [DATMMP](INFO): Starting
2025-03-01T00:25:06.566Z,1740788706.566 [DATMMP](INFO): DAT read: Jan 28 2000 19:27:54
2025-03-01T00:25:06.818Z,1740788706.818 [DATMMP](INFO): Starting
2025-03-01T00:25:07.070Z,1740788707.070 [DATMMP](INFO): Starting
2025-03-01T00:25:07.322Z,1740788707.322 [DATMMP](INFO): Starting
2025-03-01T00:25:07.574Z,1740788707.574 [DATMMP](INFO): Starting
2025-03-01T00:25:07.574Z,1740788707.574 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2025-03-01T00:25:07.576Z,1740788707.576 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-03-01T00:25:07.576Z,1740788707.576 [DATMMP](INFO): commRate: 800
2025-03-01T00:25:07.826Z,1740788707.826 [DATMMP](INFO): Starting
2025-03-01T00:25:08.078Z,1740788708.078 [DATMMP](INFO): Starting
2025-03-01T00:25:08.330Z,1740788708.330 [DATMMP](INFO): Starting
2025-03-01T00:25:08.582Z,1740788708.582 [DATMMP](INFO): Starting
2025-03-01T00:25:08.834Z,1740788708.834 [DATMMP](INFO): Starting
2025-03-01T00:25:09.086Z,1740788709.086 [DATMMP](INFO): Starting
2025-03-01T00:25:09.338Z,1740788709.338 [DATMMP](INFO): Starting
2025-03-01T00:25:09.590Z,1740788709.590 [DATMMP](INFO): Starting
2025-03-01T00:25:09.642Z,1740788709.642 [DATMMP](INFO): entering command mode
2025-03-01T00:25:09.842Z,1740788709.842 [DATMMP](INFO): Starting
2025-03-01T00:25:09.842Z,1740788709.842 [DATMMP](INFO): DAT read:
2025-03-01T00:25:09.842Z,1740788709.842 [DATMMP](INFO): DAT read: user:1>
2025-03-01T00:25:09.843Z,1740788709.843 [DATMMP](DEBUG): read user prompt 1: user:1>
2025-03-01T00:25:09.843Z,1740788709.843 [DATMMP](INFO): entering MMP mode
2025-03-01T00:25:10.094Z,1740788710.094 [DATMMP](INFO): Starting
2025-03-01T00:25:10.094Z,1740788710.094 [DATMMP](INFO): GSXN notify for xid: 0
2025-03-01T00:25:10.096Z,1740788710.096 [DATMMP](INFO): status rx: x1
2025-03-01T00:25:10.096Z,1740788710.096 [DATMMP](INFO): MMP status: 1: started
2025-03-01T00:25:10.096Z,1740788710.096 [DATMMP](INFO): Received message type: status
2025-03-01T00:25:10.097Z,1740788710.097 [DATMMP](INFO): Handled
2025-03-01T00:25:10.346Z,1740788710.346 [DATMMP](INFO): Starting
2025-03-01T00:25:10.347Z,1740788710.347 [DATMMP](INFO): Sent Tx Power Config 8
2025-03-01T00:25:10.347Z,1740788710.347 [DATMMP](INFO): Setting local address: 9
2025-03-01T00:25:10.349Z,1740788710.349 [DATMMP](INFO): Setting time to: 0:25:10 And date to:3/1/2025
2025-03-01T00:25:10.598Z,1740788710.598 [DATMMP](INFO): Starting
2025-03-01T00:25:10.598Z,1740788710.598 [DATMMP](INFO): GSXN notify for xid: 0
2025-03-01T00:25:10.599Z,1740788710.599 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2025-03-01T00:25:10.599Z,1740788710.599 [DATMMP](INFO): Received message type: mdmpower
2025-03-01T00:25:10.599Z,1740788710.599 [DATMMP](INFO): Handled
2025-03-01T00:25:10.850Z,1740788710.850 [DATMMP](INFO): Starting
2025-03-01T00:25:10.850Z,1740788710.850 [DATMMP](INFO): GSXN notify for xid: 10
2025-03-01T00:25:10.851Z,1740788710.851 [DATMMP](INFO): sreg_txpower rx: x8
2025-03-01T00:25:10.851Z,1740788710.851 [DATMMP](INFO): Received message type: sreg_txpower
2025-03-01T00:25:10.851Z,1740788710.851 [DATMMP](INFO): Match for : sreg_txpower
2025-03-01T00:25:10.851Z,1740788710.851 [DATMMP](INFO): ACK
2025-03-01T00:25:11.102Z,1740788711.102 [DATMMP](INFO): Starting
2025-03-01T00:25:11.103Z,1740788711.103 [DATMMP](INFO): GSXN notify for xid: 0
2025-03-01T00:25:11.103Z,1740788711.103 [DATMMP](INFO): mdmlocal rx: x9 x0 x0 x0
2025-03-01T00:25:11.104Z,1740788711.104 [DATMMP](INFO): Received message type: mdmlocal
2025-03-01T00:25:11.104Z,1740788711.104 [DATMMP](INFO): Handled
2025-03-01T00:25:11.354Z,1740788711.354 [DATMMP](INFO): Starting
2025-03-01T00:25:11.354Z,1740788711.354 [DATMMP](INFO): GSXN notify for xid: 11
2025-03-01T00:25:11.355Z,1740788711.355 [DATMMP](INFO): sreg_locaddr rx: x9
2025-03-01T00:25:11.355Z,1740788711.355 [DATMMP](INFO): Received message type: sreg_locaddr
2025-03-01T00:25:11.355Z,1740788711.355 [DATMMP](INFO): Match for : sreg_locaddr
2025-03-01T00:25:11.355Z,1740788711.355 [DATMMP](INFO): ACK
2025-03-01T00:25:11.606Z,1740788711.606 [DATMMP](INFO): Starting
2025-03-01T00:25:11.606Z,1740788711.606 [DATMMP](INFO): GSXN notify for xid: 12
2025-03-01T00:25:11.607Z,1740788711.607 [DATMMP](INFO): timedate rx: x0 x0 x19 xA x2 x1 x7D x0
2025-03-01T00:25:11.607Z,1740788711.607 [DATMMP](INFO): Received message type: timedate
2025-03-01T00:25:11.608Z,1740788711.608 [DATMMP](INFO): Match for : timedate
2025-03-01T00:25:11.608Z,1740788711.608 [DATMMP](INFO): ACK
2025-03-01T00:25:11.858Z,1740788711.858 [DATMMP](INFO): Starting
2025-03-01T00:25:11.858Z,1740788711.858 [DATMMP](INFO): GSXN notify for xid: 0
2025-03-01T00:25:11.859Z,1740788711.859 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2025-03-01T00:25:11.859Z,1740788711.859 [DATMMP](INFO): Received message type: sys_verb
2025-03-01T00:25:11.859Z,1740788711.859 [DATMMP](INFO): Handled
2025-03-01T00:25:12.110Z,1740788712.110 [DATMMP](INFO): Starting
2025-03-01T00:25:12.110Z,1740788712.110 [DATMMP](INFO): GSXN notify for xid: 13
2025-03-01T00:25:12.111Z,1740788712.111 [DATMMP](INFO): sreg_verbosity rx: x0
2025-03-01T00:25:12.111Z,1740788712.111 [DATMMP](INFO): Received message type: sreg_verbosity
2025-03-01T00:25:12.111Z,1740788712.111 [DATMMP](INFO): Match for : sreg_verbosity
2025-03-01T00:25:12.111Z,1740788712.111 [DATMMP](INFO): ACK
2025-03-01T00:25:12.362Z,1740788712.362 [DATMMP](INFO): Starting
2025-03-01T00:25:12.362Z,1740788712.362 [DATMMP](INFO): GSXN notify for xid: 14
2025-03-01T00:25:12.363Z,1740788712.363 [DATMMP](INFO): version rx: x2 x5
2025-03-01T00:25:12.363Z,1740788712.363 [DATMMP](INFO): DatMMPVersion: 2.5
2025-03-01T00:25:12.363Z,1740788712.363 [DATMMP](INFO): Received message type: version
2025-03-01T00:25:12.364Z,1740788712.364 [DATMMP](INFO): Match for : version
2025-03-01T00:25:12.364Z,1740788712.364 [DATMMP](INFO): ACK
2025-03-01T00:25:12.364Z,1740788712.364 [DATMMP](INFO): in MMP mode: config complete going to runnable
2025-03-01T00:25:19.943Z,1740788719.943 [NAL9602](INFO): Powering up NAL9602
2025-03-01T00:25:26.726Z,1740788726.726 [DATMMP](INFO): GSXN notify for xid: 0
2025-03-01T00:25:26.727Z,1740788726.727 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2025-03-01T00:25:26.727Z,1740788726.727 [DATMMP](INFO): Received message type: timesync
2025-03-01T00:25:26.727Z,1740788726.727 [DATMMP](INFO): Handled
2025-03-01T00:25:29.854Z,1740788729.854 [Waterlinked](INFO): Powering down
2025-03-01T00:25:30.911Z,1740788730.911 [NAL9602](INFO): NAL9602 initialized
2025-03-01T00:25:36.818Z,1740788736.818 [DATMMP](INFO): GSXN notify for xid: 0
2025-03-01T00:25:36.819Z,1740788736.819 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2025-03-01T00:25:36.819Z,1740788736.819 [DATMMP](INFO): Received message type: timesync
2025-03-01T00:25:36.819Z,1740788736.819 [DATMMP](INFO): Handled
2025-03-01T00:26:29.511Z,1740788789.511 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-01T00:26:29.516Z,1740788789.516 [CBIT](IMPORTANT): Beginning ground fault scan
2025-03-01T00:26:40.637Z,1740788800.637 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009795
CHAN A1 (24V): 0.000374
CHAN A2 (12V): -0.005678
CHAN A3 (5V): -0.003045
CHAN B0 (3.3V): -0.001125
CHAN B1 (3.15aV): -0.001382
CHAN B2 (3.15bV): -0.001247
CHAN B3 (GND): -0.000236
OPEN: 0.004976
Full Scale: +/- 1 mA
2025-03-01T00:27:23.673Z,1740788843.673 [SBIT](IMPORTANT): SBIT PASSED
2025-03-01T00:27:23.674Z,1740788843.674 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-01T00:27:23.674Z,1740788843.674 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2025-03-01T00:27:23.674Z,1740788843.674 [SBIT](IMPORTANT): CANONSampler.simulateHardware=1 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): CBIT.navErrorThreshold=250 percent;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=30 minute;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2025-03-01T00:27:23.675Z,1740788843.675 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 250.000000 count;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): Express none platform_average_current;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=1 ampere_hour;
2025-03-01T00:27:23.676Z,1740788843.676 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2025-03-01T00:27:23.677Z,1740788843.677 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool;
2025-03-01T00:27:23.677Z,1740788843.677 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=209.127836 cubic_centimeter;
2025-03-01T00:27:23.677Z,1740788843.677 [SBIT](IMPORTANT): VerticalControl.massDefault=19.324974 millimeter;
2025-03-01T00:27:23.677Z,1740788843.677 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=1 bool;
2025-03-01T00:27:24.050Z,1740788844.050 [MissionManager](IMPORTANT): Started mission Startup
2025-03-01T00:27:24.051Z,1740788844.051 [Startup] Running Loop=1
2025-03-01T00:27:24.051Z,1740788844.051 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-01T00:27:24.051Z,1740788844.051 [Startup:A.GoToSurface] Running Loop=1
2025-03-01T00:27:24.051Z,1740788844.051 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-01T00:27:24.051Z,1740788844.051 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-01T00:27:24.052Z,1740788844.052 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-01T00:27:24.052Z,1740788844.052 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-01T00:27:24.052Z,1740788844.052 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-01T00:27:24.053Z,1740788844.053 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-01T00:27:24.053Z,1740788844.053 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-01T00:27:24.056Z,1740788844.056 [Startup:StartupSatComms] Running Loop=1
2025-03-01T00:27:24.056Z,1740788844.056 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-01T00:27:24.056Z,1740788844.056 [Startup:StartupSatComms:A] Running Loop=1
2025-03-01T00:27:24.457Z,1740788844.457 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-01T00:27:51.930Z,1740788871.930 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-01T00:27:51.930Z,1740788871.930 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-01T00:27:51.931Z,1740788871.931 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2025-03-01T00:27:51.931Z,1740788871.931 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2025-03-01T00:27:51.991Z,1740788871.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-01T00:27:51.991Z,1740788871.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2025-03-01T00:27:52.337Z,1740788872.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-01T00:27:52.337Z,1740788872.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-01T00:27:52.342Z,1740788872.342 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2025-03-01T00:27:52.342Z,1740788872.342 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2025-03-01T00:27:52.738Z,1740788872.738 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2025-03-01T00:28:24.241Z,1740788904.241 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-01T00:27:24.1Z
2025-03-01T00:28:24.241Z,1740788904.241 [Startup:StartupSatComms:A] Stopped
2025-03-01T00:28:24.241Z,1740788904.241 [Startup:StartupSatComms:B] Running Loop=1
2025-03-01T00:28:24.651Z,1740788904.651 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-01T00:28:30.597Z,1740788910.597 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250228T191330/Courier0004.lzma
2025-03-01T00:28:31.600Z,1740788911.600 [DataOverHttps](INFO): Moved sent file to Logs/20250228T191330/Courier0004.lzma.bak
2025-03-01T00:28:31.600Z,1740788911.600 [DataOverHttps](INFO): SBD MOMSN=23981699
2025-03-01T00:28:47.353Z,1740788927.353 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250301T002440/Courier0000.lzma
2025-03-01T00:28:48.355Z,1740788928.355 [DataOverHttps](INFO): Moved sent file to Logs/20250301T002440/Courier0000.lzma.bak
2025-03-01T00:28:48.355Z,1740788928.355 [DataOverHttps](INFO): SBD MOMSN=23981701
2025-03-01T00:29:03.865Z,1740788943.865 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20250228T191330/Express0005.lzma
2025-03-01T00:29:04.867Z,1740788944.867 [DataOverHttps](INFO): Moved sent file to Logs/20250228T191330/Express0005.lzma.bak
2025-03-01T00:29:04.867Z,1740788944.867 [DataOverHttps](INFO): SBD MOMSN=23981706
2025-03-01T00:29:20.330Z,1740788960.330 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/lab_test_optim.tl
2025-03-01T00:29:20.330Z,1740788960.330 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/lab_test_optim.tl
2025-03-01T00:29:20.331Z,1740788960.331 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/lab_test_optim.tl
2025-03-01T00:29:20.334Z,1740788960.334 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/lab_test_optim.tl
2025-03-01T00:29:20.876Z,1740788960.876 [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-01T00:29:20.883Z,1740788960.883 [MissionManager](INFO): DefineArg lab_test_optim.MissionTimeout = 15.000000 min
2025-03-01T00:29:20.914Z,1740788960.914 [MissionManager](INFO): DefineArg lab_test_optim.RecordTime = 1.000000 min
2025-03-01T00:29:20.917Z,1740788960.917 [MissionManager](INFO): DefineArg lab_test_optim.BackseatWait = 30.000000 s
2025-03-01T00:29:20.924Z,1740788960.924 [MissionManager](INFO): DefineArg lab_test_optim.UseMission = 1 bool
2025-03-01T00:29:20.937Z,1740788960.937 [MissionManager](INFO): DefineArg lab_test_optim.UseHardcode = 0 bool
2025-03-01T00:29:20.948Z,1740788960.948 [MissionManager](INFO): DefineArg lab_test_optim.InitialSampleTime = 1.000000 s
2025-03-01T00:29:20.960Z,1740788960.960 [MissionManager](INFO): DefineArg lab_test_optim.SleepCycles = 3.000000 count
2025-03-01T00:29:20.983Z,1740788960.983 [MissionManager](INFO): DefineArg lab_test_optim.LightMode = 2.000000 count
2025-03-01T00:29:20.990Z,1740788960.990 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessWhite = 100.000000 n/a int32
2025-03-01T00:29:20.993Z,1740788960.993 [MissionManager](INFO): DefineArg lab_test_optim.BrightnessRed = 100.000000 n/a int32
2025-03-01T00:29:21.008Z,1740788961.008 [MissionManager](INFO): DefineOutput lab_test_optim.CyclesWaited = 0.000000 count
2025-03-01T00:29:21.011Z,1740788961.011 [MissionManager](INFO): DefineOutput lab_test_optim.RecordingEnabled = 0 bool
2025-03-01T00:29:21.032Z,1740788961.032 [DataOverHttps](INFO): Sending 1154 bytes from file Logs/20250301T002440/Express0001.lzma
2025-03-01T00:29:21.042Z,1740788961.042 [MissionManager](INFO): DefineOutput lab_test_optim.LightsEnabled = 0 bool
2025-03-01T00:29:21.042Z,1740788961.042 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-03-01T00:29:21.043Z,1740788961.043 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-03-01T00:29:21.044Z,1740788961.044 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-03-01T00:29:21.099Z,1740788961.099 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-03-01T00:29:21.107Z,1740788961.107 [MissionManager](INFO): DefineArg lab_test_optim:BackseatDriver.EnableBackseat = 0 bool
2025-03-01T00:29:21.122Z,1740788961.122 [lab_test_optim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-03-01T00:29:21.154Z,1740788961.154 [lab_test_optim:Startup:B.Wait](DEBUG): Construct Wait.
2025-03-01T00:29:21.175Z,1740788961.175 [lab_test_optim:Startup:UseMission:E.Wait](DEBUG): Construct Wait.
2025-03-01T00:29:21.213Z,1740788961.213 [lab_test_optim:Startup:UseBackdoor:A.Execute](DEBUG): Construct Execute.
2025-03-01T00:29:21.239Z,1740788961.239 [lab_test_optim:Startup:UseBackdoor:D.Execute](DEBUG): Construct Execute.
2025-03-01T00:29:21.252Z,1740788961.252 [lab_test_optim:Startup:UseBackdoor:G.Execute](DEBUG): Construct Execute.
2025-03-01T00:29:21.272Z,1740788961.272 [lab_test_optim:Startup:UseHardcode:A.Execute](DEBUG): Construct Execute.
2025-03-01T00:29:21.316Z,1740788961.316 [MissionManager](CRITICAL): Slate does not contain MultiRay.lightModeCommand
2025-03-01T00:29:21.316Z,1740788961.316 [lab_test_optim:Run](CRITICAL): Error!!!! no component for child behavior.
2025-03-01T00:29:21.354Z,1740788961.354 [lab_test_optim:Run:G.Wait](DEBUG): Construct Wait.
2025-03-01T00:29:21.355Z,1740788961.355 [lab_test_optim:G.Wait](DEBUG): Construct Wait.
2025-03-01T00:29:21.371Z,1740788961.371 [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-01T00:29:21.375Z,1740788961.375 [CommandExec](IMPORTANT): Running ./Missions/Engineering/lab_test_optim.tl
2025-03-01T00:29:21.795Z,1740788961.795 [CBIT](INFO): Critical error at 20250301T002921
2025-03-01T00:29:21.796Z,1740788961.796 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-01T00:29:22.031Z,1740788962.031 [DataOverHttps](INFO): Moved sent file to Logs/20250301T002440/Express0001.lzma.bak
2025-03-01T00:29:22.031Z,1740788962.031 [DataOverHttps](INFO): SBD MOMSN=23981711
2025-03-01T00:29:23.652Z,1740788963.652 [Startup:StartupSatComms:B] Stopped
2025-03-01T00:29:23.652Z,1740788963.652 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-01T00:29:23.652Z,1740788963.652 [Startup:StartupSatComms] Stopped
2025-03-01T00:29:23.653Z,1740788963.653 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-01T00:29:23.654Z,1740788963.654 [Startup](INFO): Completed Startup
2025-03-01T00:29:23.654Z,1740788963.654 [MissionManager](INFO): Startup is completed.
2025-03-01T00:29:23.654Z,1740788963.654 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-01T00:29:23.654Z,1740788963.654 [Startup] Stopped
2025-03-01T00:29:23.655Z,1740788963.655 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-01T00:29:23.655Z,1740788963.655 [Startup:A.GoToSurface] Stopped
2025-03-01T00:29:23.655Z,1740788963.655 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-01T00:29:24.059Z,1740788964.059 [MissionManager](IMPORTANT): Started mission lab_test_optim
2025-03-01T00:29:24.059Z,1740788964.059 [lab_test_optim] Running Loop=1
2025-03-01T00:29:24.059Z,1740788964.059 [lab_test_optim](DEBUG): Aggregate::initialize lab_test_optim
2025-03-01T00:29:24.059Z,1740788964.059 [lab_test_optim:BackseatDriver] Running Loop=1
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::initialize lab_test_optim:BackseatDriver
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:B] Running Loop=1
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:C] Running Loop=1
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:D] Running Loop=1
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:Startup] Running Loop=1
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:Startup](DEBUG): Aggregate::initialize lab_test_optim:Startup
2025-03-01T00:29:24.060Z,1740788964.060 [lab_test_optim:Startup:A] Running Loop=1
2025-03-01T00:29:24.061Z,1740788964.061 [lab_test_optim:Startup:A](IMPORTANT): Waiting 30.000000 s for backseat to start up...
2025-03-01T00:29:24.061Z,1740788964.061 [lab_test_optim:Startup:A] Stopped
2025-03-01T00:29:24.062Z,1740788964.062 [lab_test_optim:Startup:B.Wait] Running Loop=1
2025-03-01T00:29:24.062Z,1740788964.062 [lab_test_optim:Startup:B.Wait](DEBUG): Initialize Wait Component.
2025-03-01T00:29:24.062Z,1740788964.062 [lab_test_optim:D] Stopped
2025-03-01T00:29:24.062Z,1740788964.062 [lab_test_optim:C] Stopped
2025-03-01T00:29:24.062Z,1740788964.062 [lab_test_optim:B] Running Loop=1
2025-03-01T00:29:24.062Z,1740788964.062 [lab_test_optim:BackseatDriver] Running Loop=1
2025-03-01T00:29:24.063Z,1740788964.063 [lab_test_optim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-03-01T00:29:24.147Z,1740788964.147 [MissionManager](INFO): MissionManager is completed.
2025-03-01T00:29:24.147Z,1740788964.147 [MissionManager](INFO): Uninitializing Mission lab_test_optim
2025-03-01T00:29:24.147Z,1740788964.147 [lab_test_optim] Stopped
2025-03-01T00:29:24.147Z,1740788964.147 [lab_test_optim](DEBUG): Aggregate::uninitialize lab_test_optim
2025-03-01T00:29:24.147Z,1740788964.147 [lab_test_optim:BackseatDriver] Stopped
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:BackseatDriver](DEBUG): Aggregate::uninitialize lab_test_optim:BackseatDriver
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:BackseatDriver:A.BackseatDriver] Stopped
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:B] Stopped
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:Startup] Stopped
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:Startup](DEBUG): Aggregate::uninitialize lab_test_optim:Startup
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:Startup:B.Wait] Stopped
2025-03-01T00:29:24.148Z,1740788964.148 [lab_test_optim:Startup:B.Wait](DEBUG): Uninitialize Wait Component.
2025-03-01T00:29:24.446Z,1740788964.446 [MissionManager](IMPORTANT): Started mission Default
2025-03-01T00:29:24.447Z,1740788964.447 [Default] Running Loop=1
2025-03-01T00:29:24.447Z,1740788964.447 [Default](DEBUG): Aggregate::initialize Default
2025-03-01T00:29:24.447Z,1740788964.447 [Default:B.GoToSurface] Running Loop=1
2025-03-01T00:29:24.447Z,1740788964.447 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-01T00:29:24.447Z,1740788964.447 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-01T00:29:24.447Z,1740788964.447 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-01T00:29:24.448Z,1740788964.448 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-01T00:29:24.448Z,1740788964.448 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-01T00:29:24.448Z,1740788964.448 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-01T00:29:24.449Z,1740788964.449 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-01T00:29:24.449Z,1740788964.449 [Default:A.Wait] Running Loop=1
2025-03-01T00:29:24.449Z,1740788964.449 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-01T00:29:35.307Z,1740788975.307 [CommandExec](IMPORTANT): got command configSet MultiRay.loadAtStartup 1 bool persist
2025-03-01T00:29:35.307Z,1740788975.307 [CommandExec](IMPORTANT): configSet MultiRay.loadAtStartup requires a restart to take effect.
2025-03-01T00:29:35.311Z,1740788975.311 [CommandExec](IMPORTANT): got command restart application
2025-03-01T00:29:36.314Z,1740788976.314 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:36.314Z,1740788976.314 [CommandExec](INFO): Uninitializing the command executive.
2025-03-01T00:29:36.314Z,1740788976.314 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-01T00:29:36.314Z,1740788976.314 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:36.321Z,1740788976.321 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-03-01T00:29:36.321Z,1740788976.321 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-01T00:29:36.322Z,1740788976.322 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:36.322Z,1740788976.322 [NavChartDb](INFO): Join timeout helper Thread ID is 980
2025-03-01T00:29:36.646Z,1740788976.646 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:36.646Z,1740788976.646 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:36.653Z,1740788976.653 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-01T00:29:36.654Z,1740788976.654 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:36.654Z,1740788976.654 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 981
2025-03-01T00:29:36.818Z,1740788976.818 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:36.818Z,1740788976.818 [WetLabsBB2FL](INFO): Powering down
2025-03-01T00:29:36.819Z,1740788976.819 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:36.834Z,1740788976.834 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-01T00:29:36.834Z,1740788976.834 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:36.834Z,1740788976.834 [CTD_Seabird](INFO): Join timeout helper Thread ID is 982
2025-03-01T00:29:37.162Z,1740788977.162 [CTD_Seabird](INFO): Powering down
2025-03-01T00:29:37.174Z,1740788977.174 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:37.174Z,1740788977.174 [CTD_Seabird](INFO): Powering down
2025-03-01T00:29:37.190Z,1740788977.190 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:37.211Z,1740788977.211 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-01T00:29:37.211Z,1740788977.211 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:37.211Z,1740788977.211 [Radio_Surface](INFO): Join timeout helper Thread ID is 983
2025-03-01T00:29:37.610Z,1740788977.610 [Radio_Surface](INFO): Powering down
2025-03-01T00:29:37.611Z,1740788977.611 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:37.611Z,1740788977.611 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:37.623Z,1740788977.623 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-01T00:29:37.623Z,1740788977.623 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:37.624Z,1740788977.624 [Onboard](INFO): Join timeout helper Thread ID is 984
2025-03-01T00:29:37.783Z,1740788977.783 [Default:A.Wait](INFO): Done Waiting.
2025-03-01T00:29:37.783Z,1740788977.783 [Default:A.Wait] Stopped
2025-03-01T00:29:37.783Z,1740788977.783 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-01T00:29:37.798Z,1740788977.798 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-01T00:29:38.177Z,1740788978.177 [Default:CheckIn] Running Loop=1
2025-03-01T00:29:38.177Z,1740788978.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-01T00:29:38.177Z,1740788978.177 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-01T00:29:38.571Z,1740788978.571 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-01T00:29:42.178Z,1740788982.178 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:42.178Z,1740788982.178 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:42.186Z,1740788982.186 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-01T00:29:42.187Z,1740788982.187 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:42.187Z,1740788982.187 [DataOverHttps](INFO): Join timeout helper Thread ID is 985
2025-03-01T00:29:42.818Z,1740788982.818 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:42.818Z,1740788982.818 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:42.834Z,1740788982.834 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2025-03-01T00:29:42.834Z,1740788982.834 [DATMMP ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:42.835Z,1740788982.835 [DATMMP](INFO): Join timeout helper Thread ID is 986
2025-03-01T00:29:42.954Z,1740788982.954 [DATMMP](INFO): Stop
2025-03-01T00:29:42.954Z,1740788982.954 [DATMMP](INFO): uninitialize
2025-03-01T00:29:42.954Z,1740788982.954 [DATMMP](INFO): Powering down
2025-03-01T00:29:43.034Z,1740788983.034 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:43.035Z,1740788983.035 [DATMMP](INFO): uninitialize
2025-03-01T00:29:43.035Z,1740788983.035 [DATMMP](INFO): Powering down
2025-03-01T00:29:43.036Z,1740788983.036 [DATMMP ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.054Z,1740788983.054 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-03-01T00:29:43.054Z,1740788983.054 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.055Z,1740788983.055 [BackseatComponent](INFO): Join timeout helper Thread ID is 987
2025-03-01T00:29:43.226Z,1740788983.226 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:43.226Z,1740788983.226 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.234Z,1740788983.234 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-01T00:29:43.234Z,1740788983.234 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.235Z,1740788983.235 [logger](INFO): Join timeout helper Thread ID is 988
2025-03-01T00:29:43.270Z,1740788983.270 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:43.270Z,1740788983.270 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.274Z,1740788983.274 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-01T00:29:43.274Z,1740788983.274 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.275Z,1740788983.275 [CommandLine](INFO): Join timeout helper Thread ID is 989
2025-03-01T00:29:43.342Z,1740788983.342 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:43.342Z,1740788983.342 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.354Z,1740788983.354 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-01T00:29:43.354Z,1740788983.354 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.355Z,1740788983.355 [CommandExec](INFO): Join timeout helper Thread ID is 990
2025-03-01T00:29:43.356Z,1740788983.356 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-01T00:29:43.356Z,1740788983.356 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.356Z,1740788983.356 [controlThread](INFO): Join timeout helper Thread ID is 991
2025-03-01T00:29:43.398Z,1740788983.398 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-01T00:29:43.398Z,1740788983.398 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-01T00:29:43.398Z,1740788983.398 [AHRS_M2](INFO): Powering down
2025-03-01T00:29:43.471Z,1740788983.471 [NAL9602](INFO): Powering down
2025-03-01T00:29:43.472Z,1740788983.472 [Sonardyne_Nano](INFO): Powering down
2025-03-01T00:29:43.590Z,1740788983.590 [Waterlinked](INFO): Powering down
2025-03-01T00:29:43.591Z,1740788983.591 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-01T00:29:43.592Z,1740788983.592 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-01T00:29:43.593Z,1740788983.593 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-01T00:29:43.593Z,1740788983.593 [MissionManager](INFO): Uninitializing Mission Default
2025-03-01T00:29:43.593Z,1740788983.593 [Default] Stopped
2025-03-01T00:29:43.594Z,1740788983.594 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-01T00:29:43.594Z,1740788983.594 [Default:B.GoToSurface] Stopped
2025-03-01T00:29:43.594Z,1740788983.594 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-01T00:29:43.594Z,1740788983.594 [Default:CheckIn] Stopped
2025-03-01T00:29:43.594Z,1740788983.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-01T00:29:43.594Z,1740788983.594 [Default:CheckIn:Read_GPS] Stopped
2025-03-01T00:29:43.597Z,1740788983.597 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-01T00:29:43.597Z,1740788983.597 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-01T00:29:43.597Z,1740788983.597 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-01T00:29:43.598Z,1740788983.598 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-01T00:29:43.598Z,1740788983.598 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-01T00:29:43.598Z,1740788983.598 [BuoyancyServo](INFO): Powering down
2025-03-01T00:29:43.610Z,1740788983.610 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-01T00:29:43.610Z,1740788983.610 [ElevatorServo](INFO): Powering down
2025-03-01T00:29:43.611Z,1740788983.611 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-01T00:29:43.611Z,1740788983.611 [MassServo](INFO): Powering down
2025-03-01T00:29:43.611Z,1740788983.611 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-01T00:29:43.612Z,1740788983.612 [RudderServo](INFO): Powering down
2025-03-01T00:29:43.612Z,1740788983.612 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-01T00:29:43.612Z,1740788983.612 [ThrusterHE](INFO): Powering down
2025-03-01T00:29:43.613Z,1740788983.613 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-01T00:29:43.614Z,1740788983.614 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-01T00:29:43.614Z,1740788983.614 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-01T00:29:43.614Z,1740788983.614 [CBIT](DEBUG): Powering off loads.
2025-03-01T00:29:43.625Z,1740788983.625 [CBIT](DEBUG): Disabling WDT.
2025-03-01T00:29:43.637Z,1740788983.637 [CBIT](DEBUG): Opening all GF detection circuits.
2025-03-01T00:29:43.638Z,1740788983.638 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.669Z,1740788983.669 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.672Z,1740788983.672 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.677Z,1740788983.677 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.683Z,1740788983.683 [DATMMP ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.725Z,1740788983.725 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.774Z,1740788983.774 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.779Z,1740788983.779 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.832Z,1740788983.832 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-01T00:29:43.879Z,1740788983.879 [logger ThreadHandler](INFO): Thread cancelled.