2025-11-07T19:14:12.606Z,1762542852.606 [Supervisor](DEBUG): Initializing supervisor.
2025-11-07T19:14:12.611Z,1762542852.611 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-11-07T19:14:12.611Z,1762542852.611 [SyncHandler](INFO): Protected caller Thread ID is 3064
2025-11-07T19:14:12.612Z,1762542852.612 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-11-07T19:14:12.613Z,1762542852.613 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-11-07T19:14:12.613Z,1762542852.613 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3065
2025-11-07T19:14:12.617Z,1762542852.617 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-11-07T19:14:12.639Z,1762542852.639 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-11-07T19:14:12.640Z,1762542852.640 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-11-07T19:14:12.641Z,1762542852.641 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3066
2025-11-07T19:14:12.645Z,1762542852.645 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-11-07T19:14:12.646Z,1762542852.646 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-11-07T19:14:12.646Z,1762542852.646 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3067
2025-11-07T19:14:12.648Z,1762542852.648 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-11-07T19:14:12.649Z,1762542852.649 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-11-07T19:14:12.650Z,1762542852.650 [logger ThreadHandler](INFO): Protected caller Thread ID is 3068
2025-11-07T19:14:12.653Z,1762542852.653 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-11-07T19:14:12.654Z,1762542852.654 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-11-07T19:14:12.655Z,1762542852.655 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-11-07T19:14:13.133Z,1762542853.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-11-07T19:14:13.134Z,1762542853.134 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-11-07T19:14:13.234Z,1762542853.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-11-07T19:14:13.234Z,1762542853.234 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-11-07T19:14:13.351Z,1762542853.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-11-07T19:14:13.351Z,1762542853.351 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-11-07T19:14:13.493Z,1762542853.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-11-07T19:14:13.493Z,1762542853.493 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-11-07T19:14:13.846Z,1762542853.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-11-07T19:14:13.847Z,1762542853.847 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-11-07T19:14:13.953Z,1762542853.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-11-07T19:14:13.954Z,1762542853.954 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-11-07T19:14:14.354Z,1762542854.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-11-07T19:14:14.355Z,1762542854.355 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-11-07T19:14:14.584Z,1762542854.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-11-07T19:14:14.585Z,1762542854.585 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-11-07T19:14:14.674Z,1762542854.674 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-11-07T19:14:14.919Z,1762542854.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-11-07T19:14:14.920Z,1762542854.920 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-11-07T19:14:15.145Z,1762542855.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-11-07T19:14:15.145Z,1762542855.145 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-11-07T19:14:15.692Z,1762542855.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-11-07T19:14:15.692Z,1762542855.692 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-11-07T19:14:15.882Z,1762542855.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-11-07T19:14:15.883Z,1762542855.883 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-11-07T19:14:16.172Z,1762542856.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-11-07T19:14:16.173Z,1762542856.173 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-11-07T19:14:17.008Z,1762542857.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-11-07T19:14:17.009Z,1762542857.009 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-11-07T19:14:17.586Z,1762542857.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-11-07T19:14:17.588Z,1762542857.588 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-11-07T19:14:17.589Z,1762542857.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-11-07T19:14:17.694Z,1762542857.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-11-07T19:14:17.833Z,1762542857.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-11-07T19:14:17.927Z,1762542857.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-11-07T19:14:18.043Z,1762542858.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-11-07T19:14:18.146Z,1762542858.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-11-07T19:14:18.414Z,1762542858.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-11-07T19:14:18.414Z,1762542858.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-11-07T19:14:18.533Z,1762542858.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-11-07T19:14:18.640Z,1762542858.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-11-07T19:14:18.788Z,1762542858.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-11-07T19:14:18.885Z,1762542858.885 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2025-11-07T19:14:18.886Z,1762542858.886 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-11-07T19:14:18.897Z,1762542858.897 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-11-07T19:14:19.037Z,1762542859.037 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-11-07T19:14:19.038Z,1762542859.038 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-11-07T19:14:19.096Z,1762542859.096 [VerticalControl](DEBUG): Construct VerticalControl.
2025-11-07T19:14:19.155Z,1762542859.155 [VerticalControl] Loaded
2025-11-07T19:14:19.156Z,1762542859.156 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-11-07T19:14:19.159Z,1762542859.159 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-11-07T19:14:19.205Z,1762542859.205 [HorizontalControl] Loaded
2025-11-07T19:14:19.205Z,1762542859.205 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-11-07T19:14:19.207Z,1762542859.207 [SpeedControl](DEBUG): Construct SpeedControl.
2025-11-07T19:14:19.211Z,1762542859.211 [SpeedControl] Loaded
2025-11-07T19:14:19.211Z,1762542859.211 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-11-07T19:14:19.213Z,1762542859.213 [LoopControl](DEBUG): Construct LoopControl.
2025-11-07T19:14:19.214Z,1762542859.214 [LoopControl] Loaded
2025-11-07T19:14:19.214Z,1762542859.214 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-11-07T19:14:19.215Z,1762542859.215 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-11-07T19:14:19.215Z,1762542859.215 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-11-07T19:14:19.279Z,1762542859.279 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-11-07T19:14:19.280Z,1762542859.280 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-11-07T19:14:19.821Z,1762542859.821 [AHRS_M2] Loaded
2025-11-07T19:14:19.821Z,1762542859.821 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-11-07T19:14:19.860Z,1762542859.860 [BackseatComponent] Loaded
2025-11-07T19:14:19.861Z,1762542859.861 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-11-07T19:14:19.862Z,1762542859.862 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2025-11-07T19:14:19.862Z,1762542859.862 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3148
2025-11-07T19:14:19.865Z,1762542859.865 [LcmPublisher](INFO): Full slate access is ENABLED. All requested slate values (including non-universals) will be published on LCM.
2025-11-07T19:14:19.865Z,1762542859.865 [LcmPublisher] Loaded
2025-11-07T19:14:19.866Z,1762542859.866 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-11-07T19:14:20.682Z,1762542860.682 [BPC1] Loaded
2025-11-07T19:14:20.683Z,1762542860.683 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-11-07T19:14:20.780Z,1762542860.780 [DAT] Loaded
2025-11-07T19:14:20.780Z,1762542860.780 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-11-07T19:14:20.781Z,1762542860.781 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2025-11-07T19:14:20.782Z,1762542860.782 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3149
2025-11-07T19:14:20.855Z,1762542860.855 [DataOverHttps] Loaded
2025-11-07T19:14:20.855Z,1762542860.855 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-11-07T19:14:20.856Z,1762542860.856 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0
2025-11-07T19:14:20.856Z,1762542860.856 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3150
2025-11-07T19:14:20.879Z,1762542860.879 [Depth_Keller] Loaded
2025-11-07T19:14:20.879Z,1762542860.879 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-11-07T19:14:20.884Z,1762542860.884 [DropWeight] Loaded
2025-11-07T19:14:20.885Z,1762542860.885 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-11-07T19:14:20.949Z,1762542860.949 [NAL9602] Loaded
2025-11-07T19:14:20.949Z,1762542860.949 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-11-07T19:14:20.979Z,1762542860.979 [Onboard] Loaded
2025-11-07T19:14:20.979Z,1762542860.979 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-11-07T19:14:20.980Z,1762542860.980 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0
2025-11-07T19:14:20.980Z,1762542860.980 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3151
2025-11-07T19:14:20.994Z,1762542860.994 [Power24vConverter] Loaded
2025-11-07T19:14:20.995Z,1762542860.995 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-11-07T19:14:21.010Z,1762542861.010 [Radio_Surface] Loaded
2025-11-07T19:14:21.011Z,1762542861.011 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-11-07T19:14:21.012Z,1762542861.012 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0
2025-11-07T19:14:21.012Z,1762542861.012 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3152
2025-11-07T19:14:21.027Z,1762542861.027 [Sonardyne_Nano] Loaded
2025-11-07T19:14:21.027Z,1762542861.027 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-11-07T19:14:21.028Z,1762542861.028 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-11-07T19:14:21.029Z,1762542861.029 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-11-07T19:14:21.039Z,1762542861.039 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-11-07T19:14:21.040Z,1762542861.040 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-11-07T19:14:21.072Z,1762542861.072 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-11-07T19:14:21.073Z,1762542861.073 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-11-07T19:14:21.238Z,1762542861.238 [SBIT](DEBUG): Construct Startup Built In Test.
2025-11-07T19:14:21.247Z,1762542861.247 [SBIT] Loaded
2025-11-07T19:14:21.247Z,1762542861.247 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-11-07T19:14:21.250Z,1762542861.250 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-11-07T19:14:21.262Z,1762542861.262 [IBIT] Loaded
2025-11-07T19:14:21.263Z,1762542861.263 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-11-07T19:14:21.268Z,1762542861.268 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-11-07T19:14:21.346Z,1762542861.346 [CBIT] Loaded
2025-11-07T19:14:21.346Z,1762542861.346 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-11-07T19:14:21.379Z,1762542861.379 [GFScanner] Loaded
2025-11-07T19:14:21.379Z,1762542861.379 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-11-07T19:14:21.379Z,1762542861.379 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-11-07T19:14:21.380Z,1762542861.380 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-11-07T19:14:21.735Z,1762542861.735 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-11-07T19:14:21.736Z,1762542861.736 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-11-07T19:14:21.790Z,1762542861.790 [DepthRateCalculator] Loaded
2025-11-07T19:14:21.790Z,1762542861.790 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-11-07T19:14:21.795Z,1762542861.795 [PitchRateCalculator] Loaded
2025-11-07T19:14:21.796Z,1762542861.796 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-11-07T19:14:21.806Z,1762542861.806 [SpeedCalculator] Loaded
2025-11-07T19:14:21.806Z,1762542861.806 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-11-07T19:14:21.810Z,1762542861.810 [YawRateCalculator] Loaded
2025-11-07T19:14:21.811Z,1762542861.811 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-11-07T19:14:21.829Z,1762542861.829 [ElevatorOffsetCalculator] Loaded
2025-11-07T19:14:21.829Z,1762542861.829 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-11-07T19:14:21.830Z,1762542861.830 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-11-07T19:14:21.831Z,1762542861.831 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-11-07T19:14:22.018Z,1762542862.018 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-11-07T19:14:22.018Z,1762542862.018 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-11-07T19:14:22.161Z,1762542862.161 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-11-07T19:14:22.161Z,1762542862.161 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-11-07T19:14:22.175Z,1762542862.175 [NavChart] Loaded
2025-11-07T19:14:22.175Z,1762542862.175 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-11-07T19:14:22.181Z,1762542862.181 [UniversalFixResidualReporter] Loaded
2025-11-07T19:14:22.181Z,1762542862.181 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-11-07T19:14:22.182Z,1762542862.182 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-11-07T19:14:22.182Z,1762542862.182 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-11-07T19:14:22.324Z,1762542862.324 [BuoyancyServo] Loaded
2025-11-07T19:14:22.324Z,1762542862.324 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-11-07T19:14:22.347Z,1762542862.347 [ElevatorServo] Loaded
2025-11-07T19:14:22.347Z,1762542862.347 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-11-07T19:14:22.369Z,1762542862.369 [MassServo] Loaded
2025-11-07T19:14:22.369Z,1762542862.369 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-11-07T19:14:22.390Z,1762542862.390 [RudderServo] Loaded
2025-11-07T19:14:22.390Z,1762542862.390 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-11-07T19:14:22.407Z,1762542862.407 [ThrusterHE] Loaded
2025-11-07T19:14:22.407Z,1762542862.407 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-11-07T19:14:22.407Z,1762542862.407 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-11-07T19:14:22.408Z,1762542862.408 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-11-07T19:14:22.592Z,1762542862.592 [CTD_Seabird] Loaded
2025-11-07T19:14:22.592Z,1762542862.592 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-11-07T19:14:22.593Z,1762542862.593 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0
2025-11-07T19:14:22.594Z,1762542862.594 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3153
2025-11-07T19:14:22.630Z,1762542862.630 [ESPComponent] Loaded
2025-11-07T19:14:22.630Z,1762542862.630 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-11-07T19:14:22.652Z,1762542862.652 [PAR_Licor] Loaded
2025-11-07T19:14:22.652Z,1762542862.652 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-11-07T19:14:22.697Z,1762542862.697 [WetLabsBB2FL] Loaded
2025-11-07T19:14:22.698Z,1762542862.698 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-11-07T19:14:22.699Z,1762542862.699 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-11-07T19:14:22.699Z,1762542862.699 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3154
2025-11-07T19:14:22.700Z,1762542862.700 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-11-07T19:14:22.706Z,1762542862.706 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-11-07T19:14:22.709Z,1762542862.709 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-11-07T19:14:22.720Z,1762542862.720 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-11-07T19:14:22.721Z,1762542862.721 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-11-07T19:14:22.722Z,1762542862.722 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3155
2025-11-07T19:14:22.726Z,1762542862.726 [Supervisor](INFO): Main Thread ID is 3063
2025-11-07T19:14:22.726Z,1762542862.726 [Supervisor](DEBUG): Running supervisor.
2025-11-07T19:14:22.727Z,1762542862.727 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3156
2025-11-07T19:14:22.727Z,1762542862.727 [CommandExec](INFO): Initializing the command executive.
2025-11-07T19:14:22.729Z,1762542862.729 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3157
2025-11-07T19:14:22.732Z,1762542862.732 [controlThread ThreadHandler](INFO): Handler Thread ID is 3158
2025-11-07T19:14:22.733Z,1762542862.733 [controlThread](DEBUG): Initializing ControlThread
2025-11-07T19:14:22.734Z,1762542862.734 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-11-07T19:14:22.735Z,1762542862.735 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-11-07T19:14:22.736Z,1762542862.736 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-11-07T19:14:22.737Z,1762542862.737 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-11-07T19:14:22.740Z,1762542862.740 [SBIT](INFO): Initialize SBIT Component.
2025-11-07T19:14:22.740Z,1762542862.740 [SBIT](IMPORTANT): git: 2025-11-06-3-g49d7e8466
2025-11-07T19:14:22.740Z,1762542862.740 [SBIT](INFO): git hash: 49d7e8466fc027fb5ca162e83acc0be428d1c4a3
2025-11-07T19:14:22.741Z,1762542862.741 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-11-07T19:14:22.742Z,1762542862.742 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2025-11-07T19:14:22.743Z,1762542862.743 [SBIT](INFO): Beginning SBIT in 117.000000 seconds.
2025-11-07T19:14:22.743Z,1762542862.743 [IBIT](INFO): Initialize IBIT Component.
2025-11-07T19:14:22.744Z,1762542862.744 [CBIT](DEBUG): Initialize CBIT Component.
2025-11-07T19:14:22.745Z,1762542862.745 [logger ThreadHandler](INFO): Handler Thread ID is 3159
2025-11-07T19:14:22.756Z,1762542862.756 [CBIT](DEBUG): Initialized mux pins.
2025-11-07T19:14:22.756Z,1762542862.756 [CBIT](DEBUG): Initializing the watchdog timer.
2025-11-07T19:14:22.764Z,1762542862.764 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3160
2025-11-07T19:14:22.780Z,1762542862.780 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-11-07T19:14:22.780Z,1762542862.780 [CBIT](DEBUG): Initializing heartbeat.
2025-11-07T19:14:22.781Z,1762542862.781 [DAT ThreadHandler](INFO): Handler Thread ID is 3161
2025-11-07T19:14:22.782Z,1762542862.782 [DAT](INFO): Powering up
2025-11-07T19:14:22.782Z,1762542862.782 [DAT](DEBUG): Initializing DAT.
2025-11-07T19:14:22.785Z,1762542862.785 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3163
2025-11-07T19:14:22.787Z,1762542862.787 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-11-07T19:14:22.808Z,1762542862.808 [Onboard ThreadHandler](INFO): Handler Thread ID is 3164
2025-11-07T19:14:22.825Z,1762542862.825 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3165
2025-11-07T19:14:22.848Z,1762542862.848 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3166
2025-11-07T19:14:22.852Z,1762542862.852 [CBIT](DEBUG): Deactivating emergency mode.
2025-11-07T19:14:22.852Z,1762542862.852 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-11-07T19:14:22.856Z,1762542862.856 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3168
2025-11-07T19:14:22.860Z,1762542862.860 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3170
2025-11-07T19:14:22.862Z,1762542862.862 [WetLabsBB2FL](INFO): Powering up
2025-11-07T19:14:22.870Z,1762542862.870 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-11-07T19:14:22.870Z,1762542862.870 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-11-07T19:14:22.870Z,1762542862.870 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-11-07T19:14:22.870Z,1762542862.870 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-11-07T19:14:22.870Z,1762542862.870 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-11-07T19:14:22.871Z,1762542862.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-11-07T19:14:22.871Z,1762542862.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-11-07T19:14:22.871Z,1762542862.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-11-07T19:14:22.892Z,1762542862.892 [CBIT](DEBUG): Backplane powered.
2025-11-07T19:14:22.892Z,1762542862.892 [GFScanner](DEBUG): Initializing GFScanner
2025-11-07T19:14:22.892Z,1762542862.892 [GFScanner](DEBUG): Deactivating GF circuits.
2025-11-07T19:14:22.897Z,1762542862.897 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-11-07T19:14:22.897Z,1762542862.897 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-11-07T19:14:22.897Z,1762542862.897 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-11-07T19:14:22.898Z,1762542862.898 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-11-07T19:14:22.898Z,1762542862.898 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-11-07T19:14:22.899Z,1762542862.899 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-11-07T19:14:22.900Z,1762542862.900 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-11-07T19:14:22.905Z,1762542862.905 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-11-07T19:14:22.905Z,1762542862.905 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-11-07T19:14:22.905Z,1762542862.905 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-11-07T19:14:22.907Z,1762542862.907 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-11-07T19:14:22.970Z,1762542862.970 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-11-07T19:14:22.975Z,1762542862.975 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-11-07T19:14:23.037Z,1762542863.037 [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-11-07T19:14:23.038Z,1762542863.038 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-11-07T19:14:23.038Z,1762542863.038 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-11-07T19:14:23.039Z,1762542863.039 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-11-07T19:14:23.232Z,1762542863.232 [Radio_Surface](INFO): Powering up
2025-11-07T19:14:23.378Z,1762542863.378 [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-11-07T19:14:23.404Z,1762542863.404 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-11-07T19:14:23.405Z,1762542863.405 [Default:A.Wait](DEBUG): Construct Wait.
2025-11-07T19:14:23.416Z,1762542863.416 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-11-07T19:14:23.458Z,1762542863.458 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-11-07T19:14:23.461Z,1762542863.461 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-11-07T19:14:23.483Z,1762542863.483 [Default:E.Execute](DEBUG): Construct Execute.
2025-11-07T19:14:23.486Z,1762542863.486 [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-11-07T19:14:23.491Z,1762542863.491 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2025-11-07T19:14:23.513Z,1762542863.513 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-11-07T19:14:23.584Z,1762542863.584 [Depth_Keller](INFO): Initializing.
2025-11-07T19:14:23.586Z,1762542863.586 [Power24vConverter](INFO): Powering up.
2025-11-07T19:14:23.586Z,1762542863.586 [Sonardyne_Nano](INFO): Initializing.
2025-11-07T19:14:23.628Z,1762542863.628 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-11-07T19:14:23.672Z,1762542863.672 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-11-07T19:14:23.680Z,1762542863.680 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-11-07T19:14:23.681Z,1762542863.681 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-11-07T19:14:23.688Z,1762542863.688 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-11-07T19:14:23.689Z,1762542863.689 [MassServo](DEBUG): Initializing EZServoServo.
2025-11-07T19:14:23.696Z,1762542863.696 [MassServo](DEBUG): Initializing MassServo.
2025-11-07T19:14:23.697Z,1762542863.697 [RudderServo](DEBUG): Initializing EZServoServo.
2025-11-07T19:14:23.704Z,1762542863.704 [RudderServo](DEBUG): Initializing RudderServo.
2025-11-07T19:14:23.705Z,1762542863.705 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-11-07T19:14:23.712Z,1762542863.712 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-11-07T19:14:23.938Z,1762542863.938 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-11-07T19:14:23.938Z,1762542863.938 [DropWeight] Hardware Fault, FailCount= 1
2025-11-07T19:14:23.938Z,1762542863.938 [DropWeight](ERROR): Hardware Fault
2025-11-07T19:14:23.981Z,1762542863.981 [CommandExec](FAULT): Scheduling is paused
2025-11-07T19:14:23.981Z,1762542863.981 [CBIT](INFO): Critical error at 20251107T191423
2025-11-07T19:14:23.981Z,1762542863.981 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-11-07T19:14:23.988Z,1762542863.988 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-11-07T19:14:23.989Z,1762542863.989 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-11-07T19:14:24.327Z,1762542864.327 [CBIT](INFO): Critical error at 20251107T191423
2025-11-07T19:14:24.968Z,1762542864.968 [WetLabsBB2FL](INFO): Powering down
2025-11-07T19:14:25.518Z,1762542865.518 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-11-07T19:14:27.562Z,1762542867.562 [Sonardyne_Nano](FAULT): Failed hardware self-test:>CKHW:FAIL;LBK
2025-11-07T19:14:27.562Z,1762542867.562 [Sonardyne_Nano] Hardware Fault, FailCount= 1
2025-11-07T19:14:27.562Z,1762542867.562 [Sonardyne_Nano](ERROR): Hardware Fault
2025-11-07T19:14:27.676Z,1762542867.676 [CBIT](ERROR): Hardware Fault in component: Sonardyne_Nano
2025-11-07T19:14:28.033Z,1762542868.033 [Sonardyne_Nano](INFO): Powering down
2025-11-07T19:14:28.895Z,1762542868.895 [CBIT](INFO): Clearing failed state for component Sonardyne_Nano
2025-11-07T19:14:28.895Z,1762542868.895 [Sonardyne_Nano] No Fault, FailCount= 1
2025-11-07T19:14:29.205Z,1762542869.205 [Sonardyne_Nano](INFO): Initializing.
2025-11-07T19:14:29.465Z,1762542869.465 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-11-07T19:14:31.226Z,1762542871.226 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-11-07T19:14:32.421Z,1762542872.421 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-11-07T19:14:35.381Z,1762542875.381 [DAT](INFO): DAT read:
2025-11-07T19:14:35.382Z,1762542875.382 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-11-07T19:14:36.766Z,1762542876.766 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004499
2025-11-07T19:14:37.145Z,1762542877.145 [DAT](INFO): DAT read: MF Frequency Band
2025-11-07T19:14:37.146Z,1762542877.146 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-11-07T19:14:37.147Z,1762542877.147 [DAT](INFO): DAT read: Nov 7 2025 03:02:41
2025-11-07T19:14:38.153Z,1762542878.153 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-11-07T19:14:38.154Z,1762542878.154 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-11-07T19:14:38.155Z,1762542878.155 [DAT](INFO): commRate: 800
2025-11-07T19:14:40.220Z,1762542880.220 [DAT](INFO): entering command mode
2025-11-07T19:14:40.420Z,1762542880.420 [DAT](INFO): DAT read:
2025-11-07T19:14:40.421Z,1762542880.421 [DAT](INFO): DAT read: user:1>
2025-11-07T19:14:40.421Z,1762542880.421 [DAT](INFO): setting verbose to 3
2025-11-07T19:14:40.673Z,1762542880.673 [DAT](INFO): DAT read: user:1>
2025-11-07T19:14:40.674Z,1762542880.674 [DAT](INFO): DAT read: Verbose | 3
2025-11-07T19:14:40.674Z,1762542880.674 [DAT](INFO): set verbose to 3
2025-11-07T19:14:40.674Z,1762542880.674 [DAT](INFO): setting DatVerbose to 27440
2025-11-07T19:14:40.925Z,1762542880.925 [DAT](INFO): DAT read: user:2>
2025-11-07T19:14:40.926Z,1762542880.926 [DAT](INFO): DAT read: DatVerbose | 27440
2025-11-07T19:14:40.926Z,1762542880.926 [DAT](INFO): set DatVerbose to 27440
2025-11-07T19:14:40.927Z,1762542880.927 [DAT](INFO): setting transmit power to 8
2025-11-07T19:14:41.177Z,1762542881.177 [DAT](INFO): DAT read: user:3>
2025-11-07T19:14:41.178Z,1762542881.178 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-11-07T19:14:41.178Z,1762542881.178 [DAT](INFO): set transmit power to 8
2025-11-07T19:14:41.178Z,1762542881.178 [DAT](INFO): setting local address to 1
2025-11-07T19:14:41.429Z,1762542881.429 [DAT](INFO): DAT read: user:4>
2025-11-07T19:14:41.430Z,1762542881.430 [DAT](INFO): DAT read: LocalAddr | 1
2025-11-07T19:14:41.430Z,1762542881.430 [DAT](INFO): set local address to 1
2025-11-07T19:14:41.431Z,1762542881.431 [DAT](INFO): Setting time to: 19:14:41 And date to:11/7/2025
2025-11-07T19:14:41.681Z,1762542881.681 [DAT](INFO): DAT read: user:5>
2025-11-07T19:14:41.682Z,1762542881.682 [DAT](INFO): DAT read: Fri Nov 7, 2025 19:14:41
2025-11-07T19:14:41.682Z,1762542881.682 [DAT](INFO): Local DAT time set to Fri Nov 7, 2025 19:14:41
2025-11-07T19:14:50.991Z,1762542890.991 [NAL9602](INFO): Powering up NAL9602
2025-11-07T19:15:01.902Z,1762542901.902 [NAL9602](INFO): NAL9602 initialized
2025-11-07T19:15:30.581Z,1762542930.581 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:15:44.314Z,1762542944.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:15:57.645Z,1762542957.645 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:16:10.978Z,1762542970.978 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:16:20.316Z,1762542980.316 [SBIT](IMPORTANT): Beginning Startup BIT
2025-11-07T19:16:20.325Z,1762542980.325 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-11-07T19:16:24.844Z,1762542984.844 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000502
CHAN A1 (24V): 0.000451
CHAN A2 (12V): 0.000063
CHAN A3 (5V): -0.001971
CHAN B0 (3.3V): 0.000235
CHAN B1 (3.15aV): 0.000101
CHAN B2 (3.15bV): -0.000248
CHAN B3 (GND): -0.000439
OPEN: 0.006654
Full Scale: +/- 1 mA
2025-11-07T19:16:33.402Z,1762542993.402 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.741956
2025-11-07T19:16:34.968Z,1762542994.968 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2025-11-07T19:16:34.972Z,1762542994.972 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2025-11-07T19:16:34.973Z,1762542994.973 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121 degree
2025-11-07T19:16:34.976Z,1762542994.976 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2025-11-07T19:16:34.977Z,1762542994.977 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1762542858second
2025-11-07T19:16:34.977Z,1762542994.977 [CommandExec](FAULT): Incomplete syntax. Try: help set
2025-11-07T19:16:35.551Z,1762542995.551 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,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,LcmPublisher,Reporter,LogSplitter,
2025-11-07T19:16:54.751Z,1762543014.751 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2025-11-07T19:16:54.752Z,1762543014.752 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121 degree
2025-11-07T19:16:54.752Z,1762543014.752 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1762542858second
2025-11-07T19:16:54.753Z,1762543014.753 [CommandExec](FAULT): Incomplete syntax. Try: help set
2025-11-07T19:17:14.056Z,1762543034.056 [SBIT](CRITICAL): SBIT FAILED
2025-11-07T19:17:14.057Z,1762543034.057 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-11-07T19:17:14.057Z,1762543034.057 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=19 count;
2025-11-07T19:17:14.057Z,1762543034.057 [SBIT](IMPORTANT): BackseatComponent.fullSlateAccess=1 bool;
2025-11-07T19:17:14.057Z,1762543034.057 [SBIT](IMPORTANT): DAT.surfaceThreshold=0.01 meter;
2025-11-07T19:17:14.058Z,1762543034.058 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-11-07T19:17:14.058Z,1762543034.058 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second;
2025-11-07T19:17:14.058Z,1762543034.058 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second;
2025-11-07T19:17:14.058Z,1762543034.058 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=162.414241 cubic_centimeter;
2025-11-07T19:17:14.058Z,1762543034.058 [SBIT](IMPORTANT): VerticalControl.massDefault=24.263883 millimeter;
2025-11-07T19:17:14.058Z,1762543034.058 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;
2025-11-07T19:17:14.060Z,1762543034.060 [CBIT](INFO): Critical error at 20251107T191714
2025-11-07T19:17:14.460Z,1762543034.460 [MissionManager](IMPORTANT): Started mission Startup
2025-11-07T19:17:14.461Z,1762543034.461 [Startup] Running Loop=1
2025-11-07T19:17:14.461Z,1762543034.461 [Startup](DEBUG): Aggregate::initialize Startup
2025-11-07T19:17:14.461Z,1762543034.461 [Startup:A.GoToSurface] Running Loop=1
2025-11-07T19:17:14.461Z,1762543034.461 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-11-07T19:17:14.462Z,1762543034.462 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-11-07T19:17:14.462Z,1762543034.462 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-11-07T19:17:14.462Z,1762543034.462 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-11-07T19:17:14.463Z,1762543034.463 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-11-07T19:17:14.463Z,1762543034.463 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-11-07T19:17:14.463Z,1762543034.463 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-11-07T19:17:14.465Z,1762543034.465 [Startup:StartupSatComms] Running Loop=1
2025-11-07T19:17:14.465Z,1762543034.465 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-11-07T19:17:14.465Z,1762543034.465 [Startup:StartupSatComms:A] Running Loop=1
2025-11-07T19:17:14.857Z,1762543034.857 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-11-07T19:17:14.857Z,1762543034.857 [Startup:StartupSatComms:A] Stopped
2025-11-07T19:17:14.857Z,1762543034.857 [Startup:StartupSatComms:B] Running Loop=1
2025-11-07T19:17:15.255Z,1762543035.255 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-11-07T19:17:18.904Z,1762543038.904 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1762543014second
2025-11-07T19:17:18.904Z,1762543038.904 [CommandExec](FAULT): Incomplete syntax. Try: help set
2025-11-07T19:17:22.551Z,1762543042.551 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251106T211139/Courier0100.lzma
2025-11-07T19:17:23.554Z,1762543043.554 [DataOverHttps](INFO): Moved sent file to Logs/20251106T211139/Courier0100.lzma.bak
2025-11-07T19:17:23.554Z,1762543043.554 [DataOverHttps](INFO): SBD MOMSN=26292325
2025-11-07T19:17:40.216Z,1762543060.216 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20251107T191412/Courier0000.lzma
2025-11-07T19:17:41.218Z,1762543061.218 [DataOverHttps](INFO): Moved sent file to Logs/20251107T191412/Courier0000.lzma.bak
2025-11-07T19:17:41.218Z,1762543061.218 [DataOverHttps](INFO): SBD MOMSN=26292327
2025-11-07T19:17:50.278Z,1762543070.278 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1762543014second
2025-11-07T19:17:50.278Z,1762543070.278 [CommandExec](FAULT): Incomplete syntax. Try: help set
2025-11-07T19:17:57.849Z,1762543077.849 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20251106T211139/Express0101.lzma
2025-11-07T19:17:58.850Z,1762543078.850 [DataOverHttps](INFO): Moved sent file to Logs/20251106T211139/Express0101.lzma.bak
2025-11-07T19:17:58.850Z,1762543078.850 [DataOverHttps](INFO): SBD MOMSN=26292333
2025-11-07T19:18:14.865Z,1762543094.865 [DataOverHttps](INFO): Sending 1278 bytes from file Logs/20251107T191412/Express0001.lzma
2025-11-07T19:18:15.232Z,1762543095.232 [Startup:StartupSatComms:B](INFO): Timed out from 2025-11-07T19:17:14.9Z
2025-11-07T19:18:15.232Z,1762543095.232 [Startup:StartupSatComms:B] Stopped
2025-11-07T19:18:15.233Z,1762543095.233 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-11-07T19:18:15.233Z,1762543095.233 [Startup:StartupSatComms] Stopped
2025-11-07T19:18:15.233Z,1762543095.233 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-11-07T19:18:15.234Z,1762543095.234 [Startup](INFO): Completed Startup
2025-11-07T19:18:15.234Z,1762543095.234 [MissionManager](INFO): Startup is completed.
2025-11-07T19:18:15.234Z,1762543095.234 [MissionManager](INFO): Uninitializing Mission Startup
2025-11-07T19:18:15.234Z,1762543095.234 [Startup] Stopped
2025-11-07T19:18:15.234Z,1762543095.234 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-11-07T19:18:15.234Z,1762543095.234 [Startup:A.GoToSurface] Stopped
2025-11-07T19:18:15.234Z,1762543095.234 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-11-07T19:18:15.637Z,1762543095.637 [MissionManager](IMPORTANT): Started mission Default
2025-11-07T19:18:15.637Z,1762543095.637 [Default] Running Loop=1
2025-11-07T19:18:15.637Z,1762543095.637 [Default](DEBUG): Aggregate::initialize Default
2025-11-07T19:18:15.637Z,1762543095.637 [Default:B.GoToSurface] Running Loop=1
2025-11-07T19:18:15.637Z,1762543095.637 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-11-07T19:18:15.638Z,1762543095.638 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-11-07T19:18:15.638Z,1762543095.638 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-11-07T19:18:15.638Z,1762543095.638 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-11-07T19:18:15.638Z,1762543095.638 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-11-07T19:18:15.639Z,1762543095.639 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-11-07T19:18:15.639Z,1762543095.639 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-11-07T19:18:15.639Z,1762543095.639 [Default:A.Wait] Running Loop=1
2025-11-07T19:18:15.639Z,1762543095.639 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-11-07T19:18:15.866Z,1762543095.866 [DataOverHttps](INFO): Moved sent file to Logs/20251107T191412/Express0001.lzma.bak
2025-11-07T19:18:15.866Z,1762543095.866 [DataOverHttps](INFO): SBD MOMSN=26292337
2025-11-07T19:18:28.989Z,1762543108.989 [Default:A.Wait](INFO): Done Waiting.
2025-11-07T19:18:28.989Z,1762543108.989 [Default:A.Wait] Stopped
2025-11-07T19:18:28.989Z,1762543108.989 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-11-07T19:18:29.461Z,1762543109.461 [Default:CheckIn] Running Loop=1
2025-11-07T19:18:29.461Z,1762543109.461 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-11-07T19:18:29.461Z,1762543109.461 [Default:CheckIn:Read_GPS] Running Loop=1
2025-11-07T19:18:29.821Z,1762543109.821 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-11-07T19:18:41.070Z,1762543121.070 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:18:48.346Z,1762543128.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:19:03.291Z,1762543143.291 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:19:18.642Z,1762543158.642 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:19:34.000Z,1762543174.000 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:19:48.942Z,1762543188.942 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:20:04.302Z,1762543204.302 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:20:04.777Z,1762543204.777 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-11-07T19:20:20.055Z,1762543220.055 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:20:35.005Z,1762543235.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:20:50.356Z,1762543250.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-11-07T19:21:43.693Z,1762543303.693 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-11-07T19:21:43.694Z,1762543303.694 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-11-07T19:21:43.694Z,1762543303.694 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-11-07T19:21:43.695Z,1762543303.695 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-11-07T19:21:43.696Z,1762543303.696 [BPC1](ERROR): Battery stick #50 (s/n: 019C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-11-07T19:21:43.697Z,1762543303.697 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-11-07T19:21:43.698Z,1762543303.698 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-11-07T19:21:43.698Z,1762543303.698 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x5AD0.
2025-11-07T19:21:43.699Z,1762543303.699 [BPC1](INFO): Calculating totals. Valid battery stick count: 38. Valid reserve battery stick count: 4.
2025-11-07T19:21:43.702Z,1762543303.702 [BPC1](INFO): Received data from all battery sticks.
2025-11-07T19:23:29.542Z,1762543409.542 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-11-07T19:18:29.5Z
2025-11-07T19:23:29.542Z,1762543409.542 [Default:CheckIn:Read_GPS] Stopped
2025-11-07T19:23:29.542Z,1762543409.542 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-11-07T19:23:29.949Z,1762543409.949 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-11-07T19:23:36.451Z,1762543416.451 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20251107T191412/Courier0004.lzma
2025-11-07T19:23:37.454Z,1762543417.454 [DataOverHttps](INFO): Moved sent file to Logs/20251107T191412/Courier0004.lzma.bak
2025-11-07T19:23:37.454Z,1762543417.454 [DataOverHttps](INFO): SBD MOMSN=26292369
2025-11-07T19:23:53.136Z,1762543433.136 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20251107T191412/Express0005.lzma
2025-11-07T19:23:54.138Z,1762543434.138 [DataOverHttps](INFO): Moved sent file to Logs/20251107T191412/Express0005.lzma.bak
2025-11-07T19:23:54.138Z,1762543434.138 [DataOverHttps](INFO): SBD MOMSN=26292371
2025-11-07T19:23:55.410Z,1762543435.410 [Default:CheckIn:Read_Iridium] Stopped
2025-11-07T19:23:55.411Z,1762543435.411 [Default:CheckIn:C.Wait] Running Loop=1
2025-11-07T19:23:55.411Z,1762543435.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-11-07T19:24:01.044Z,1762543441.044 [NAL9602](INFO): Not Powering down - fast GPS
2025-11-07T19:25:40.371Z,1762543540.371 [CommandExec](IMPORTANT): got command run ./Missions/marl_lab.tl
2025-11-07T19:25:40.371Z,1762543540.371 [MissionManager](INFO): Loading Mission from file: ./Missions/marl_lab.tl
2025-11-07T19:25:40.371Z,1762543540.371 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/marl_lab.tl
2025-11-07T19:25:40.373Z,1762543540.373 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/marl_lab.tl
2025-11-07T19:25:40.928Z,1762543540.928 [MissionManager](DEBUG): TethyslAPI: ./Missions/marl_lab.tl translated into:
1
8
10
NaN
20
10
1
1
NaN
0
0
Got test_good :
and temp var is
modem
_.others_observations
none_str
SendData: sent send_observations :
to nearby vehicles. Elapsed time
Upadating ContactLabel
2025-11-07T19:25:40.938Z,1762543540.938 [MissionManager](INFO): DefineArg marl_lab.MissionTimeout = 1.000000 h
2025-11-07T19:25:40.941Z,1762543540.941 [MissionManager](INFO): DefineArg marl_lab.ContactLabel = 8.000000 count
2025-11-07T19:25:40.945Z,1762543540.945 [MissionManager](INFO): DefineArg marl_lab.SendDataLabel = 10.000000 count
2025-11-07T19:25:40.947Z,1762543540.947 [MissionManager](INFO): DefineArg marl_lab.ContactDepth = nan m
2025-11-07T19:25:40.950Z,1762543540.950 [MissionManager](INFO): DefineArg marl_lab.SendObservationDataInterval = 20.000000 s
2025-11-07T19:25:40.953Z,1762543540.953 [MissionManager](INFO): DefineArg marl_lab.TrackingUpdatePeriod = 10.000000 min
2025-11-07T19:25:40.956Z,1762543540.956 [MissionManager](INFO): DefineArg marl_lab.NumberOfPings = 1.000000 count
2025-11-07T19:25:40.959Z,1762543540.959 [MissionManager](INFO): DefineArg marl_lab.AcousticContactTimeout = 1.000000 h
2025-11-07T19:25:40.962Z,1762543540.962 [MissionManager](INFO): DefineArg marl_lab.MyTempVar = nan n/a str
2025-11-07T19:25:40.965Z,1762543540.965 [MissionManager](INFO): DefineOutput marl_lab.TimeLastSendData = 0.000000 s
2025-11-07T19:25:40.969Z,1762543540.969 [MissionManager](INFO): DefineOutput marl_lab.ElapsedSinceLastSendData = 0.000000 s
2025-11-07T19:25:40.969Z,1762543540.969 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/AbortDrift.tl
2025-11-07T19:25:40.969Z,1762543540.969 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/AbortDrift.tl
2025-11-07T19:25:40.970Z,1762543540.970 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/AbortDrift.tl
2025-11-07T19:25:41.098Z,1762543541.098 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/AbortDrift.tl translated into:
96
Insert acoustic timeout set to
.
stop
2025-11-07T19:25:41.116Z,1762543541.116 [MissionManager](INFO): DefineArg marl_lab:AbortDrift.AcousticTimeout = 96.000000 h
2025-11-07T19:25:41.119Z,1762543541.119 [marl_lab:AbortDrift:B.AbortDrift](DEBUG): Construct AbortDrift.
2025-11-07T19:25:41.121Z,1762543541.121 [marl_lab:AbortDrift:C.Execute](DEBUG): Construct Execute.
2025-11-07T19:25:41.123Z,1762543541.123 [MissionManager](INFO): RedefineArg marl_lab:AbortDrift.AcousticTimeout = reader:marl_lab.AcousticContactTimeout
2025-11-07T19:25:41.369Z,1762543541.369 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-11-07T19:25:41.369Z,1762543541.369 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-11-07T19:25:41.370Z,1762543541.370 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-11-07T19:25:41.415Z,1762543541.415 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-11-07T19:25:41.423Z,1762543541.423 [MissionManager](INFO): DefineArg marl_lab:BackseatDriver.EnableBackseat = 0 bool
2025-11-07T19:25:41.437Z,1762543541.437 [marl_lab:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-11-07T19:25:41.441Z,1762543541.441 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-11-07T19:25:41.442Z,1762543541.442 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-11-07T19:25:41.443Z,1762543541.443 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-11-07T19:25:41.721Z,1762543541.721 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-11-07T19:25:41.729Z,1762543541.729 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleAll = 0 bool
2025-11-07T19:25:41.736Z,1762543541.736 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad1 = 0 bool
2025-11-07T19:25:41.739Z,1762543541.739 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad2 = 0 bool
2025-11-07T19:25:41.755Z,1762543541.755 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.SampleLoad3 = 0 bool
2025-11-07T19:25:41.759Z,1762543541.759 [MissionManager](INFO): DefineArg marl_lab:PowerOnly.EnabledPowerOnly = 0 bool
2025-11-07T19:25:41.775Z,1762543541.775 [marl_lab:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-11-07T19:25:41.789Z,1762543541.789 [marl_lab:H.Wait](DEBUG): Construct Wait.
2025-11-07T19:25:41.809Z,1762543541.809 [MissionManager](DEBUG):
mission marl_lab {
"""
Lab test mission for marl3 acoustic communications.
Tests acoustic tracking and send observation data without driving.
"""
arguments {
MissionTimeout = 1 hour
"""
Maximum duration of mission
"""
ContactLabel = 8 count
"""
The acoustic address of the asset to be tracked.
tethys = 6
brizo = 11
daphne = 8
galene = 9
makai = 5
pontus = 10
triton = 12
"""
SendDataLabel = 10 count
"""
The acoustic address of the asset to send observation data to.
10 is pontus
"""
ContactDepth = NaN meter
"""
The asset's depth to be tracked
"""
SendObservationDataInterval = 20 second
"""
How often to send back some a-comms data
"""
TrackingUpdatePeriod = 10 minute
"""
How long to wait between acoustic queries
"""
NumberOfPings = 1 count
"""
Number of return pings to request with each acoustic query (more than 1
will activate oneway mode)
"""
AcousticContactTimeout = 1 hour
"""
If the vehicle does not receive an acoustic signal for more than this
length of time, it will surface for communications with shore.
"""
MyTempVar = NaN none_str
"""
A custom variable from the backseat driver interface.
"""
}
output {
TimeLastSendData = 0 second
ElapsedSinceLastSendData = 0 second
"""
Time elapsed since last Send Data fix.
"""
}
timeout duration=MissionTimeout
insert Insert/AbortDrift.tl {
redefineArg AcousticTimeout = AcousticContactTimeout
}
behavior Estimation:TrackAcousticContact {
run in parallel
set contactDepthSetting = ContactDepth
set contactLabelSetting = ContactLabel
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
aggregate SendObservationData {
run when (
elapsed ( MyTempVar ) >= SendObservationDataInterval
)
syslog info "Got test_good : " + customUri "_.send_observations"~none_str + " and temp var is "
+ MyTempVar~none_str
assign in sequence ElapsedSinceLastSendData = elapsed ( MyTempVar )
assign in sequence MyTempVar = customUri "_.send_observations"
behavior Sensor:SendDirect {
run in parallel
set destType = "modem"
set destId = SendDataLabel
set destName = "_.others_observations"
set value = MyTempVar
set unit = "none_str"
}
syslog info "SendData: sent send_observations : " + MyTempVar~none_str
+ " to nearby vehicles. Elapsed time " + ElapsedSinceLastSendData~minute
}
aggregate UpdateContactLabel {
run when (
elapsed ( customUri "_.contactLabelSetting" ) < ( elapsed ( ContactLabel ) )
)
assign in sequence ContactLabel = abs ( customUri "_.contactLabelSetting" )
syslog info "Upadating ContactLabel " + customUri "_.contactLabelSetting"~count
}
insert Insert/BackseatDriver.tl
assign in sequence BackseatDriver:EnableBackseat = true
insert Insert/PowerOnly.tl
behavior Guidance:Wait {
run in sequence
set duration = MissionTimeout
}
}
2025-11-07T19:25:41.811Z,1762543541.811 [CommandExec](IMPORTANT): Running ./Missions/marl_lab.tl
2025-11-07T19:25:42.060Z,1762543542.060 [Default] Stopped
2025-11-07T19:25:42.061Z,1762543542.061 [Default](DEBUG): Aggregate::uninitialize Default
2025-11-07T19:25:42.061Z,1762543542.061 [Default:B.GoToSurface] Stopped
2025-11-07T19:25:42.061Z,1762543542.061 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-11-07T19:25:42.061Z,1762543542.061 [Default:CheckIn] Stopped
2025-11-07T19:25:42.061Z,1762543542.061 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-11-07T19:25:42.061Z,1762543542.061 [Default:CheckIn:C.Wait] Stopped
2025-11-07T19:25:42.061Z,1762543542.061 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-11-07T19:25:42.061Z,1762543542.061 [MissionManager](IMPORTANT): Started mission marl_lab
2025-11-07T19:25:42.061Z,1762543542.061 [marl_lab] Running Loop=1
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab](DEBUG): Aggregate::initialize marl_lab
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:AbortDrift] Running Loop=1
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:AbortDrift](DEBUG): Aggregate::initialize marl_lab:AbortDrift
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:AbortDrift:A] Running Loop=1
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:B.] Running Loop=1
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:B.](INFO): Initializing TrackAcousticContact.
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:BackseatDriver] Running Loop=1
2025-11-07T19:25:42.062Z,1762543542.062 [marl_lab:BackseatDriver](DEBUG): Aggregate::initialize marl_lab:BackseatDriver
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly](DEBUG): Aggregate::initialize marl_lab:PowerOnly
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly:A] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly:B] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly:C] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly:D] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly:E.Wait] Running Loop=1
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-11-07T19:25:42.063Z,1762543542.063 [marl_lab:F] Running Loop=1
2025-11-07T19:25:42.084Z,1762543542.084 [marl_lab:PowerOnly] Running Loop=1
2025-11-07T19:25:42.084Z,1762543542.084 [marl_lab:PowerOnly:D] Stopped
2025-11-07T19:25:42.084Z,1762543542.084 [marl_lab:PowerOnly:C] Stopped
2025-11-07T19:25:42.085Z,1762543542.085 [marl_lab:PowerOnly:B] Stopped
2025-11-07T19:25:42.085Z,1762543542.085 [marl_lab:PowerOnly:A] Stopped
2025-11-07T19:25:42.085Z,1762543542.085 [marl_lab:F] Stopped
2025-11-07T19:25:42.085Z,1762543542.085 [marl_lab:H.Wait] Running Loop=1
2025-11-07T19:25:42.085Z,1762543542.085 [marl_lab:H.Wait](DEBUG): Initialize Wait Component.
2025-11-07T19:25:42.085Z,1762543542.085 [marl_lab:BackseatDriver] Running Loop=1
2025-11-07T19:25:42.086Z,1762543542.086 [marl_lab:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-11-07T19:25:42.164Z,1762543542.164 [marl_lab:B.] Running Loop=1
2025-11-07T19:25:42.165Z,1762543542.165 [marl_lab:AbortDrift] Running Loop=1
2025-11-07T19:25:42.166Z,1762543542.166 [marl_lab:AbortDrift:A](INFO): Insert acoustic timeout set to 1.000000 h .
2025-11-07T19:25:42.166Z,1762543542.166 [marl_lab:AbortDrift:A] Stopped
2025-11-07T19:25:42.166Z,1762543542.166 [marl_lab:AbortDrift:B.AbortDrift] Running Loop=1
2025-11-07T19:25:42.166Z,1762543542.166 [marl_lab:AbortDrift:B.AbortDrift](INFO): Initializing AbortDrift.
2025-11-07T19:25:42.166Z,1762543542.166 [marl_lab:AbortDrift:B.AbortDrift](IMPORTANT): Acoustic timeout set to: 1.00 hours.
2025-11-07T19:25:42.488Z,1762543542.488 [BackseatComponent](INFO): Powering up
2025-11-07T19:25:42.489Z,1762543542.489 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-11-07T19:25:45.685Z,1762543545.685 [CTD_Seabird](ERROR): Failed to parse device response:
2025-11-07T19:25:47.695Z,1762543547.695 [CTD_Seabird](ERROR): Salinity reading out of range: 9.173037 psu
2025-11-07T19:25:47.697Z,1762543547.697 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:48.701Z,1762543548.701 [CTD_Seabird](ERROR): Salinity reading out of range: 0.750552 psu
2025-11-07T19:25:48.702Z,1762543548.702 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:49.702Z,1762543549.702 [CTD_Seabird](ERROR): Salinity reading out of range: 7.256938 psu
2025-11-07T19:25:49.703Z,1762543549.703 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:50.706Z,1762543550.706 [CTD_Seabird](ERROR): Salinity reading out of range: 1.896179 psu
2025-11-07T19:25:50.707Z,1762543550.707 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:51.367Z,1762543551.367 [marl_lab:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.send_observations with code: 2009
2025-11-07T19:25:51.710Z,1762543551.710 [CTD_Seabird](ERROR): Salinity reading out of range: 7.107126 psu
2025-11-07T19:25:51.712Z,1762543551.712 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:52.714Z,1762543552.714 [CTD_Seabird](ERROR): Salinity reading out of range: 1.771233 psu
2025-11-07T19:25:52.715Z,1762543552.715 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:53.718Z,1762543553.718 [CTD_Seabird](ERROR): Salinity reading out of range: 6.800987 psu
2025-11-07T19:25:53.719Z,1762543553.719 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:54.722Z,1762543554.722 [CTD_Seabird](ERROR): Salinity reading out of range: 1.742813 psu
2025-11-07T19:25:54.723Z,1762543554.723 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:55.726Z,1762543555.726 [CTD_Seabird](ERROR): Salinity reading out of range: 6.992853 psu
2025-11-07T19:25:55.727Z,1762543555.727 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:56.730Z,1762543556.730 [CTD_Seabird](ERROR): Salinity reading out of range: 1.946901 psu
2025-11-07T19:25:56.732Z,1762543556.732 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:57.734Z,1762543557.734 [CTD_Seabird](ERROR): Salinity reading out of range: 7.541580 psu
2025-11-07T19:25:57.735Z,1762543557.735 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:58.738Z,1762543558.738 [CTD_Seabird](ERROR): Salinity reading out of range: 2.528673 psu
2025-11-07T19:25:58.739Z,1762543558.739 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:25:59.742Z,1762543559.742 [CTD_Seabird](ERROR): Salinity reading out of range: 1.314246 psu
2025-11-07T19:25:59.743Z,1762543559.743 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:00.746Z,1762543560.746 [CTD_Seabird](ERROR): Salinity reading out of range: 7.552817 psu
2025-11-07T19:26:00.747Z,1762543560.747 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:01.064Z,1762543561.064 [marl_lab:SendObservationData] Running Loop=1
2025-11-07T19:26:01.064Z,1762543561.064 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData
2025-11-07T19:26:01.064Z,1762543561.064 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1
2025-11-07T19:26:01.065Z,1762543561.065 [marl_lab:SendObservationData:A] Running Loop=1
2025-11-07T19:26:01.065Z,1762543561.065 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1
2025-11-07T19:26:01.067Z,1762543561.067 [marl_lab:SendObservationData:A](INFO): Got test_good : test_msg_4 n/a str and temp var is nan n/a str
2025-11-07T19:26:01.067Z,1762543561.067 [marl_lab:SendObservationData:A] Stopped
2025-11-07T19:26:01.067Z,1762543561.067 [marl_lab:SendObservationData:B] Running Loop=1
2025-11-07T19:26:01.481Z,1762543561.481 [marl_lab:SendObservationData:B] Stopped
2025-11-07T19:26:01.481Z,1762543561.481 [marl_lab:SendObservationData:C] Running Loop=1
2025-11-07T19:26:01.750Z,1762543561.750 [CTD_Seabird](ERROR): Salinity reading out of range: 2.315943 psu
2025-11-07T19:26:01.751Z,1762543561.751 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:01.869Z,1762543561.869 [marl_lab:SendObservationData:C] Stopped
2025-11-07T19:26:01.869Z,1762543561.869 [marl_lab:SendObservationData:E] Running Loop=1
2025-11-07T19:26:02.273Z,1762543562.273 [marl_lab:SendObservationData:E](INFO): SendData: sent send_observations : nan n/a str to nearby vehicles. Elapsed time 0.341983 min
2025-11-07T19:26:02.273Z,1762543562.273 [marl_lab:SendObservationData:E] Stopped
2025-11-07T19:26:02.273Z,1762543562.273 [marl_lab:SendObservationData](INFO): Completed marl_lab:SendObservationData
2025-11-07T19:26:02.273Z,1762543562.273 [marl_lab:SendObservationData] Stopped
2025-11-07T19:26:02.274Z,1762543562.274 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData
2025-11-07T19:26:02.274Z,1762543562.274 [marl_lab:SendObservationData:D.SendDirect] Stopped
2025-11-07T19:26:02.754Z,1762543562.754 [CTD_Seabird](ERROR): Salinity reading out of range: 1.240759 psu
2025-11-07T19:26:02.755Z,1762543562.755 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:03.758Z,1762543563.758 [CTD_Seabird](ERROR): Salinity reading out of range: 7.221950 psu
2025-11-07T19:26:03.759Z,1762543563.759 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:04.762Z,1762543564.762 [CTD_Seabird](ERROR): Salinity reading out of range: 1.936031 psu
2025-11-07T19:26:04.763Z,1762543564.763 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:05.101Z,1762543565.101 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:26:05.102Z,1762543565.102 [DAT](INFO): #Outgoing data=1
2025-11-07T19:26:05.102Z,1762543565.102 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:26:05.353Z,1762543565.353 [DAT](INFO): setting remote address to 10
2025-11-07T19:26:05.605Z,1762543565.605 [DAT](INFO): DAT read: user:6>
2025-11-07T19:26:05.606Z,1762543565.606 [DAT](INFO): DAT read: RemoteAddr | 10
2025-11-07T19:26:05.606Z,1762543565.606 [DAT](INFO): set remote address to 10
2025-11-07T19:26:05.766Z,1762543565.766 [CTD_Seabird](ERROR): Salinity reading out of range: 6.983364 psu
2025-11-07T19:26:05.767Z,1762543565.767 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:06.770Z,1762543566.770 [CTD_Seabird](ERROR): Salinity reading out of range: 1.902587 psu
2025-11-07T19:26:06.771Z,1762543566.771 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:06.868Z,1762543566.868 [DAT](INFO): entering online mode
2025-11-07T19:26:07.117Z,1762543567.117 [DAT](INFO): DAT read: user:7>
2025-11-07T19:26:07.117Z,1762543567.117 [DAT](INFO): DAT read:
2025-11-07T19:26:07.118Z,1762543567.118 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-11-07T19:26:07.119Z,1762543567.119 [DAT](INFO): commRate: 800
2025-11-07T19:26:07.119Z,1762543567.119 [DAT](INFO): online mode acknowledged
2025-11-07T19:26:07.119Z,1762543567.119 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:26:07.774Z,1762543567.774 [CTD_Seabird](ERROR): Salinity reading out of range: 7.581214 psu
2025-11-07T19:26:07.776Z,1762543567.776 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:08.778Z,1762543568.778 [CTD_Seabird](ERROR): Salinity reading out of range: 2.286969 psu
2025-11-07T19:26:08.780Z,1762543568.780 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:09.782Z,1762543569.782 [CTD_Seabird](ERROR): Salinity reading out of range: 7.490192 psu
2025-11-07T19:26:09.783Z,1762543569.783 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:10.393Z,1762543570.393 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:26:09.9218
2025-11-07T19:26:10.393Z,1762543570.393 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:26:10.786Z,1762543570.786 [CTD_Seabird](ERROR): Salinity reading out of range: 2.282464 psu
2025-11-07T19:26:10.787Z,1762543570.787 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:11.790Z,1762543571.790 [CTD_Seabird](ERROR): Salinity reading out of range: 7.223053 psu
2025-11-07T19:26:11.791Z,1762543571.791 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:12.794Z,1762543572.794 [CTD_Seabird](ERROR): Salinity reading out of range: 1.687111 psu
2025-11-07T19:26:12.795Z,1762543572.795 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:12.897Z,1762543572.897 [BackseatComponent](IMPORTANT): netra: running backseat application.
2025-11-07T19:26:13.305Z,1762543573.305 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:13.306Z,1762543573.306 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:13.509Z,1762543573.509 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:13.509Z,1762543573.509 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:13.798Z,1762543573.798 [CTD_Seabird](ERROR): Salinity reading out of range: 6.692507 psu
2025-11-07T19:26:13.799Z,1762543573.799 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:13.917Z,1762543573.917 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:13.917Z,1762543573.917 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:14.121Z,1762543574.121 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:14.121Z,1762543574.121 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:14.529Z,1762543574.529 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:14.529Z,1762543574.529 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:14.733Z,1762543574.733 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:14.733Z,1762543574.733 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:14.802Z,1762543574.802 [CTD_Seabird](ERROR): Salinity reading out of range: 2.031890 psu
2025-11-07T19:26:14.803Z,1762543574.803 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:15.141Z,1762543575.141 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:15.141Z,1762543575.141 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:15.350Z,1762543575.350 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:15.350Z,1762543575.350 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:15.757Z,1762543575.757 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:15.757Z,1762543575.757 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:15.806Z,1762543575.806 [CTD_Seabird](ERROR): Salinity reading out of range: 7.047469 psu
2025-11-07T19:26:15.808Z,1762543575.808 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:15.961Z,1762543575.961 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:15.961Z,1762543575.961 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:16.397Z,1762543576.397 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:16.397Z,1762543576.397 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:16.810Z,1762543576.810 [CTD_Seabird](ERROR): Salinity reading out of range: 1.719408 psu
2025-11-07T19:26:16.811Z,1762543576.811 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:17.701Z,1762543577.701 [DAT](INFO): DAT read: Rx Time:19:26:16.6050
2025-11-07T19:26:17.701Z,1762543577.701 [DAT](INFO): Rx dataTimestamp_ set to:1762543577.701105
2025-11-07T19:26:17.814Z,1762543577.814 [CTD_Seabird](ERROR): Salinity reading out of range: 7.059573 psu
2025-11-07T19:26:17.815Z,1762543577.815 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:18.457Z,1762543578.457 [DAT](INFO): DAT read:
2025-11-07T19:26:18.459Z,1762543578.459 [DAT](INFO): DAT read: DATA(0002):~~
2025-11-07T19:26:18.459Z,1762543578.459 [DAT](INFO): Got DATA 2
2025-11-07T19:26:18.461Z,1762543578.461 [DAT](INFO): DAT read: Source:010 Destination:001
2025-11-07T19:26:18.461Z,1762543578.461 [DAT](INFO): Got Src/Dest after DATA
2025-11-07T19:26:18.462Z,1762543578.462 [DAT](INFO): DATA Src=10, Dst=1
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:20.1 AGC:19 SPD:+0.0 CCERR:013
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](INFO): Incoming data is intended for us
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](INFO): Got ack
2025-11-07T19:26:18.463Z,1762543578.463 [DAT](INFO): DAT read:
2025-11-07T19:26:18.464Z,1762543578.464 [DAT](INFO): DAT read:
2025-11-07T19:26:18.464Z,1762543578.464 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2025-11-07T19:26:18.709Z,1762543578.709 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:26:18.710Z,1762543578.710 [DAT](INFO): #Outgoing data=1
2025-11-07T19:26:18.710Z,1762543578.710 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:26:18.818Z,1762543578.818 [CTD_Seabird](ERROR): Salinity reading out of range: 1.943794 psu
2025-11-07T19:26:18.819Z,1762543578.819 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:18.838Z,1762543578.838 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:18.838Z,1762543578.838 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:18.961Z,1762543578.961 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:26:19.823Z,1762543579.823 [CTD_Seabird](ERROR): Salinity reading out of range: 7.504365 psu
2025-11-07T19:26:19.825Z,1762543579.825 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:20.826Z,1762543580.826 [CTD_Seabird](ERROR): Salinity reading out of range: 2.003715 psu
2025-11-07T19:26:20.827Z,1762543580.827 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:21.541Z,1762543581.541 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:21.541Z,1762543581.541 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:21.830Z,1762543581.830 [CTD_Seabird](ERROR): Salinity reading out of range: 7.409157 psu
2025-11-07T19:26:21.831Z,1762543581.831 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:22.064Z,1762543582.064 [marl_lab:SendObservationData] Running Loop=1
2025-11-07T19:26:22.064Z,1762543582.064 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData
2025-11-07T19:26:22.064Z,1762543582.064 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1
2025-11-07T19:26:22.065Z,1762543582.065 [marl_lab:SendObservationData:A] Running Loop=1
2025-11-07T19:26:22.066Z,1762543582.066 [marl_lab:SendObservationData:A](INFO): Got test_good : test_msg_13 n/a str and temp var is nan n/a str
2025-11-07T19:26:22.066Z,1762543582.066 [marl_lab:SendObservationData:A] Stopped
2025-11-07T19:26:22.066Z,1762543582.066 [marl_lab:SendObservationData:B] Running Loop=1
2025-11-07T19:26:22.237Z,1762543582.237 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:26:21.7219
2025-11-07T19:26:22.237Z,1762543582.237 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:26:22.469Z,1762543582.469 [marl_lab:SendObservationData:B] Stopped
2025-11-07T19:26:22.469Z,1762543582.469 [marl_lab:SendObservationData:C] Running Loop=1
2025-11-07T19:26:22.834Z,1762543582.834 [CTD_Seabird](ERROR): Salinity reading out of range: 1.761629 psu
2025-11-07T19:26:22.835Z,1762543582.835 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:22.925Z,1762543582.925 [marl_lab:SendObservationData:C] Stopped
2025-11-07T19:26:22.925Z,1762543582.925 [marl_lab:SendObservationData:E] Running Loop=1
2025-11-07T19:26:23.297Z,1762543583.297 [marl_lab:SendObservationData:E](INFO): SendData: sent send_observations : nan n/a str to nearby vehicles. Elapsed time 0.343341 min
2025-11-07T19:26:23.297Z,1762543583.297 [marl_lab:SendObservationData:E] Stopped
2025-11-07T19:26:23.297Z,1762543583.297 [marl_lab:SendObservationData](INFO): Completed marl_lab:SendObservationData
2025-11-07T19:26:23.297Z,1762543583.297 [marl_lab:SendObservationData] Stopped
2025-11-07T19:26:23.298Z,1762543583.298 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData
2025-11-07T19:26:23.298Z,1762543583.298 [marl_lab:SendObservationData:D.SendDirect] Stopped
2025-11-07T19:26:23.838Z,1762543583.838 [CTD_Seabird](ERROR): Salinity reading out of range: 6.938204 psu
2025-11-07T19:26:23.839Z,1762543583.839 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:23.870Z,1762543583.870 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:23.870Z,1762543583.870 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:24.842Z,1762543584.842 [CTD_Seabird](ERROR): Salinity reading out of range: 1.803823 psu
2025-11-07T19:26:24.843Z,1762543584.843 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:25.847Z,1762543585.847 [CTD_Seabird](ERROR): Salinity reading out of range: 7.194962 psu
2025-11-07T19:26:25.851Z,1762543585.851 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:26.575Z,1762543586.575 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:26.575Z,1762543586.575 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:26.850Z,1762543586.850 [CTD_Seabird](ERROR): Salinity reading out of range: 1.943611 psu
2025-11-07T19:26:26.851Z,1762543586.851 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:27.210Z,1762543587.210 [CommandLine](INFO): End of History
2025-11-07T19:26:27.854Z,1762543587.854 [CTD_Seabird](ERROR): Salinity reading out of range: 7.604876 psu
2025-11-07T19:26:27.855Z,1762543587.855 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:28.546Z,1762543588.546 [DAT](INFO): DAT read: Rx Time:19:26:27.4049
2025-11-07T19:26:28.546Z,1762543588.546 [DAT](INFO): Rx dataTimestamp_ set to:1762543588.545732
2025-11-07T19:26:28.858Z,1762543588.858 [CTD_Seabird](ERROR): Salinity reading out of range: 1.888633 psu
2025-11-07T19:26:28.859Z,1762543588.859 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:28.898Z,1762543588.898 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:28.899Z,1762543588.899 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:29.301Z,1762543589.301 [DAT](INFO): DAT read:
2025-11-07T19:26:29.301Z,1762543589.301 [DAT](INFO): DAT read: DATA(0002):~~
2025-11-07T19:26:29.302Z,1762543589.302 [DAT](INFO): Got DATA 2
2025-11-07T19:26:29.302Z,1762543589.302 [DAT](INFO): DAT read: Source:010 Destination:001
2025-11-07T19:26:29.302Z,1762543589.302 [DAT](INFO): Got Src/Dest after DATA
2025-11-07T19:26:29.303Z,1762543589.303 [DAT](INFO): DATA Src=10, Dst=1
2025-11-07T19:26:29.304Z,1762543589.304 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:20.0 AGC:16 SPD:+0.0 CCERR:013
2025-11-07T19:26:29.304Z,1762543589.304 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:29.304Z,1762543589.304 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:29.305Z,1762543589.305 [DAT](INFO): Incoming data is intended for us
2025-11-07T19:26:29.305Z,1762543589.305 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2025-11-07T19:26:29.305Z,1762543589.305 [DAT](INFO): Got ack
2025-11-07T19:26:29.305Z,1762543589.305 [DAT](INFO): DAT read:
2025-11-07T19:26:29.305Z,1762543589.305 [DAT](INFO): DAT read:
2025-11-07T19:26:29.553Z,1762543589.553 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2025-11-07T19:26:29.805Z,1762543589.805 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:26:29.805Z,1762543589.805 [DAT](INFO): #Outgoing data=1
2025-11-07T19:26:29.805Z,1762543589.805 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:26:29.862Z,1762543589.862 [CTD_Seabird](ERROR): Salinity reading out of range: 7.387393 psu
2025-11-07T19:26:29.863Z,1762543589.863 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:30.057Z,1762543590.057 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:26:30.866Z,1762543590.866 [CTD_Seabird](ERROR): Salinity reading out of range: 1.730459 psu
2025-11-07T19:26:30.867Z,1762543590.867 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:31.605Z,1762543591.605 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:31.605Z,1762543591.605 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:31.870Z,1762543591.870 [CTD_Seabird](ERROR): Salinity reading out of range: 6.923357 psu
2025-11-07T19:26:31.871Z,1762543591.871 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:32.874Z,1762543592.874 [CTD_Seabird](ERROR): Salinity reading out of range: 1.689683 psu
2025-11-07T19:26:32.876Z,1762543592.876 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:33.334Z,1762543593.334 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:26:32.8219
2025-11-07T19:26:33.334Z,1762543593.334 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:26:33.878Z,1762543593.878 [CTD_Seabird](ERROR): Salinity reading out of range: 7.126725 psu
2025-11-07T19:26:33.879Z,1762543593.879 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:33.930Z,1762543593.930 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:33.931Z,1762543593.931 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:34.882Z,1762543594.882 [CTD_Seabird](ERROR): Salinity reading out of range: 1.831090 psu
2025-11-07T19:26:34.883Z,1762543594.883 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:35.254Z,1762543595.254 [CommandLine](INFO): End of History
2025-11-07T19:26:35.886Z,1762543595.886 [CTD_Seabird](ERROR): Salinity reading out of range: 7.813171 psu
2025-11-07T19:26:35.887Z,1762543595.887 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:36.637Z,1762543596.637 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:36.637Z,1762543596.637 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:36.890Z,1762543596.890 [CTD_Seabird](ERROR): Salinity reading out of range: 1.886807 psu
2025-11-07T19:26:36.891Z,1762543596.891 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:37.894Z,1762543597.894 [CTD_Seabird](ERROR): Salinity reading out of range: 7.143363 psu
2025-11-07T19:26:37.895Z,1762543597.895 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:38.898Z,1762543598.898 [CTD_Seabird](ERROR): Salinity reading out of range: 1.867694 psu
2025-11-07T19:26:38.899Z,1762543598.899 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:38.963Z,1762543598.963 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:38.963Z,1762543598.963 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:39.633Z,1762543599.633 [DAT](INFO): DAT read: Rx Time:19:26:38.5050
2025-11-07T19:26:39.633Z,1762543599.633 [DAT](INFO): Rx dataTimestamp_ set to:1762543599.633063
2025-11-07T19:26:39.907Z,1762543599.907 [CTD_Seabird](ERROR): Salinity reading out of range: 7.277079 psu
2025-11-07T19:26:39.909Z,1762543599.909 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:40.389Z,1762543600.389 [DAT](INFO): DAT read:
2025-11-07T19:26:40.389Z,1762543600.389 [DAT](INFO): DAT read: DATA(0002):~~
2025-11-07T19:26:40.389Z,1762543600.389 [DAT](INFO): Got DATA 2
2025-11-07T19:26:40.390Z,1762543600.390 [DAT](INFO): DAT read: Source:010 Destination:001
2025-11-07T19:26:40.390Z,1762543600.390 [DAT](INFO): Got Src/Dest after DATA
2025-11-07T19:26:40.391Z,1762543600.391 [DAT](INFO): DATA Src=10, Dst=1
2025-11-07T19:26:40.392Z,1762543600.392 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:20.4 AGC:18 SPD:+0.0 CCERR:013
2025-11-07T19:26:40.392Z,1762543600.392 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:40.392Z,1762543600.392 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:40.392Z,1762543600.392 [DAT](INFO): Incoming data is intended for us
2025-11-07T19:26:40.392Z,1762543600.392 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2025-11-07T19:26:40.393Z,1762543600.393 [DAT](INFO): Got ack
2025-11-07T19:26:40.393Z,1762543600.393 [DAT](INFO): DAT read:
2025-11-07T19:26:40.393Z,1762543600.393 [DAT](INFO): DAT read:
2025-11-07T19:26:40.641Z,1762543600.641 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2025-11-07T19:26:40.893Z,1762543600.893 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:26:40.893Z,1762543600.893 [DAT](INFO): #Outgoing data=1
2025-11-07T19:26:40.894Z,1762543600.894 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:26:40.910Z,1762543600.910 [CTD_Seabird](ERROR): Salinity reading out of range: 2.399697 psu
2025-11-07T19:26:40.911Z,1762543600.911 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:41.145Z,1762543601.145 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:26:41.669Z,1762543601.669 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:41.669Z,1762543601.669 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:41.914Z,1762543601.914 [CTD_Seabird](ERROR): Salinity reading out of range: 1.287977 psu
2025-11-07T19:26:41.915Z,1762543601.915 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:42.187Z,1762543602.187 [CommandLine](INFO): End of History
2025-11-07T19:26:42.918Z,1762543602.918 [CTD_Seabird](ERROR): Salinity reading out of range: 7.205300 psu
2025-11-07T19:26:42.919Z,1762543602.919 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:43.080Z,1762543603.080 [marl_lab:SendObservationData] Running Loop=1
2025-11-07T19:26:43.080Z,1762543603.080 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData
2025-11-07T19:26:43.080Z,1762543603.080 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1
2025-11-07T19:26:43.081Z,1762543603.081 [marl_lab:SendObservationData:A] Running Loop=1
2025-11-07T19:26:43.082Z,1762543603.082 [marl_lab:SendObservationData:A](INFO): Got test_good : test_msg_21 n/a str and temp var is nan n/a str
2025-11-07T19:26:43.082Z,1762543603.082 [marl_lab:SendObservationData:A] Stopped
2025-11-07T19:26:43.082Z,1762543603.082 [marl_lab:SendObservationData:B] Running Loop=1
2025-11-07T19:26:43.501Z,1762543603.501 [marl_lab:SendObservationData:B] Stopped
2025-11-07T19:26:43.501Z,1762543603.501 [marl_lab:SendObservationData:C] Running Loop=1
2025-11-07T19:26:43.897Z,1762543603.897 [marl_lab:SendObservationData:C] Stopped
2025-11-07T19:26:43.897Z,1762543603.897 [marl_lab:SendObservationData:E] Running Loop=1
2025-11-07T19:26:43.922Z,1762543603.922 [CTD_Seabird](ERROR): Salinity reading out of range: 2.025419 psu
2025-11-07T19:26:43.923Z,1762543603.923 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:43.995Z,1762543603.995 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:43.996Z,1762543603.996 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:44.298Z,1762543604.298 [marl_lab:SendObservationData:E](INFO): SendData: sent send_observations : nan n/a str to nearby vehicles. Elapsed time 0.342931 min
2025-11-07T19:26:44.299Z,1762543604.299 [marl_lab:SendObservationData:E] Stopped
2025-11-07T19:26:44.300Z,1762543604.300 [marl_lab:SendObservationData](INFO): Completed marl_lab:SendObservationData
2025-11-07T19:26:44.300Z,1762543604.300 [marl_lab:SendObservationData] Stopped
2025-11-07T19:26:44.300Z,1762543604.300 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData
2025-11-07T19:26:44.300Z,1762543604.300 [marl_lab:SendObservationData:D.SendDirect] Stopped
2025-11-07T19:26:44.422Z,1762543604.422 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:26:43.9220
2025-11-07T19:26:44.422Z,1762543604.422 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:26:44.927Z,1762543604.927 [CTD_Seabird](ERROR): Salinity reading out of range: 7.067469 psu
2025-11-07T19:26:44.928Z,1762543604.928 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:45.930Z,1762543605.930 [CTD_Seabird](ERROR): Salinity reading out of range: 1.718773 psu
2025-11-07T19:26:45.931Z,1762543605.931 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:46.701Z,1762543606.701 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:46.701Z,1762543606.701 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:46.831Z,1762543606.831 [CommandLine](INFO): End of History
2025-11-07T19:26:46.934Z,1762543606.934 [CTD_Seabird](ERROR): Salinity reading out of range: 6.830909 psu
2025-11-07T19:26:46.935Z,1762543606.935 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:47.938Z,1762543607.938 [CTD_Seabird](ERROR): Salinity reading out of range: 1.644183 psu
2025-11-07T19:26:47.939Z,1762543607.939 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:48.942Z,1762543608.942 [CTD_Seabird](ERROR): Salinity reading out of range: 7.021086 psu
2025-11-07T19:26:48.943Z,1762543608.943 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:49.029Z,1762543609.029 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:49.029Z,1762543609.029 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:49.946Z,1762543609.946 [CTD_Seabird](ERROR): Salinity reading out of range: 2.099147 psu
2025-11-07T19:26:49.947Z,1762543609.947 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:50.733Z,1762543610.733 [DAT](INFO): DAT read: Rx Time:19:26:49.6052
2025-11-07T19:26:50.733Z,1762543610.733 [DAT](INFO): Rx dataTimestamp_ set to:1762543610.732960
2025-11-07T19:26:50.950Z,1762543610.950 [CTD_Seabird](ERROR): Salinity reading out of range: 7.863987 psu
2025-11-07T19:26:50.951Z,1762543610.951 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:51.330Z,1762543611.330 [CommandLine](INFO): End of History
2025-11-07T19:26:51.489Z,1762543611.489 [DAT](INFO): DAT read:
2025-11-07T19:26:51.489Z,1762543611.489 [DAT](INFO): DAT read: DATA(0002):~~
2025-11-07T19:26:51.490Z,1762543611.490 [DAT](INFO): Got DATA 2
2025-11-07T19:26:51.490Z,1762543611.490 [DAT](INFO): DAT read: Source:010 Destination:001
2025-11-07T19:26:51.490Z,1762543611.490 [DAT](INFO): Got Src/Dest after DATA
2025-11-07T19:26:51.491Z,1762543611.491 [DAT](INFO): DATA Src=10, Dst=1
2025-11-07T19:26:51.493Z,1762543611.493 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:20.4 AGC:17 SPD:+0.0 CCERR:013
2025-11-07T19:26:51.493Z,1762543611.493 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:51.493Z,1762543611.493 [DAT](INFO): Got CRC:Pass
2025-11-07T19:26:51.493Z,1762543611.493 [DAT](INFO): Incoming data is intended for us
2025-11-07T19:26:51.493Z,1762543611.493 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2025-11-07T19:26:51.493Z,1762543611.493 [DAT](INFO): Got ack
2025-11-07T19:26:51.494Z,1762543611.494 [DAT](INFO): DAT read:
2025-11-07T19:26:51.494Z,1762543611.494 [DAT](INFO): DAT read:
2025-11-07T19:26:51.494Z,1762543611.494 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2025-11-07T19:26:51.733Z,1762543611.733 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:51.733Z,1762543611.733 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:51.954Z,1762543611.954 [CTD_Seabird](ERROR): Salinity reading out of range: 1.827963 psu
2025-11-07T19:26:51.955Z,1762543611.955 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:51.994Z,1762543611.994 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:26:51.994Z,1762543611.994 [DAT](INFO): #Outgoing data=1
2025-11-07T19:26:51.994Z,1762543611.994 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:26:52.245Z,1762543612.245 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:26:52.958Z,1762543612.958 [CTD_Seabird](ERROR): Salinity reading out of range: 7.325583 psu
2025-11-07T19:26:52.959Z,1762543612.959 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:53.962Z,1762543613.962 [CTD_Seabird](ERROR): Salinity reading out of range: 1.779886 psu
2025-11-07T19:26:53.963Z,1762543613.963 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:54.060Z,1762543614.060 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:54.061Z,1762543614.061 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:54.966Z,1762543614.966 [CTD_Seabird](ERROR): Salinity reading out of range: 7.221904 psu
2025-11-07T19:26:54.968Z,1762543614.968 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:55.521Z,1762543615.521 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:26:55.0221
2025-11-07T19:26:55.521Z,1762543615.521 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:26:55.970Z,1762543615.970 [CTD_Seabird](ERROR): Salinity reading out of range: 1.991515 psu
2025-11-07T19:26:55.971Z,1762543615.971 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:56.118Z,1762543616.118 [CommandLine](INFO): End of History
2025-11-07T19:26:56.765Z,1762543616.765 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:56.765Z,1762543616.765 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:56.974Z,1762543616.974 [CTD_Seabird](ERROR): Salinity reading out of range: 7.338748 psu
2025-11-07T19:26:56.975Z,1762543616.975 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:57.978Z,1762543617.978 [CTD_Seabird](ERROR): Salinity reading out of range: 2.086818 psu
2025-11-07T19:26:57.979Z,1762543617.979 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:58.982Z,1762543618.982 [CTD_Seabird](ERROR): Salinity reading out of range: 7.509361 psu
2025-11-07T19:26:58.983Z,1762543618.983 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:26:59.090Z,1762543619.090 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:26:59.090Z,1762543619.090 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:26:59.986Z,1762543619.986 [CTD_Seabird](ERROR): Salinity reading out of range: 2.049857 psu
2025-11-07T19:26:59.987Z,1762543619.987 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:00.990Z,1762543620.990 [CTD_Seabird](ERROR): Salinity reading out of range: 7.041921 psu
2025-11-07T19:27:00.991Z,1762543620.991 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:01.396Z,1762543621.396 [CommandLine](INFO): End of History
2025-11-07T19:27:01.793Z,1762543621.793 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:01.793Z,1762543621.793 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:01.821Z,1762543621.821 [DAT](INFO): DAT read: Rx Time:19:27:00.7052
2025-11-07T19:27:01.822Z,1762543621.822 [DAT](INFO): Rx dataTimestamp_ set to:1762543621.821379
2025-11-07T19:27:01.995Z,1762543621.995 [CTD_Seabird](ERROR): Salinity reading out of range: 1.763663 psu
2025-11-07T19:27:01.997Z,1762543621.997 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:02.577Z,1762543622.577 [DAT](INFO): DAT read:
2025-11-07T19:27:02.577Z,1762543622.577 [DAT](INFO): DAT read: DATA(0002):~~
2025-11-07T19:27:02.578Z,1762543622.578 [DAT](INFO): Got DATA 2
2025-11-07T19:27:02.578Z,1762543622.578 [DAT](INFO): DAT read: Source:010 Destination:001
2025-11-07T19:27:02.578Z,1762543622.578 [DAT](INFO): Got Src/Dest after DATA
2025-11-07T19:27:02.579Z,1762543622.579 [DAT](INFO): DATA Src=10, Dst=1
2025-11-07T19:27:02.580Z,1762543622.580 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:20.4 AGC:17 SPD:+0.0 CCERR:013
2025-11-07T19:27:02.581Z,1762543622.581 [DAT](INFO): Got CRC:Pass
2025-11-07T19:27:02.581Z,1762543622.581 [DAT](INFO): Got CRC:Pass
2025-11-07T19:27:02.582Z,1762543622.582 [DAT](INFO): Incoming data is intended for us
2025-11-07T19:27:02.582Z,1762543622.582 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2025-11-07T19:27:02.582Z,1762543622.582 [DAT](INFO): Got ack
2025-11-07T19:27:02.583Z,1762543622.583 [DAT](INFO): DAT read:
2025-11-07T19:27:02.586Z,1762543622.586 [DAT](INFO): DAT read:
2025-11-07T19:27:02.587Z,1762543622.587 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2025-11-07T19:27:02.829Z,1762543622.829 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:27:02.829Z,1762543622.829 [DAT](INFO): #Outgoing data=1
2025-11-07T19:27:02.830Z,1762543622.830 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:27:02.998Z,1762543622.998 [CTD_Seabird](ERROR): Salinity reading out of range: 6.993884 psu
2025-11-07T19:27:02.999Z,1762543622.999 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:03.333Z,1762543623.333 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:27:04.003Z,1762543624.003 [CTD_Seabird](ERROR): Salinity reading out of range: 1.771558 psu
2025-11-07T19:27:04.004Z,1762543624.004 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:04.121Z,1762543624.121 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:04.121Z,1762543624.121 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:04.188Z,1762543624.188 [marl_lab:SendObservationData] Running Loop=1
2025-11-07T19:27:04.188Z,1762543624.188 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData
2025-11-07T19:27:04.188Z,1762543624.188 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1
2025-11-07T19:27:04.189Z,1762543624.189 [marl_lab:SendObservationData:A] Running Loop=1
2025-11-07T19:27:04.190Z,1762543624.190 [marl_lab:SendObservationData:A](INFO): Got test_good : test_msg_30 n/a str and temp var is nan n/a str
2025-11-07T19:27:04.191Z,1762543624.191 [marl_lab:SendObservationData:A] Stopped
2025-11-07T19:27:04.191Z,1762543624.191 [marl_lab:SendObservationData:B] Running Loop=1
2025-11-07T19:27:04.537Z,1762543624.537 [marl_lab:SendObservationData:B] Stopped
2025-11-07T19:27:04.537Z,1762543624.537 [marl_lab:SendObservationData:C] Running Loop=1
2025-11-07T19:27:04.945Z,1762543624.945 [marl_lab:SendObservationData:C] Stopped
2025-11-07T19:27:04.945Z,1762543624.945 [marl_lab:SendObservationData:E] Running Loop=1
2025-11-07T19:27:05.010Z,1762543625.010 [CTD_Seabird](ERROR): Salinity reading out of range: 7.607503 psu
2025-11-07T19:27:05.011Z,1762543625.011 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:05.365Z,1762543625.365 [marl_lab:SendObservationData:E](INFO): SendData: sent send_observations : nan n/a str to nearby vehicles. Elapsed time 0.343998 min
2025-11-07T19:27:05.365Z,1762543625.365 [marl_lab:SendObservationData:E] Stopped
2025-11-07T19:27:05.366Z,1762543625.366 [marl_lab:SendObservationData](INFO): Completed marl_lab:SendObservationData
2025-11-07T19:27:05.366Z,1762543625.366 [marl_lab:SendObservationData] Stopped
2025-11-07T19:27:05.366Z,1762543625.366 [marl_lab:SendObservationData](DEBUG): Aggregate::uninitialize marl_lab:SendObservationData
2025-11-07T19:27:05.366Z,1762543625.366 [marl_lab:SendObservationData:D.SendDirect] Stopped
2025-11-07T19:27:06.010Z,1762543626.010 [CTD_Seabird](ERROR): Salinity reading out of range: 2.388491 psu
2025-11-07T19:27:06.012Z,1762543626.012 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:06.609Z,1762543626.609 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:27:06.1222
2025-11-07T19:27:06.610Z,1762543626.610 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:27:06.825Z,1762543626.825 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:06.825Z,1762543626.825 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:07.014Z,1762543627.014 [CTD_Seabird](ERROR): Salinity reading out of range: 1.378941 psu
2025-11-07T19:27:07.015Z,1762543627.015 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:08.018Z,1762543628.018 [CTD_Seabird](ERROR): Salinity reading out of range: 7.442555 psu
2025-11-07T19:27:08.019Z,1762543628.019 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:09.022Z,1762543629.022 [CTD_Seabird](ERROR): Salinity reading out of range: 2.467010 psu
2025-11-07T19:27:09.023Z,1762543629.023 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:09.153Z,1762543629.153 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:09.153Z,1762543629.153 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:09.633Z,1762543629.633 [DAT](INFO): DAT read: Rx Time:19:27:08.6053
2025-11-07T19:27:09.633Z,1762543629.633 [DAT](INFO): Rx dataTimestamp_ set to:1762543629.633195
2025-11-07T19:27:10.026Z,1762543630.026 [CTD_Seabird](ERROR): Salinity reading out of range: 1.497535 psu
2025-11-07T19:27:10.028Z,1762543630.028 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:10.137Z,1762543630.137 [DAT](INFO): DAT read:
2025-11-07T19:27:10.138Z,1762543630.138 [DAT](INFO): DAT read: $Packet for address 10
2025-11-07T19:27:10.138Z,1762543630.138 [DAT](INFO): received a packet notification
2025-11-07T19:27:11.034Z,1762543631.034 [CTD_Seabird](ERROR): Salinity reading out of range: 7.482305 psu
2025-11-07T19:27:11.036Z,1762543631.036 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:11.071Z,1762543631.071 [CommandLine](INFO): End of History
2025-11-07T19:27:11.857Z,1762543631.857 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:11.857Z,1762543631.857 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:12.038Z,1762543632.038 [CTD_Seabird](ERROR): Salinity reading out of range: 2.132418 psu
2025-11-07T19:27:12.039Z,1762543632.039 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:13.042Z,1762543633.042 [CTD_Seabird](ERROR): Salinity reading out of range: 7.352846 psu
2025-11-07T19:27:13.043Z,1762543633.043 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:13.169Z,1762543633.169 [DAT](INFO): DAT read: Rx Time:19:27:12.0553
2025-11-07T19:27:13.169Z,1762543633.169 [DAT](INFO): Rx dataTimestamp_ set to:1762543633.169131
2025-11-07T19:27:13.673Z,1762543633.673 [DAT](INFO): DAT read:
2025-11-07T19:27:13.673Z,1762543633.673 [DAT](INFO): DAT read: $Packet for address 10
2025-11-07T19:27:13.674Z,1762543633.674 [DAT](INFO): received a packet notification
2025-11-07T19:27:14.046Z,1762543634.046 [CTD_Seabird](ERROR): Salinity reading out of range: 1.886988 psu
2025-11-07T19:27:14.047Z,1762543634.047 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:14.185Z,1762543634.185 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:14.186Z,1762543634.186 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:15.062Z,1762543635.062 [CTD_Seabird](ERROR): Salinity reading out of range: 7.175717 psu
2025-11-07T19:27:15.063Z,1762543635.063 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:16.067Z,1762543636.067 [CTD_Seabird](ERROR): Salinity reading out of range: 1.814734 psu
2025-11-07T19:27:16.069Z,1762543636.069 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:16.697Z,1762543636.697 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2025-11-07T19:27:16.784Z,1762543636.784 [CommandLine](INFO): End of History
2025-11-07T19:27:16.889Z,1762543636.889 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:16.889Z,1762543636.889 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:16.949Z,1762543636.949 [DAT](INFO): modem://10: set _.others_observations nan n/a str none_str
2025-11-07T19:27:16.950Z,1762543636.950 [DAT](INFO): #Outgoing data=1
2025-11-07T19:27:16.950Z,1762543636.950 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2025-11-07T19:27:17.070Z,1762543637.070 [CTD_Seabird](ERROR): Salinity reading out of range: 7.018049 psu
2025-11-07T19:27:17.071Z,1762543637.071 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:17.201Z,1762543637.201 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2025-11-07T19:27:18.074Z,1762543638.074 [CTD_Seabird](ERROR): Salinity reading out of range: 1.744736 psu
2025-11-07T19:27:18.075Z,1762543638.075 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:19.078Z,1762543639.078 [CTD_Seabird](ERROR): Salinity reading out of range: 7.270677 psu
2025-11-07T19:27:19.079Z,1762543639.079 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:19.217Z,1762543639.217 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:19.218Z,1762543639.218 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:20.082Z,1762543640.082 [CTD_Seabird](ERROR): Salinity reading out of range: 1.817351 psu
2025-11-07T19:27:20.083Z,1762543640.083 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:20.481Z,1762543640.481 [DAT](INFO): DAT read: Forwarding Delay UpTx time:19:27:19.9723
2025-11-07T19:27:20.481Z,1762543640.481 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2025-11-07T19:27:21.086Z,1762543641.086 [CTD_Seabird](ERROR): Salinity reading out of range: 7.044344 psu
2025-11-07T19:27:21.087Z,1762543641.087 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:21.399Z,1762543641.399 [CommandLine](INFO): End of History
2025-11-07T19:27:21.921Z,1762543641.921 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:21.921Z,1762543641.921 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:22.091Z,1762543642.091 [CTD_Seabird](ERROR): Salinity reading out of range: 1.683848 psu
2025-11-07T19:27:22.092Z,1762543642.092 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:23.095Z,1762543643.095 [CTD_Seabird](ERROR): Salinity reading out of range: 6.938585 psu
2025-11-07T19:27:23.096Z,1762543643.096 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:23.513Z,1762543643.513 [DAT](INFO): DAT read: Rx Time:19:27:22.4555
2025-11-07T19:27:23.513Z,1762543643.513 [DAT](INFO): Rx dataTimestamp_ set to:1762543643.513123
2025-11-07T19:27:24.017Z,1762543644.017 [DAT](INFO): DAT read:
2025-11-07T19:27:24.017Z,1762543644.017 [DAT](INFO): DAT read: $Packet for address 10
2025-11-07T19:27:24.018Z,1762543644.018 [DAT](INFO): received a packet notification
2025-11-07T19:27:24.098Z,1762543644.098 [CTD_Seabird](ERROR): Salinity reading out of range: 1.695124 psu
2025-11-07T19:27:24.099Z,1762543644.099 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:24.249Z,1762543644.249 [BackseatComponent](FAULT): Failed to create a data reader. _.others_observations is unknown.
2025-11-07T19:27:24.249Z,1762543644.249 [BackseatComponent](ERROR): Failed to request data from _.others_observations.
2025-11-07T19:27:25.102Z,1762543645.102 [CTD_Seabird](ERROR): Salinity reading out of range: 6.927236 psu
2025-11-07T19:27:25.103Z,1762543645.103 [CTD_Seabird](INFO): some bad data, not updating bins
2025-11-07T19:27:25.172Z,1762543645.172 [marl_lab:SendObservationData] Running Loop=1
2025-11-07T19:27:25.172Z,1762543645.172 [marl_lab:SendObservationData](DEBUG): Aggregate::initialize marl_lab:SendObservationData
2025-11-07T19:27:25.172Z,1762543645.172 [marl_lab:SendObservationData:D.SendDirect] Running Loop=1
2025-11-07T19:27:25.173Z,1762543645.173 [marl_lab:SendObservationData:A] Running Loop=1
2025-11-07T19:27:25.174Z,1762543645.174 [marl_lab:SendObservationData:A](INFO): Got test_good : test_msg_38 n/a str and temp var is nan n/a str
2025-11-07T19:27:25.174Z,1762543645.174 [marl_lab:SendObservationData:A] Stopped
2025-11-07T19:27:25.174Z,1762543645.174 [marl_lab:SendObservationData:B] Running Loop=1
2025-11-07T19:27:25.525Z,1762543645.525 [marl_lab:SendObservationData:B] Stopped
2025-11-07T19:27:25.525Z,1762543645.525 [marl_lab:SendObservationData:C] Running Loop=1
2025-11-07T19:27:25.925Z,1762543645.925 [marl_lab:SendObservationData:C] Stopped
2025-11-07T19:27:25.925Z,1762543645.925 [marl_lab:SendObservatio