2023-05-04T23:21:27.179Z,1683242487.179 [Supervisor](DEBUG): Initializing supervisor.
2023-05-04T23:21:27.184Z,1683242487.184 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-04T23:21:27.184Z,1683242487.184 [SyncHandler](INFO): Protected caller Thread ID is 3879
2023-05-04T23:21:27.185Z,1683242487.185 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-04T23:21:27.186Z,1683242487.186 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-04T23:21:27.187Z,1683242487.187 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3880
2023-05-04T23:21:27.191Z,1683242487.191 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-04T23:21:27.208Z,1683242487.208 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-04T23:21:27.209Z,1683242487.209 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-04T23:21:27.209Z,1683242487.209 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3881
2023-05-04T23:21:27.214Z,1683242487.214 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-04T23:21:27.215Z,1683242487.215 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-04T23:21:27.215Z,1683242487.215 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3882
2023-05-04T23:21:27.217Z,1683242487.217 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-04T23:21:27.218Z,1683242487.218 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-04T23:21:27.218Z,1683242487.218 [logger ThreadHandler](INFO): Protected caller Thread ID is 3883
2023-05-04T23:21:27.222Z,1683242487.222 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-04T23:21:27.222Z,1683242487.222 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-04T23:21:27.224Z,1683242487.224 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-04T23:21:27.670Z,1683242487.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-04T23:21:27.671Z,1683242487.671 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-04T23:21:27.876Z,1683242487.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-04T23:21:27.876Z,1683242487.876 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-04T23:21:27.954Z,1683242487.954 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-04T23:21:28.163Z,1683242488.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-04T23:21:28.164Z,1683242488.164 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-04T23:21:28.241Z,1683242488.241 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-04T23:21:28.334Z,1683242488.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-04T23:21:28.335Z,1683242488.335 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-04T23:21:28.657Z,1683242488.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-04T23:21:28.657Z,1683242488.657 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-04T23:21:28.786Z,1683242488.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-04T23:21:28.787Z,1683242488.787 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-04T23:21:29.265Z,1683242489.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-04T23:21:29.265Z,1683242489.265 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-04T23:21:29.440Z,1683242489.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-04T23:21:29.441Z,1683242489.441 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-04T23:21:29.822Z,1683242489.822 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-04T23:21:29.823Z,1683242489.823 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-04T23:21:30.091Z,1683242490.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-04T23:21:30.092Z,1683242490.092 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-04T23:21:30.198Z,1683242490.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-04T23:21:30.199Z,1683242490.199 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-04T23:21:30.946Z,1683242490.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-04T23:21:30.946Z,1683242490.946 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-04T23:21:31.269Z,1683242491.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-04T23:21:31.270Z,1683242491.270 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-04T23:21:31.880Z,1683242491.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-04T23:21:31.882Z,1683242491.882 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-04T23:21:31.883Z,1683242491.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-04T23:21:32.063Z,1683242492.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-04T23:21:32.145Z,1683242492.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-04T23:21:32.274Z,1683242492.274 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-04T23:21:32.357Z,1683242492.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-04T23:21:32.616Z,1683242492.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-04T23:21:32.617Z,1683242492.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-04T23:21:32.719Z,1683242492.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-04T23:21:32.828Z,1683242492.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-04T23:21:32.937Z,1683242492.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-04T23:21:33.081Z,1683242493.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-04T23:21:33.177Z,1683242493.177 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-04T23:21:33.270Z,1683242493.270 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-04T23:21:33.270Z,1683242493.270 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-04T23:21:33.282Z,1683242493.282 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-04T23:21:33.293Z,1683242493.293 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-04T23:21:33.293Z,1683242493.293 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-04T23:21:33.394Z,1683242493.394 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-04T23:21:33.394Z,1683242493.394 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-04T23:21:33.445Z,1683242493.445 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-04T23:21:33.503Z,1683242493.503 [VerticalControl] Loaded
2023-05-04T23:21:33.503Z,1683242493.503 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-04T23:21:33.506Z,1683242493.506 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-04T23:21:33.546Z,1683242493.546 [HorizontalControl] Loaded
2023-05-04T23:21:33.546Z,1683242493.546 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-04T23:21:33.549Z,1683242493.549 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-04T23:21:33.552Z,1683242493.552 [SpeedControl] Loaded
2023-05-04T23:21:33.552Z,1683242493.552 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-04T23:21:33.555Z,1683242493.555 [LoopControl](DEBUG): Construct LoopControl.
2023-05-04T23:21:33.555Z,1683242493.555 [LoopControl] Loaded
2023-05-04T23:21:33.555Z,1683242493.555 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-04T23:21:33.556Z,1683242493.556 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-04T23:21:33.556Z,1683242493.556 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-04T23:21:33.712Z,1683242493.712 [BuoyancyServo] Loaded
2023-05-04T23:21:33.712Z,1683242493.712 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-04T23:21:33.732Z,1683242493.732 [ElevatorServo] Loaded
2023-05-04T23:21:33.732Z,1683242493.732 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-04T23:21:33.751Z,1683242493.751 [MassServo] Loaded
2023-05-04T23:21:33.751Z,1683242493.751 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-04T23:21:33.769Z,1683242493.769 [RudderServo] Loaded
2023-05-04T23:21:33.769Z,1683242493.769 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-04T23:21:33.783Z,1683242493.783 [ThrusterHE] Loaded
2023-05-04T23:21:33.784Z,1683242493.784 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-04T23:21:33.784Z,1683242493.784 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-04T23:21:33.785Z,1683242493.785 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-04T23:21:33.836Z,1683242493.836 [DepthRateCalculator] Loaded
2023-05-04T23:21:33.836Z,1683242493.836 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-04T23:21:33.841Z,1683242493.841 [PitchRateCalculator] Loaded
2023-05-04T23:21:33.841Z,1683242493.841 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-04T23:21:33.850Z,1683242493.850 [SpeedCalculator] Loaded
2023-05-04T23:21:33.851Z,1683242493.851 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-04T23:21:33.855Z,1683242493.855 [YawRateCalculator] Loaded
2023-05-04T23:21:33.855Z,1683242493.855 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-04T23:21:33.873Z,1683242493.873 [ElevatorOffsetCalculator] Loaded
2023-05-04T23:21:33.873Z,1683242493.873 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-04T23:21:33.874Z,1683242493.874 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-04T23:21:33.874Z,1683242493.874 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-04T23:21:33.987Z,1683242493.987 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-04T23:21:33.987Z,1683242493.987 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-04T23:21:34.000Z,1683242494.000 [NavChart] Loaded
2023-05-04T23:21:34.001Z,1683242494.001 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-04T23:21:34.006Z,1683242494.006 [UniversalFixResidualReporter] Loaded
2023-05-04T23:21:34.006Z,1683242494.006 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-04T23:21:34.007Z,1683242494.007 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-04T23:21:34.007Z,1683242494.007 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-04T23:21:34.443Z,1683242494.443 [AHRS_M2] Loaded
2023-05-04T23:21:34.443Z,1683242494.443 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-04T23:21:34.478Z,1683242494.478 [BackseatComponent] Loaded
2023-05-04T23:21:34.478Z,1683242494.478 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-04T23:21:34.479Z,1683242494.479 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-04T23:21:34.479Z,1683242494.479 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3965
2023-05-04T23:21:34.482Z,1683242494.482 [LcmUniversalReporter] Loaded
2023-05-04T23:21:34.482Z,1683242494.482 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-04T23:21:35.307Z,1683242495.307 [BPC1] Loaded
2023-05-04T23:21:35.307Z,1683242495.307 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-04T23:21:35.378Z,1683242495.378 [DataOverHttps] Loaded
2023-05-04T23:21:35.379Z,1683242495.379 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-04T23:21:35.380Z,1683242495.380 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-04T23:21:35.380Z,1683242495.380 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3966
2023-05-04T23:21:35.401Z,1683242495.401 [Depth_Keller] Loaded
2023-05-04T23:21:35.401Z,1683242495.401 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-04T23:21:35.463Z,1683242495.463 [NAL9602] Loaded
2023-05-04T23:21:35.463Z,1683242495.463 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-04T23:21:35.493Z,1683242495.493 [Onboard] Loaded
2023-05-04T23:21:35.493Z,1683242495.493 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-04T23:21:35.494Z,1683242495.494 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-04T23:21:35.494Z,1683242495.494 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3967
2023-05-04T23:21:35.506Z,1683242495.506 [Power24vConverter] Loaded
2023-05-04T23:21:35.506Z,1683242495.506 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-04T23:21:35.520Z,1683242495.520 [Radio_Surface] Loaded
2023-05-04T23:21:35.520Z,1683242495.520 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-04T23:21:35.521Z,1683242495.521 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-04T23:21:35.521Z,1683242495.521 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3968
2023-05-04T23:21:35.607Z,1683242495.607 [DATMMP] Loaded
2023-05-04T23:21:35.608Z,1683242495.608 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-04T23:21:35.609Z,1683242495.609 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-04T23:21:35.609Z,1683242495.609 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3969
2023-05-04T23:21:35.610Z,1683242495.610 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-04T23:21:35.610Z,1683242495.610 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-04T23:21:35.685Z,1683242495.685 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-04T23:21:35.685Z,1683242495.685 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-04T23:21:35.838Z,1683242495.838 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-04T23:21:35.838Z,1683242495.838 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-04T23:21:35.865Z,1683242495.865 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-04T23:21:35.866Z,1683242495.866 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-04T23:21:36.034Z,1683242496.034 [CTD_Seabird] Loaded
2023-05-04T23:21:36.035Z,1683242496.035 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-04T23:21:36.036Z,1683242496.036 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2023-05-04T23:21:36.036Z,1683242496.036 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3970
2023-05-04T23:21:36.069Z,1683242496.069 [ESPComponent] Loaded
2023-05-04T23:21:36.069Z,1683242496.069 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-04T23:21:36.089Z,1683242496.089 [PAR_Licor] Loaded
2023-05-04T23:21:36.089Z,1683242496.089 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-04T23:21:36.120Z,1683242496.120 [WetLabsBB2FL] Loaded
2023-05-04T23:21:36.120Z,1683242496.120 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-04T23:21:36.121Z,1683242496.121 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0
2023-05-04T23:21:36.121Z,1683242496.121 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3971
2023-05-04T23:21:36.122Z,1683242496.122 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-04T23:21:36.123Z,1683242496.123 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-04T23:21:36.433Z,1683242496.433 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-04T23:21:36.434Z,1683242496.434 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-04T23:21:36.812Z,1683242496.812 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-04T23:21:36.822Z,1683242496.822 [SBIT] Loaded
2023-05-04T23:21:36.822Z,1683242496.822 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-04T23:21:36.825Z,1683242496.825 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-04T23:21:36.837Z,1683242496.837 [IBIT] Loaded
2023-05-04T23:21:36.838Z,1683242496.838 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-04T23:21:36.843Z,1683242496.843 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-04T23:21:36.947Z,1683242496.947 [CBIT] Loaded
2023-05-04T23:21:36.947Z,1683242496.947 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-04T23:21:36.947Z,1683242496.947 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-04T23:21:36.953Z,1683242496.953 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-04T23:21:36.956Z,1683242496.956 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-04T23:21:36.967Z,1683242496.967 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-04T23:21:36.968Z,1683242496.968 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2023-05-04T23:21:36.969Z,1683242496.969 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3972
2023-05-04T23:21:36.973Z,1683242496.973 [Supervisor](INFO): Main Thread ID is 3727
2023-05-04T23:21:36.973Z,1683242496.973 [Supervisor](DEBUG): Running supervisor.
2023-05-04T23:21:36.974Z,1683242496.974 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3973
2023-05-04T23:21:36.974Z,1683242496.974 [CommandExec](INFO): Initializing the command executive.
2023-05-04T23:21:36.976Z,1683242496.976 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3974
2023-05-04T23:21:36.978Z,1683242496.978 [controlThread ThreadHandler](INFO): Handler Thread ID is 3975
2023-05-04T23:21:36.978Z,1683242496.978 [controlThread](DEBUG): Initializing ControlThread
2023-05-04T23:21:36.979Z,1683242496.979 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-04T23:21:36.981Z,1683242496.981 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-04T23:21:36.982Z,1683242496.982 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-04T23:21:36.982Z,1683242496.982 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-04T23:21:36.983Z,1683242496.983 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-04T23:21:36.984Z,1683242496.984 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-04T23:21:36.984Z,1683242496.984 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-04T23:21:36.985Z,1683242496.985 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-04T23:21:36.985Z,1683242496.985 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-04T23:21:36.986Z,1683242496.986 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-04T23:21:36.987Z,1683242496.987 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-04T23:21:36.996Z,1683242496.996 [SBIT](INFO): Initialize SBIT Component.
2023-05-04T23:21:36.997Z,1683242496.997 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-04T23:21:36.997Z,1683242496.997 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-04T23:21:36.997Z,1683242496.997 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-04T23:21:36.998Z,1683242496.998 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-05-04T23:21:36.999Z,1683242496.999 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-04T23:21:37.000Z,1683242497.000 [IBIT](INFO): Initialize IBIT Component.
2023-05-04T23:21:37.001Z,1683242497.001 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-04T23:21:37.002Z,1683242497.002 [logger ThreadHandler](INFO): Handler Thread ID is 3976
2023-05-04T23:21:37.012Z,1683242497.012 [CBIT](DEBUG): Initialized mux pins.
2023-05-04T23:21:37.012Z,1683242497.012 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-04T23:21:37.021Z,1683242497.021 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3977
2023-05-04T23:21:37.032Z,1683242497.032 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3978
2023-05-04T23:21:37.033Z,1683242497.033 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-04T23:21:37.036Z,1683242497.036 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-04T23:21:37.037Z,1683242497.037 [CBIT](DEBUG): Initializing heartbeat.
2023-05-04T23:21:37.044Z,1683242497.044 [Onboard ThreadHandler](INFO): Handler Thread ID is 3979
2023-05-04T23:21:37.062Z,1683242497.062 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3980
2023-05-04T23:21:37.080Z,1683242497.080 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3981
2023-05-04T23:21:37.081Z,1683242497.081 [DATMMP](INFO): Start
2023-05-04T23:21:37.081Z,1683242497.081 [DATMMP](INFO): Powering up
2023-05-04T23:21:37.081Z,1683242497.081 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-04T23:21:37.085Z,1683242497.085 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3983
2023-05-04T23:21:37.086Z,1683242497.086 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-04T23:21:37.089Z,1683242497.089 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3985
2023-05-04T23:21:37.092Z,1683242497.092 [WetLabsBB2FL](INFO): Powering up
2023-05-04T23:21:37.094Z,1683242497.094 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3987
2023-05-04T23:21:37.097Z,1683242497.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-04T23:21:37.097Z,1683242497.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-04T23:21:37.097Z,1683242497.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-04T23:21:37.097Z,1683242497.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-04T23:21:37.097Z,1683242497.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-04T23:21:37.098Z,1683242497.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-04T23:21:37.098Z,1683242497.098 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-04T23:21:37.098Z,1683242497.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-04T23:21:37.108Z,1683242497.108 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-04T23:21:37.108Z,1683242497.108 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-04T23:21:37.144Z,1683242497.144 [CBIT](DEBUG): Backplane powered.
2023-05-04T23:21:37.145Z,1683242497.145 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-04T23:21:37.158Z,1683242497.158 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-04T23:21:37.177Z,1683242497.177 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-05-04T23:21:37.177Z,1683242497.177 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-04T23:21:37.253Z,1683242497.253 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-04T23:21:37.254Z,1683242497.254 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-04T23:21:37.276Z,1683242497.276 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-04T23:21:37.303Z,1683242497.303 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-04T23:21:37.306Z,1683242497.306 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-04T23:21:37.354Z,1683242497.354 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-04T23:21:37.358Z,1683242497.358 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-05-04T23:21:37.376Z,1683242497.376 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-04T23:21:37.382Z,1683242497.382 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-04T23:21:37.454Z,1683242497.454 [Power24vConverter](INFO): Powering up.
2023-05-04T23:21:37.468Z,1683242497.468 [Radio_Surface](INFO): Powering up
2023-05-04T23:21:37.501Z,1683242497.501 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:21:37.508Z,1683242497.508 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-04T23:21:37.509Z,1683242497.509 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:21:37.516Z,1683242497.516 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-04T23:21:37.517Z,1683242497.517 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:21:37.524Z,1683242497.524 [MassServo](DEBUG): Initializing MassServo.
2023-05-04T23:21:37.525Z,1683242497.525 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:21:37.540Z,1683242497.540 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-04T23:21:37.541Z,1683242497.541 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-04T23:21:37.548Z,1683242497.548 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-04T23:21:39.200Z,1683242499.200 [WetLabsBB2FL](INFO): Powering down
2023-05-04T23:21:43.305Z,1683242503.305 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-04T23:21:49.177Z,1683242509.177 [DATMMP](INFO): DAT read: 

2023-05-04T23:21:49.177Z,1683242509.177 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-04T23:21:50.941Z,1683242510.941 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-04T23:21:50.942Z,1683242510.942 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-04T23:21:50.942Z,1683242510.942 [DATMMP](INFO): DAT read: Apr  4 2000 23:21:44 

2023-05-04T23:21:52.201Z,1683242512.201 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-04T23:21:52.202Z,1683242512.202 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-04T23:21:52.202Z,1683242512.202 [DATMMP](INFO): commRate: 800
2023-05-04T23:21:54.268Z,1683242514.268 [DATMMP](INFO): entering command mode
2023-05-04T23:21:54.468Z,1683242514.468 [DATMMP](INFO): DAT read: 

2023-05-04T23:21:54.469Z,1683242514.469 [DATMMP](INFO): DAT read: user:1>

2023-05-04T23:21:54.469Z,1683242514.469 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-04T23:21:54.469Z,1683242514.469 [DATMMP](INFO): entering MMP mode
2023-05-04T23:21:54.721Z,1683242514.721 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:21:54.723Z,1683242514.723 [DATMMP](INFO): status rx: 	 x1
2023-05-04T23:21:54.723Z,1683242514.723 [DATMMP](INFO): MMP status: 1: started
2023-05-04T23:21:54.723Z,1683242514.723 [DATMMP](INFO): Received message type: status
2023-05-04T23:21:54.723Z,1683242514.723 [DATMMP](INFO): Handled
2023-05-04T23:21:54.973Z,1683242514.973 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-04T23:21:54.974Z,1683242514.974 [DATMMP](INFO): Setting local address: 5
2023-05-04T23:21:54.975Z,1683242514.975 [DATMMP](INFO): Setting time to: 23:21:54 And date to:5/4/2023
2023-05-04T23:21:55.224Z,1683242515.224 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:21:55.225Z,1683242515.225 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-04T23:21:55.225Z,1683242515.225 [DATMMP](INFO): Received message type: mdmpower
2023-05-04T23:21:55.226Z,1683242515.226 [DATMMP](INFO): Handled
2023-05-04T23:21:55.476Z,1683242515.476 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-04T23:21:55.477Z,1683242515.477 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-04T23:21:55.477Z,1683242515.477 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-04T23:21:55.477Z,1683242515.477 [DATMMP](INFO): Match for : sreg_txpower
2023-05-04T23:21:55.478Z,1683242515.478 [DATMMP](INFO): ACK
2023-05-04T23:21:55.728Z,1683242515.728 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:21:55.729Z,1683242515.729 [DATMMP](INFO): mdmlocal rx: 	 x5	 x0	 x0	 x0
2023-05-04T23:21:55.729Z,1683242515.729 [DATMMP](INFO): Received message type: mdmlocal
2023-05-04T23:21:55.730Z,1683242515.730 [DATMMP](INFO): Handled
2023-05-04T23:21:55.980Z,1683242515.980 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-04T23:21:55.981Z,1683242515.981 [DATMMP](INFO): sreg_locaddr rx: 	 x5
2023-05-04T23:21:55.981Z,1683242515.981 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-04T23:21:55.981Z,1683242515.981 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-04T23:21:55.981Z,1683242515.981 [DATMMP](INFO): ACK
2023-05-04T23:21:56.232Z,1683242516.232 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-04T23:21:56.235Z,1683242516.235 [DATMMP](INFO): timedate rx: 	 x0	 x17	 x15	 x36	 x4	 x4	 x7B	 x0
2023-05-04T23:21:56.236Z,1683242516.236 [DATMMP](INFO): Received message type: timedate
2023-05-04T23:21:56.237Z,1683242516.237 [DATMMP](INFO): Match for : timedate
2023-05-04T23:21:56.237Z,1683242516.237 [DATMMP](INFO): ACK
2023-05-04T23:21:56.484Z,1683242516.484 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:21:56.485Z,1683242516.485 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-04T23:21:56.485Z,1683242516.485 [DATMMP](INFO): Received message type: sys_verb
2023-05-04T23:21:56.486Z,1683242516.486 [DATMMP](INFO): Handled
2023-05-04T23:21:56.736Z,1683242516.736 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-04T23:21:56.737Z,1683242516.737 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-04T23:21:56.737Z,1683242516.737 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-04T23:21:56.737Z,1683242516.737 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-04T23:21:56.737Z,1683242516.737 [DATMMP](INFO): ACK
2023-05-04T23:21:56.988Z,1683242516.988 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-04T23:21:56.989Z,1683242516.989 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-04T23:21:56.989Z,1683242516.989 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-04T23:21:56.990Z,1683242516.990 [DATMMP](INFO): Received message type: version
2023-05-04T23:21:56.990Z,1683242516.990 [DATMMP](INFO): Match for : version
2023-05-04T23:21:56.990Z,1683242516.990 [DATMMP](INFO): ACK
2023-05-04T23:21:56.990Z,1683242516.990 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-04T23:22:05.674Z,1683242525.674 [NAL9602](INFO): Powering up NAL9602
2023-05-04T23:22:11.605Z,1683242531.605 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:22:11.605Z,1683242531.605 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-04T23:22:11.606Z,1683242531.606 [DATMMP](INFO): Received message type: timesync
2023-05-04T23:22:11.606Z,1683242531.606 [DATMMP](INFO): Handled
2023-05-04T23:22:16.590Z,1683242536.590 [NAL9602](INFO): NAL9602 initialized
2023-05-04T23:22:21.437Z,1683242541.437 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:22:21.438Z,1683242541.438 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-04T23:22:21.438Z,1683242541.438 [DATMMP](INFO): Received message type: timesync
2023-05-04T23:22:21.438Z,1683242541.438 [DATMMP](INFO): Handled
2023-05-04T23:22:58.646Z,1683242578.646 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-04T23:22:58.651Z,1683242578.651 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-04T23:23:09.534Z,1683242589.534 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010075
CHAN A1 (24V): -0.008204
CHAN A2 (12V): -0.005070
CHAN A3 (5V): -0.002221
CHAN B0 (3.3V): -0.001120
CHAN B1 (3.15aV): -0.001235
CHAN B2 (3.15bV): -0.001255
CHAN B3 (GND): -0.000454
OPEN: 0.004800
Full Scale: +/- 1 mA
2023-05-04T23:23:52.758Z,1683242632.758 [SBIT](IMPORTANT): SBIT PASSED
2023-05-04T23:23:52.758Z,1683242632.758 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-04T23:23:52.759Z,1683242632.759 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-04T23:23:52.760Z,1683242632.760 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-04T23:23:52.760Z,1683242632.760 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-04T23:23:52.760Z,1683242632.760 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-04T23:23:52.760Z,1683242632.760 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-04T23:23:52.760Z,1683242632.760 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-04T23:23:53.154Z,1683242633.154 [MissionManager](IMPORTANT): Started mission Startup
2023-05-04T23:23:53.155Z,1683242633.155 [Startup] Running Loop=1
2023-05-04T23:23:53.155Z,1683242633.155 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-04T23:23:53.155Z,1683242633.155 [Startup:A.GoToSurface] Running Loop=1
2023-05-04T23:23:53.155Z,1683242633.155 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T23:23:53.155Z,1683242633.155 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T23:23:53.176Z,1683242633.176 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T23:23:53.176Z,1683242633.176 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T23:23:53.177Z,1683242633.177 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T23:23:53.177Z,1683242633.177 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T23:23:53.178Z,1683242633.178 [Startup:StartupSatComms] Running Loop=1
2023-05-04T23:23:53.179Z,1683242633.179 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-04T23:23:53.179Z,1683242633.179 [Startup:StartupSatComms:A] Running Loop=1
2023-05-04T23:23:53.555Z,1683242633.555 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-04T23:24:04.050Z,1683242644.050 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:24:04.878Z,1683242644.878 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004852
2023-05-04T23:24:14.002Z,1683242654.002 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-04T23:24:14.005Z,1683242654.005 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-04T23:24:14.006Z,1683242654.006 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-04T23:24:14.009Z,1683242654.009 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-04T23:24:14.010Z,1683242654.010 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-04T23:24:14.013Z,1683242654.013 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-04T23:24:14.181Z,1683242654.181 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-04T23:24:14.567Z,1683242654.567 [Startup:StartupSatComms:A] Stopped
2023-05-04T23:24:14.567Z,1683242654.567 [Startup:StartupSatComms:B] Running Loop=1
2023-05-04T23:24:15.010Z,1683242655.010 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-04T23:24:17.457Z,1683242657.457 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:24:23.860Z,1683242663.860 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230504T230959/Courier0007.lzma
2023-05-04T23:24:24.280Z,1683242664.280 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683242657 second
2023-05-04T23:24:24.862Z,1683242664.862 [DataOverHttps](INFO): Moved sent file to Logs/20230504T230959/Courier0007.lzma.bak
2023-05-04T23:24:24.862Z,1683242664.862 [DataOverHttps](INFO): SBD MOMSN=18281996
2023-05-04T23:24:29.177Z,1683242669.177 [CommandExec](IMPORTANT): got command show stack
2023-05-04T23:24:29.178Z,1683242669.178 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-04T23:24:29.178Z,1683242669.178 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2023-05-04T23:24:29.178Z,1683242669.178 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2023-05-04T23:24:30.789Z,1683242670.789 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:24:39.743Z,1683242679.743 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2023-05-04T23:24:40.129Z,1683242680.129 [Startup:StartupSatComms:B] Stopped
2023-05-04T23:24:40.129Z,1683242680.129 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-04T23:24:40.130Z,1683242680.130 [Startup:StartupSatComms] Stopped
2023-05-04T23:24:40.130Z,1683242680.130 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-04T23:24:40.130Z,1683242680.130 [Startup](INFO): Completed Startup
2023-05-04T23:24:40.131Z,1683242680.131 [MissionManager](INFO): Startup is completed.
2023-05-04T23:24:40.131Z,1683242680.131 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-04T23:24:40.131Z,1683242680.131 [Startup] Stopped
2023-05-04T23:24:40.131Z,1683242680.131 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-04T23:24:40.131Z,1683242680.131 [Startup:A.GoToSurface] Stopped
2023-05-04T23:24:40.131Z,1683242680.131 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:24:40.509Z,1683242680.509 [MissionManager](IMPORTANT): Started mission Default
2023-05-04T23:24:40.509Z,1683242680.509 [Default] Running Loop=1
2023-05-04T23:24:40.510Z,1683242680.510 [Default](DEBUG): Aggregate::initialize Default
2023-05-04T23:24:40.510Z,1683242680.510 [Default:B.GoToSurface] Running Loop=1
2023-05-04T23:24:40.510Z,1683242680.510 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T23:24:40.510Z,1683242680.510 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T23:24:40.510Z,1683242680.510 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T23:24:40.511Z,1683242680.511 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T23:24:40.511Z,1683242680.511 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T23:24:40.511Z,1683242680.511 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T23:24:40.511Z,1683242680.511 [Default:A.Wait] Running Loop=1
2023-05-04T23:24:40.512Z,1683242680.512 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:24:43.876Z,1683242683.876 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230504T232127/Courier0000.lzma
2023-05-04T23:24:44.186Z,1683242684.186 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:24:44.878Z,1683242684.878 [DataOverHttps](INFO): Moved sent file to Logs/20230504T232127/Courier0000.lzma.bak
2023-05-04T23:24:44.878Z,1683242684.878 [DataOverHttps](INFO): SBD MOMSN=18281998
2023-05-04T23:24:52.807Z,1683242692.807 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-04T23:24:53.827Z,1683242693.827 [Default:A.Wait](INFO): Done Waiting.
2023-05-04T23:24:53.827Z,1683242693.827 [Default:A.Wait] Stopped
2023-05-04T23:24:53.827Z,1683242693.827 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:24:54.233Z,1683242694.233 [Default:CheckIn] Running Loop=1
2023-05-04T23:24:54.233Z,1683242694.233 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:24:54.233Z,1683242694.233 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:24:54.647Z,1683242694.647 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-04T23:24:57.522Z,1683242697.522 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:24:58.429Z,1683242698.429 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T23:24:58.429Z,1683242698.429 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T23:24:58.491Z,1683242698.491 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 6.000000 count
2023-05-04T23:24:58.494Z,1683242698.494 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m
2023-05-04T23:24:58.497Z,1683242698.497 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s
2023-05-04T23:24:58.500Z,1683242698.500 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count
2023-05-04T23:24:58.503Z,1683242698.503 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count
2023-05-04T23:24:58.506Z,1683242698.506 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count
2023-05-04T23:24:58.510Z,1683242698.510 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min
2023-05-04T23:24:58.513Z,1683242698.513 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 6.000000 enum
2023-05-04T23:24:58.518Z,1683242698.518 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum
2023-05-04T23:24:58.521Z,1683242698.521 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum
2023-05-04T23:24:58.616Z,1683242698.616 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Construct Wait.
2023-05-04T23:24:58.634Z,1683242698.634 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Sensor="Tethys/Sensor"
    xmlns:Units="Tethys/Units"
    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
    xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                        Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                        Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
    Id="senddata_direct_and_track_test">


    <DefineArg Name="AcousticTargetContactLabel"><Description>
        The acoustic address of the asset to be tracked. 21 is waveglider Tiny
    </Description><Units:count/><Value>6</Value></DefineArg>

    <DefineArg Name="AcousticTargetDepth"><Description>
        Depth of acoustic target if known and fixed (or nearly fixed). For
        example, if the acoustic target is a Wave Glider, set it to zero. This
        will improve 2D projected position estimates in the Earth reference
        frame. Defaults to NaN.
    </Description><Units:meter/><Value>0</Value></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>15</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of return pings to request with each acoustic query (more than 1
        will activate oneway mode)
    </Description><Units:count/><Value>1</Value></DefineArg>    

    <DefineArg Name="NumberOfFixesLowPass"><Description>
        Number of fixes to average to produce smoothed lat/lon/dep output
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="NumberOfStartingFixesToIgnore"><Description>
        Number of fixes to ignore at the start of mission (as the vehicle just
        leaves surface, contact's location estimate especailly bearing can be
        erroneous. Initialized to 8, corresponding to 2 minutes if query
        interval TrackingUpdatePeriod = 15 s.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="AcommsInterval"><Description></Description><Units:minute/><Value>1</Value></DefineArg>    
    
    <DefineArg Name="modemId1"><Description>
        Modem ID1.
    </Description><Units:enum/><Value>6</Value></DefineArg>

    <DefineArg Name="modemId2"><Description>
        Modem ID2.
    </Description><Units:enum/><Value>5</Value></DefineArg>

    <DefineOutput Name="ModemID"><Description>
        Modem ID. Set to modemId1 or modemId2. Initialized to 1.
    </Description><Units:enum/><Value>1</Value></DefineOutput>            


        <Estimation:Tracking>
            <Parallel/>
            <Setting><Estimation:Tracking.contactLabelSetting/><Arg Name="AcousticTargetContactLabel"/></Setting>
            <Setting><Estimation:Tracking.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:Tracking.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
            <Setting><Estimation:Tracking.numFixesLowPassSetting/><Arg Name="NumberOfFixesLowPass"/></Setting>
            <Setting><Estimation:Tracking.numStartingFixesToIgnoreSetting/><Arg Name="NumberOfStartingFixesToIgnore"/></Setting>
            <Setting><Estimation:Tracking.contactDepthSetting/><Arg Name="AcousticTargetDepth"/></Setting>
        </Estimation:Tracking>



  <Aggregate Id="DirectData">
  <Sequence Repeat="4"/>
    <Guidance:Wait>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Arg Name="AcommsInterval"/></Setting>
    </Guidance:Wait>
                
    <SendData Service="Direct" Destination="modem:6:_.pressure">
        <Sensor:Onboard.Pressure/><Units:atmosphere/>
    </SendData>

    <SendData Service="Direct" Destination="modem:6:_.temperature">
        <Sensor:Onboard.Temperature/><Units:celsius/>
    </SendData>

    <SendData Service="Direct" Destination="modem:6:_.humidity">
        <Sensor:Onboard.Humidity/><Units:percent/>
    </SendData>

  </Aggregate>

</Mission>


2023-05-04T23:24:58.641Z,1683242698.641 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T23:24:58.722Z,1683242698.722 [Default] Stopped
2023-05-04T23:24:58.722Z,1683242698.722 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T23:24:58.722Z,1683242698.722 [Default:B.GoToSurface] Stopped
2023-05-04T23:24:58.722Z,1683242698.722 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:24:58.723Z,1683242698.723 [Default:CheckIn] Stopped
2023-05-04T23:24:58.723Z,1683242698.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:24:58.723Z,1683242698.723 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:24:58.723Z,1683242698.723 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test
2023-05-04T23:24:58.723Z,1683242698.723 [senddata_direct_and_track_test] Running Loop=1
2023-05-04T23:24:58.723Z,1683242698.723 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test
2023-05-04T23:24:58.723Z,1683242698.723 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-04T23:24:58.723Z,1683242698.723 [senddata_direct_and_track_test:A.](INFO): Initializing Tracking.
2023-05-04T23:24:58.732Z,1683242698.732 [senddata_direct_and_track_test:DirectData] Running Loop=1
2023-05-04T23:24:58.732Z,1683242698.732 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-04T23:24:58.732Z,1683242698.732 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-04T23:24:58.732Z,1683242698.732 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:24:58.733Z,1683242698.733 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-04T23:24:58.733Z,1683242698.733 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:24:58.734Z,1683242698.734 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:24:59.101Z,1683242699.101 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:24:59.102Z,1683242699.102 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:24:59.488Z,1683242699.488 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:24:59.489Z,1683242699.489 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:24:59.888Z,1683242699.888 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:24:59.889Z,1683242699.889 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:00.305Z,1683242700.305 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:00.305Z,1683242700.305 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:00.715Z,1683242700.715 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:00.716Z,1683242700.716 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:01.103Z,1683242701.103 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:01.104Z,1683242701.104 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:01.580Z,1683242701.580 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:01.580Z,1683242701.580 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:02.070Z,1683242702.070 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:02.070Z,1683242702.070 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:02.330Z,1683242702.330 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:02.331Z,1683242702.331 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:02.730Z,1683242702.730 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:02.731Z,1683242702.731 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:03.148Z,1683242703.148 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:03.149Z,1683242703.149 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:03.522Z,1683242703.522 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:03.523Z,1683242703.523 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:03.931Z,1683242703.931 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:03.932Z,1683242703.932 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:04.340Z,1683242704.340 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:04.340Z,1683242704.340 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:04.743Z,1683242704.743 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:04.744Z,1683242704.744 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:05.151Z,1683242705.151 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:05.151Z,1683242705.151 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:05.547Z,1683242705.547 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:05.548Z,1683242705.548 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:05.947Z,1683242705.947 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:05.948Z,1683242705.948 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:06.353Z,1683242706.353 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:06.354Z,1683242706.354 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:06.767Z,1683242706.767 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:06.767Z,1683242706.767 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:07.183Z,1683242707.183 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:07.184Z,1683242707.184 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:07.581Z,1683242707.581 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:07.581Z,1683242707.581 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:07.989Z,1683242707.989 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:07.990Z,1683242707.990 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:08.384Z,1683242708.384 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:08.384Z,1683242708.384 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:08.779Z,1683242708.779 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:08.780Z,1683242708.780 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:09.187Z,1683242709.187 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:09.188Z,1683242709.188 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:09.591Z,1683242709.591 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:09.592Z,1683242709.592 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:09.999Z,1683242709.999 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:09.000Z,1683242710.000 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:10.462Z,1683242710.462 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:10.462Z,1683242710.462 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:10.848Z,1683242710.848 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:25:10.860Z,1683242710.860 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:10.861Z,1683242710.861 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:11.228Z,1683242711.228 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:11.228Z,1683242711.228 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:11.635Z,1683242711.635 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:11.637Z,1683242711.637 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:12.031Z,1683242712.031 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:12.032Z,1683242712.032 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:12.426Z,1683242712.426 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:12.427Z,1683242712.427 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:12.841Z,1683242712.841 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:12.842Z,1683242712.842 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:13.237Z,1683242713.237 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:13.238Z,1683242713.238 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:13.652Z,1683242713.652 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:13.653Z,1683242713.653 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:14.031Z,1683242714.031 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:14.032Z,1683242714.032 [senddata_direct_and_track_test:A.](INFO): update period (15.000000 s) has elapsed, querying...
2023-05-04T23:25:14.032Z,1683242714.032 [senddata_direct_and_track_test:A.](INFO): *** querying DAT ***
2023-05-04T23:25:14.161Z,1683242714.161 [DATMMP](INFO): ****** received valid address query ******
2023-05-04T23:25:14.161Z,1683242714.161 [DATMMP](INFO): ****** received valid ping request ******
2023-05-04T23:25:14.161Z,1683242714.161 [DATMMP](INFO): Querying Benthos address 6
2023-05-04T23:25:14.413Z,1683242714.413 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T23:25:14.414Z,1683242714.414 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x0	 x2
2023-05-04T23:25:14.414Z,1683242714.414 [DATMMP](INFO): Progress on cmd
2023-05-04T23:25:14.414Z,1683242714.414 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:25:14.414Z,1683242714.414 [DATMMP](INFO): Handled
2023-05-04T23:25:14.414Z,1683242714.414 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:14.475Z,1683242714.475 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:14.475Z,1683242714.476 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:14.665Z,1683242714.665 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:14.666Z,1683242714.666 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 x19	 xC	 xD22
2023-05-04T23:25:14.666Z,1683242714.666 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:25:14.666Z,1683242714.666 [DATMMP](INFO): Handled
2023-05-04T23:25:14.666Z,1683242714.666 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:14.851Z,1683242714.851 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:14.852Z,1683242714.852 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:14.917Z,1683242714.917 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:14.917Z,1683242714.917 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:25:14.917Z,1683242714.917 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:25:14.918Z,1683242714.918 [DATMMP](INFO): Handled
2023-05-04T23:25:14.918Z,1683242714.918 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:15.168Z,1683242715.168 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:15.239Z,1683242715.239 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:15.240Z,1683242715.240 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:15.421Z,1683242715.421 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:15.645Z,1683242715.645 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:15.646Z,1683242715.646 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:15.673Z,1683242715.673 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:15.924Z,1683242715.924 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:16.049Z,1683242716.049 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:16.050Z,1683242716.050 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:16.176Z,1683242716.176 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:16.429Z,1683242716.429 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:16.469Z,1683242716.469 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:16.470Z,1683242716.470 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:16.681Z,1683242716.681 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:16.862Z,1683242716.862 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:16.862Z,1683242716.862 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:16.932Z,1683242716.932 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:17.185Z,1683242717.185 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:17.185Z,1683242717.185 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:25:17.185Z,1683242717.185 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:25:17.185Z,1683242717.185 [DATMMP](INFO): Handled
2023-05-04T23:25:17.186Z,1683242717.186 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:17.326Z,1683242717.326 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:17.327Z,1683242717.327 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:17.437Z,1683242717.437 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:17.437Z,1683242717.437 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:25:17.437Z,1683242717.437 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:25:17.438Z,1683242717.438 [DATMMP](INFO): Handled
2023-05-04T23:25:17.438Z,1683242717.438 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:17.689Z,1683242717.689 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:17.690Z,1683242717.690 [DATMMP](INFO): directional_debug rx: 	 x461	 xD2	 x2B	 x40	 x23F0	 x27A1	 x2712	 x36A3
2023-05-04T23:25:17.691Z,1683242717.691 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:25:17.691Z,1683242717.691 [DATMMP](INFO): Handled
2023-05-04T23:25:17.692Z,1683242717.692 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:17.694Z,1683242717.694 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:17.695Z,1683242717.695 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:25:17.941Z,1683242717.941 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:17.943Z,1683242717.943 [DATMMP](INFO): directional rx: 	 x44C	 xA7	 x17B	 xFF2E	 x5E8	 xFF3B	 x6EC8	 x468	 x3	 xFFEE	 x27B5966	 x-43569E0
2023-05-04T23:25:17.945Z,1683242717.945 [DATMMP](INFO): Received message type: directional
2023-05-04T23:25:17.945Z,1683242717.945 [DATMMP](INFO): Handled
2023-05-04T23:25:17.945Z,1683242717.945 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:18.099Z,1683242718.099 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:18.101Z,1683242718.101 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:18.102Z,1683242718.102 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:18.111Z,1683242718.111 [Reporter](INFO): DATMMP.acoustic_contact_range 28360.000000 m
2023-05-04T23:25:18.193Z,1683242718.193 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T23:25:18.194Z,1683242718.194 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x1	 x6
2023-05-04T23:25:18.194Z,1683242718.194 [DATMMP](INFO): Progress on cmd
2023-05-04T23:25:18.194Z,1683242718.194 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:25:18.194Z,1683242718.194 [DATMMP](INFO): Handled
2023-05-04T23:25:18.194Z,1683242718.194 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:18.445Z,1683242718.445 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T23:25:18.445Z,1683242718.445 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x2	 x3
2023-05-04T23:25:18.446Z,1683242718.446 [DATMMP](ERROR): 3:COMPLETE
2023-05-04T23:25:18.446Z,1683242718.446 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:25:18.446Z,1683242718.446 [DATMMP](INFO): Handled
2023-05-04T23:25:18.446Z,1683242718.446 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:18.516Z,1683242718.516 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:18.517Z,1683242718.517 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:18.518Z,1683242718.518 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:18.697Z,1683242718.697 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:25:18.698Z,1683242718.698 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:25:18.698Z,1683242718.698 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:25:18.698Z,1683242718.698 [DATMMP](INFO): Handled
2023-05-04T23:25:18.698Z,1683242718.698 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:18.878Z,1683242718.878 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:18.879Z,1683242718.879 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:18.879Z,1683242718.879 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:18.949Z,1683242718.949 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:19.200Z,1683242719.200 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:19.288Z,1683242719.288 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:19.289Z,1683242719.289 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:19.290Z,1683242719.290 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:19.452Z,1683242719.452 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:19.703Z,1683242719.703 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:19.704Z,1683242719.704 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:19.705Z,1683242719.705 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:19.706Z,1683242719.706 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:19.957Z,1683242719.957 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:20.087Z,1683242720.087 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:20.088Z,1683242720.088 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:20.089Z,1683242720.089 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:20.212Z,1683242720.212 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:20.460Z,1683242720.460 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:20.498Z,1683242720.498 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:20.499Z,1683242720.499 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:20.500Z,1683242720.500 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:20.713Z,1683242720.713 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:20.907Z,1683242720.907 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:20.908Z,1683242720.908 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:20.909Z,1683242720.909 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:20.965Z,1683242720.965 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:21.216Z,1683242721.216 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:21.299Z,1683242721.299 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:21.300Z,1683242721.300 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:21.301Z,1683242721.301 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:21.468Z,1683242721.468 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:21.711Z,1683242721.711 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:21.712Z,1683242721.712 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:21.713Z,1683242721.713 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:21.721Z,1683242721.721 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:21.972Z,1683242721.972 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:22.113Z,1683242722.113 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:22.113Z,1683242722.113 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:22.114Z,1683242722.114 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:22.224Z,1683242722.224 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:22.476Z,1683242722.476 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:22.537Z,1683242722.537 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:22.538Z,1683242722.538 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:22.539Z,1683242722.539 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:22.728Z,1683242722.728 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:22.943Z,1683242722.943 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:22.944Z,1683242722.944 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:22.945Z,1683242722.945 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:22.980Z,1683242722.980 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:23.232Z,1683242723.232 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:23.368Z,1683242723.368 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:23.369Z,1683242723.369 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:23.370Z,1683242723.370 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:23.484Z,1683242723.484 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:23.736Z,1683242723.736 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:23.865Z,1683242723.865 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:23.866Z,1683242723.866 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:23.867Z,1683242723.867 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:23.988Z,1683242723.988 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:24.229Z,1683242724.229 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:25:24.241Z,1683242724.241 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:24.270Z,1683242724.270 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:24.271Z,1683242724.271 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:24.280Z,1683242724.280 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:24.492Z,1683242724.492 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:25:24.563Z,1683242724.563 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:24.564Z,1683242724.564 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:24.565Z,1683242724.565 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:24.977Z,1683242724.977 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:24.978Z,1683242724.978 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:24.979Z,1683242724.979 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:25.373Z,1683242725.373 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:25.374Z,1683242725.374 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:25.375Z,1683242725.375 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:25.792Z,1683242725.792 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:25.793Z,1683242725.793 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:25.794Z,1683242725.794 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:26.161Z,1683242726.161 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:26.162Z,1683242726.162 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:26.163Z,1683242726.163 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:26.557Z,1683242726.557 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:26.558Z,1683242726.558 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:26.559Z,1683242726.559 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:26.959Z,1683242726.959 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:26.960Z,1683242726.960 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:26.961Z,1683242726.961 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:27.367Z,1683242727.367 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:27.368Z,1683242727.368 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:27.369Z,1683242727.369 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:27.789Z,1683242727.789 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:27.789Z,1683242727.789 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:27.790Z,1683242727.790 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:28.187Z,1683242728.187 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:28.188Z,1683242728.188 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:28.188Z,1683242728.188 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:28.596Z,1683242728.596 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:28.597Z,1683242728.597 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:28.598Z,1683242728.598 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:28.993Z,1683242728.993 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:28.994Z,1683242728.994 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:28.995Z,1683242728.995 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:29.384Z,1683242729.384 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:29.385Z,1683242729.385 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:29.386Z,1683242729.386 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:29.792Z,1683242729.792 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:29.793Z,1683242729.793 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:29.794Z,1683242729.794 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:30.192Z,1683242730.192 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:30.193Z,1683242730.193 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:30.194Z,1683242730.194 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:30.595Z,1683242730.595 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:30.596Z,1683242730.596 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:30.597Z,1683242730.597 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:30.995Z,1683242730.995 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:30.997Z,1683242730.997 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:30.997Z,1683242730.997 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:31.399Z,1683242731.399 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:31.400Z,1683242731.400 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:31.401Z,1683242731.401 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:31.805Z,1683242731.805 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:31.806Z,1683242731.806 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:31.806Z,1683242731.806 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:32.239Z,1683242732.239 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:32.244Z,1683242732.244 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:32.245Z,1683242732.245 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:32.612Z,1683242732.612 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:32.614Z,1683242732.614 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:32.614Z,1683242732.614 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:33.061Z,1683242733.061 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:33.062Z,1683242733.062 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:33.063Z,1683242733.063 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:33.453Z,1683242733.453 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:33.454Z,1683242733.454 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:33.455Z,1683242733.455 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:33.845Z,1683242733.845 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:33.846Z,1683242733.846 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:33.847Z,1683242733.847 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:34.254Z,1683242734.254 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:34.255Z,1683242734.255 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:34.256Z,1683242734.256 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:34.636Z,1683242734.636 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:34.637Z,1683242734.637 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:34.638Z,1683242734.638 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:35.044Z,1683242735.044 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:35.045Z,1683242735.045 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:35.045Z,1683242735.045 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:35.451Z,1683242735.451 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:35.452Z,1683242735.452 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:35.453Z,1683242735.453 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:35.844Z,1683242735.844 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:35.845Z,1683242735.845 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:35.846Z,1683242735.846 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:36.335Z,1683242736.335 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:36.344Z,1683242736.344 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:36.345Z,1683242736.345 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:36.661Z,1683242736.661 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:36.662Z,1683242736.662 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:36.662Z,1683242736.662 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:37.120Z,1683242737.120 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:37.121Z,1683242737.121 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:37.122Z,1683242737.122 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:37.511Z,1683242737.511 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:25:37.546Z,1683242737.546 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:37.546Z,1683242737.546 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:37.547Z,1683242737.547 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:37.908Z,1683242737.908 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:37.909Z,1683242737.909 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:37.910Z,1683242737.910 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:38.318Z,1683242738.318 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:38.319Z,1683242738.319 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:38.320Z,1683242738.320 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:38.709Z,1683242738.709 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:38.710Z,1683242738.710 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:38.711Z,1683242738.711 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:39.102Z,1683242739.102 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:39.103Z,1683242739.103 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:39.104Z,1683242739.104 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:39.498Z,1683242739.498 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:39.498Z,1683242739.498 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:39.499Z,1683242739.499 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:39.891Z,1683242739.891 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:39.892Z,1683242739.892 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:39.893Z,1683242739.893 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:40.303Z,1683242740.303 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:40.303Z,1683242740.303 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:40.304Z,1683242740.304 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:40.691Z,1683242740.691 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:40.693Z,1683242740.693 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:40.694Z,1683242740.694 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:41.099Z,1683242741.099 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:41.101Z,1683242741.101 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:41.101Z,1683242741.101 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:41.503Z,1683242741.503 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:41.504Z,1683242741.504 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:41.505Z,1683242741.505 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:41.914Z,1683242741.914 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:41.915Z,1683242741.915 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:41.916Z,1683242741.916 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:42.309Z,1683242742.309 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:42.309Z,1683242742.309 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:42.310Z,1683242742.310 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:42.715Z,1683242742.715 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:42.716Z,1683242742.716 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:42.717Z,1683242742.717 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:43.117Z,1683242743.117 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:43.118Z,1683242743.118 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:43.119Z,1683242743.119 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:43.534Z,1683242743.534 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:43.535Z,1683242743.535 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:43.536Z,1683242743.536 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:43.946Z,1683242743.946 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:43.947Z,1683242743.947 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:43.948Z,1683242743.948 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:44.345Z,1683242744.345 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:44.346Z,1683242744.346 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:44.347Z,1683242744.347 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:44.749Z,1683242744.749 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:44.750Z,1683242744.750 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:44.751Z,1683242744.751 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:45.147Z,1683242745.147 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:45.148Z,1683242745.148 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:45.148Z,1683242745.148 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:45.539Z,1683242745.539 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:45.540Z,1683242745.540 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:45.541Z,1683242745.541 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:45.951Z,1683242745.951 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:45.953Z,1683242745.953 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:45.953Z,1683242745.953 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:46.351Z,1683242746.351 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:46.352Z,1683242746.352 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:46.353Z,1683242746.353 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:46.762Z,1683242746.762 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:46.763Z,1683242746.763 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:46.764Z,1683242746.764 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:47.159Z,1683242747.159 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:47.160Z,1683242747.160 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:47.161Z,1683242747.161 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:47.563Z,1683242747.563 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:47.564Z,1683242747.564 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:47.565Z,1683242747.565 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:47.971Z,1683242747.971 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:47.972Z,1683242747.972 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:47.973Z,1683242747.973 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:48.378Z,1683242748.378 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:48.379Z,1683242748.379 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:48.379Z,1683242748.379 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:48.794Z,1683242748.794 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:48.795Z,1683242748.795 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:48.796Z,1683242748.796 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:49.214Z,1683242749.214 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:49.215Z,1683242749.215 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:49.216Z,1683242749.216 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:49.602Z,1683242749.602 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:49.603Z,1683242749.603 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:49.603Z,1683242749.603 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:49.998Z,1683242749.998 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:49.999Z,1683242749.999 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:50.000Z,1683242750.000 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:50.461Z,1683242750.461 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:50.462Z,1683242750.462 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:50.462Z,1683242750.462 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:50.781Z,1683242750.781 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:25:50.793Z,1683242750.793 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:50.794Z,1683242750.794 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:50.795Z,1683242750.795 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:51.297Z,1683242751.297 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:51.298Z,1683242751.298 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:51.299Z,1683242751.299 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:51.628Z,1683242751.628 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:51.629Z,1683242751.629 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:51.630Z,1683242751.630 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:52.029Z,1683242752.029 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:52.030Z,1683242752.030 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:52.031Z,1683242752.031 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:52.439Z,1683242752.439 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:52.439Z,1683242752.439 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:52.440Z,1683242752.440 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:52.819Z,1683242752.819 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:52.821Z,1683242752.821 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:52.821Z,1683242752.821 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:53.220Z,1683242753.220 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:53.221Z,1683242753.221 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:53.222Z,1683242753.222 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:53.635Z,1683242753.635 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:53.636Z,1683242753.636 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:53.637Z,1683242753.637 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:54.051Z,1683242754.051 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:54.052Z,1683242754.052 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:54.053Z,1683242754.053 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:54.448Z,1683242754.448 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:54.449Z,1683242754.449 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:54.450Z,1683242754.450 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:54.846Z,1683242754.846 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:54.847Z,1683242754.847 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:54.848Z,1683242754.848 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:55.255Z,1683242755.255 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:55.255Z,1683242755.255 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:55.256Z,1683242755.256 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:55.644Z,1683242755.644 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:55.645Z,1683242755.645 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:55.645Z,1683242755.645 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:56.047Z,1683242756.047 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:56.048Z,1683242756.048 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:56.049Z,1683242756.049 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:56.455Z,1683242756.455 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:56.456Z,1683242756.456 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:56.457Z,1683242756.457 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:56.866Z,1683242756.866 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:56.867Z,1683242756.867 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:56.868Z,1683242756.868 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:57.286Z,1683242757.286 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:57.287Z,1683242757.287 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:57.288Z,1683242757.288 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:57.665Z,1683242757.665 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:57.666Z,1683242757.666 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:57.667Z,1683242757.667 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:58.075Z,1683242758.075 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:58.077Z,1683242758.077 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:58.077Z,1683242758.077 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:58.471Z,1683242758.471 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:58.472Z,1683242758.472 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:58.473Z,1683242758.473 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:58.889Z,1683242758.889 [senddata_direct_and_track_test:DirectData:A.Wait](INFO): Done Waiting.
2023-05-04T23:25:58.889Z,1683242758.889 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-04T23:25:58.889Z,1683242758.889 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:25:58.890Z,1683242758.890 [senddata_direct_and_track_test:DirectData:B] Running Loop=1
2023-05-04T23:25:58.890Z,1683242758.890 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:58.891Z,1683242758.891 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:58.892Z,1683242758.892 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:59.306Z,1683242759.306 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Pressure with destination: modem:6:_.pressure
2023-05-04T23:25:59.306Z,1683242759.306 [senddata_direct_and_track_test:DirectData:B] Stopped
2023-05-04T23:25:59.306Z,1683242759.306 [senddata_direct_and_track_test:DirectData:C] Running Loop=1
2023-05-04T23:25:59.306Z,1683242759.306 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:59.307Z,1683242759.307 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:59.312Z,1683242759.312 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:59.529Z,1683242759.529 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-04T23:25:59.530Z,1683242759.530 [DATMMP](INFO): modem://6: set _.pressure 0.742639 atmosphere
2023-05-04T23:25:59.530Z,1683242759.530 [DATMMP](INFO): #Outgoing data=1
2023-05-04T23:25:59.537Z,1683242759.537 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-04T23:25:59.721Z,1683242759.721 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Temperature with destination: modem:6:_.temperature
2023-05-04T23:25:59.721Z,1683242759.721 [senddata_direct_and_track_test:DirectData:C] Stopped
2023-05-04T23:25:59.721Z,1683242759.721 [senddata_direct_and_track_test:DirectData:D] Running Loop=1
2023-05-04T23:25:59.721Z,1683242759.721 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:25:59.722Z,1683242759.722 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:25:59.723Z,1683242759.723 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:25:59.781Z,1683242759.781 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-04T23:25:59.782Z,1683242759.782 [DATMMP](INFO): depth rx: 	 x1	 x1	 x4	 x0
2023-05-04T23:25:59.782Z,1683242759.782 [DATMMP](INFO): Received message type: depth
2023-05-04T23:25:59.782Z,1683242759.782 [DATMMP](INFO): Match for : depth
2023-05-04T23:25:59.782Z,1683242759.782 [DATMMP](INFO): ACK
2023-05-04T23:25:59.782Z,1683242759.782 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:26:00.033Z,1683242760.033 [DATMMP](INFO): GSXN notify for xid: 17
2023-05-04T23:26:00.033Z,1683242760.033 [DATMMP](INFO): lat rx: 	 x2318600	 x6	 x1	 x0
2023-05-04T23:26:00.034Z,1683242760.034 [DATMMP](INFO): Received message type: lat
2023-05-04T23:26:00.034Z,1683242760.034 [DATMMP](INFO): Match for : lat
2023-05-04T23:26:00.034Z,1683242760.034 [DATMMP](INFO): ACK
2023-05-04T23:26:00.034Z,1683242760.034 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:26:00.158Z,1683242760.158 [senddata_direct_and_track_test:DirectData:D](INFO): Queuing up send data for Onboard.Humidity with destination: modem:6:_.humidity
2023-05-04T23:26:00.158Z,1683242760.158 [senddata_direct_and_track_test:DirectData:D] Stopped
2023-05-04T23:26:00.158Z,1683242760.158 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData
2023-05-04T23:26:00.158Z,1683242760.158 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:DirectData](INFO): Running loop #2
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:DirectData] Running Loop=2
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:26:00.159Z,1683242760.159 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:26:00.164Z,1683242760.164 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:26:00.165Z,1683242760.165 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:26:00.285Z,1683242760.285 [DATMMP](INFO): GSXN notify for xid: 18
2023-05-04T23:26:00.369Z,1683242760.369 [DATMMP](INFO): lon rx: 	 x0	 x6	 x1	 x0
2023-05-04T23:26:00.369Z,1683242760.369 [DATMMP](INFO): Received message type: lon
2023-05-04T23:26:00.369Z,1683242760.369 [DATMMP](INFO): Match for : lon
2023-05-04T23:26:00.369Z,1683242760.369 [DATMMP](INFO): ACK
2023-05-04T23:26:00.369Z,1683242760.369 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:26:00.369Z,1683242760.369 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-04T23:26:00.509Z,1683242760.509 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:26:00.510Z,1683242760.510 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:26:00.510Z,1683242760.510 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:26:00.537Z,1683242760.537 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-04T23:26:00.537Z,1683242760.537 [DATMMP](INFO): Tx to 6 len 34 xid 19
2023-05-04T23:26:00.538Z,1683242760.538 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-04T23:26:00.538Z,1683242760.538 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-04T23:26:00.789Z,1683242760.789 [DATMMP](INFO): GSXN notify for xid: 19
2023-05-04T23:26:00.790Z,1683242760.790 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-04T23:26:00.790Z,1683242760.790 [DATMMP](DEBUG): Command complete send
2023-05-04T23:26:00.790Z,1683242760.790 [DATMMP](ERROR): 1:COMPLETE
2023-05-04T23:26:00.790Z,1683242760.790 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:26:00.791Z,1683242760.791 [DATMMP](INFO): Handled
2023-05-04T23:26:00.791Z,1683242760.791 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:00.931Z,1683242760.931 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:26:00.931Z,1683242760.931 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:26:00.932Z,1683242760.932 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:26:01.041Z,1683242761.041 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:01.042Z,1683242761.042 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 x19	 x3A	 x1EA8
2023-05-04T23:26:01.042Z,1683242761.042 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:26:01.042Z,1683242761.042 [DATMMP](INFO): Handled
2023-05-04T23:26:01.042Z,1683242761.042 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:01.042Z,1683242761.042 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:01.293Z,1683242761.293 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:01.330Z,1683242761.330 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:26:01.331Z,1683242761.331 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:26:01.332Z,1683242761.332 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:26:01.544Z,1683242761.544 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:01.545Z,1683242761.545 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:01.713Z,1683242761.713 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:26:01.714Z,1683242761.714 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:26:01.715Z,1683242761.715 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:26:01.781Z,1683242761.781 [CommandExec](IMPORTANT): got command stop
2023-05-04T23:26:01.781Z,1683242761.781 [CommandExec](IMPORTANT): Scheduling is paused
2023-05-04T23:26:01.781Z,1683242761.781 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-05-04T23:26:01.797Z,1683242761.797 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:01.797Z,1683242761.797 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:02.048Z,1683242762.048 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:02.049Z,1683242762.049 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:02.165Z,1683242762.165 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:26:02.166Z,1683242762.166 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242717.940753 .And dataStartTime_:1683242714.032346
2023-05-04T23:26:02.166Z,1683242762.166 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:26:02.166Z,1683242762.166 [MissionManager](INFO): MissionManager is completed.
2023-05-04T23:26:02.167Z,1683242762.167 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test] Stopped
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test:A.] Stopped
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test:A.](DEBUG): Uninitializing Tracking.
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-04T23:26:02.167Z,1683242762.167 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:26:02.300Z,1683242762.300 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:02.301Z,1683242762.301 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:02.531Z,1683242762.531 [MissionManager](IMPORTANT): Started mission Default
2023-05-04T23:26:02.532Z,1683242762.532 [Default] Running Loop=1
2023-05-04T23:26:02.536Z,1683242762.536 [Default](DEBUG): Aggregate::initialize Default
2023-05-04T23:26:02.536Z,1683242762.536 [Default:B.GoToSurface] Running Loop=1
2023-05-04T23:26:02.536Z,1683242762.536 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T23:26:02.536Z,1683242762.536 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T23:26:02.537Z,1683242762.537 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T23:26:02.537Z,1683242762.537 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T23:26:02.537Z,1683242762.537 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T23:26:02.538Z,1683242762.538 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T23:26:02.538Z,1683242762.538 [Default:A.Wait] Running Loop=1
2023-05-04T23:26:02.538Z,1683242762.538 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:26:02.552Z,1683242762.552 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:02.553Z,1683242762.553 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:02.804Z,1683242762.804 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:02.805Z,1683242762.805 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:03.056Z,1683242763.056 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:03.057Z,1683242763.057 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:03.309Z,1683242763.309 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:03.560Z,1683242763.560 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:03.561Z,1683242763.561 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:03.812Z,1683242763.812 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:03.813Z,1683242763.813 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:04.064Z,1683242764.064 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:04.065Z,1683242764.065 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:04.316Z,1683242764.316 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:04.317Z,1683242764.317 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:04.568Z,1683242764.568 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:04.568Z,1683242764.568 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:04.578Z,1683242764.578 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:26:04.820Z,1683242764.820 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:04.821Z,1683242764.821 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:05.073Z,1683242765.073 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:05.073Z,1683242765.073 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:05.325Z,1683242765.325 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:05.326Z,1683242765.326 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:26:05.326Z,1683242765.326 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:26:05.326Z,1683242765.326 [DATMMP](INFO): Handled
2023-05-04T23:26:05.326Z,1683242765.326 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:05.326Z,1683242765.326 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:05.577Z,1683242765.577 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:05.578Z,1683242765.578 [DATMMP](INFO): timestamp rx: 	 x1	 x17	 x1A	 x2	 x1FEF
2023-05-04T23:26:05.578Z,1683242765.578 [DATMMP](INFO): Rx dataTimestamp_ set to:1683242777.874496
2023-05-04T23:26:05.579Z,1683242765.579 [DATMMP](INFO): Rx ping set to:1683242765.577719
2023-05-04T23:26:05.579Z,1683242765.579 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:26:05.579Z,1683242765.579 [DATMMP](INFO): Handled
2023-05-04T23:26:05.579Z,1683242765.579 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:05.579Z,1683242765.579 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:05.829Z,1683242765.829 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:05.830Z,1683242765.830 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-04T23:26:05.830Z,1683242765.830 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-04T23:26:05.830Z,1683242765.830 [DATMMP](INFO): Received message type: remote_header
2023-05-04T23:26:05.830Z,1683242765.830 [DATMMP](INFO): Handled
2023-05-04T23:26:05.831Z,1683242765.831 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:05.831Z,1683242765.831 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:06.081Z,1683242766.081 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:06.082Z,1683242766.082 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:26:06.082Z,1683242766.082 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:26:06.082Z,1683242766.082 [DATMMP](INFO): Handled
2023-05-04T23:26:06.082Z,1683242766.082 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:06.082Z,1683242766.082 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:06.333Z,1683242766.333 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:06.334Z,1683242766.334 [DATMMP](INFO): directional_debug rx: 	 x41C	 x1FF	 x2E	 x36	 x2CF0	 x3141	 x2CF2	 x43E3
2023-05-04T23:26:06.335Z,1683242766.335 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:26:06.335Z,1683242766.335 [DATMMP](INFO): Handled
2023-05-04T23:26:06.335Z,1683242766.335 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:06.336Z,1683242766.336 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:06.585Z,1683242766.585 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:06.586Z,1683242766.586 [DATMMP](INFO): directional rx: 	 x42A	 x1A7	 x1C0	 xFE01	 x5BE	 xFE12	 x0	 x3F1	 x4	 xFFEC	 x2318600	 x0
2023-05-04T23:26:06.588Z,1683242766.588 [DATMMP](INFO): Received message type: directional
2023-05-04T23:26:06.588Z,1683242766.588 [DATMMP](INFO): Handled
2023-05-04T23:26:06.588Z,1683242766.588 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:06.588Z,1683242766.588 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:06.837Z,1683242766.837 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:06.838Z,1683242766.838 [DATMMP](INFO): acstats rx: 	 x6	 x5	 x0	 x23B	 x27	 x36	 xBF	 xB4	 xD	 xFFFF	 xA3	 x2E	 xC	 x0
2023-05-04T23:26:06.838Z,1683242766.838 [DATMMP](INFO): Received message type: acstats
2023-05-04T23:26:06.839Z,1683242766.839 [DATMMP](INFO): Handled
2023-05-04T23:26:06.839Z,1683242766.839 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:06.839Z,1683242766.839 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:06.986Z,1683242766.986 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-04T23:26:07.089Z,1683242767.089 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:07.090Z,1683242767.090 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-04T23:26:07.090Z,1683242767.090 [DATMMP](INFO): Check CRC
2023-05-04T23:26:07.090Z,1683242767.090 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-04T23:26:07.090Z,1683242767.090 [DATMMP](INFO): Got DATA: len 4
2023-05-04T23:26:07.090Z,1683242767.090 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-04T23:26:07.091Z,1683242767.091 [DATMMP](INFO): Got ~~ ack, bytes left 0
2023-05-04T23:26:07.091Z,1683242767.091 [DATMMP](INFO): Received command: 

2023-05-04T23:26:07.091Z,1683242767.091 [DATMMP](INFO): Received message type: data_rx
2023-05-04T23:26:07.091Z,1683242767.091 [DATMMP](INFO): Handled
2023-05-04T23:26:07.091Z,1683242767.091 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-04T23:26:07.091Z,1683242767.091 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-04T23:26:07.092Z,1683242767.092 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:07.341Z,1683242767.341 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-04T23:26:07.342Z,1683242767.342 [DATMMP](INFO): modem://6: set _.temperature 24.138697 celsius
2023-05-04T23:26:07.342Z,1683242767.342 [DATMMP](INFO): #Outgoing data=1
2023-05-04T23:26:07.342Z,1683242767.342 [DATMMP](ERROR): Waiting for previous to be ack'd
2023-05-04T23:26:07.342Z,1683242767.342 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-04T23:26:07.342Z,1683242767.342 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:07.592Z,1683242767.592 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:26:07.593Z,1683242767.593 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-04T23:26:07.593Z,1683242767.593 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:07.844Z,1683242767.844 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-04T23:26:07.845Z,1683242767.845 [DATMMP](INFO): Tx to 6 len 35 xid 20
2023-05-04T23:26:07.845Z,1683242767.845 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-04T23:26:07.845Z,1683242767.845 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-04T23:26:07.846Z,1683242767.846 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:08.097Z,1683242768.097 [DATMMP](INFO): GSXN notify for xid: 20
2023-05-04T23:26:08.097Z,1683242768.097 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-04T23:26:08.098Z,1683242768.098 [DATMMP](DEBUG): Command complete send
2023-05-04T23:26:08.098Z,1683242768.098 [DATMMP](ERROR): 1:COMPLETE
2023-05-04T23:26:08.098Z,1683242768.098 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:26:08.098Z,1683242768.098 [DATMMP](INFO): Handled
2023-05-04T23:26:08.098Z,1683242768.098 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:08.098Z,1683242768.098 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:08.349Z,1683242768.349 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:08.350Z,1683242768.350 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 x1A	 x6	 x34F
2023-05-04T23:26:08.350Z,1683242768.350 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:26:08.350Z,1683242768.350 [DATMMP](INFO): Handled
2023-05-04T23:26:08.350Z,1683242768.350 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:08.350Z,1683242768.350 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:08.600Z,1683242768.600 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:08.601Z,1683242768.601 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:08.852Z,1683242768.852 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:08.852Z,1683242768.852 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:09.105Z,1683242769.105 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:09.105Z,1683242769.105 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:09.362Z,1683242769.362 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:09.362Z,1683242769.362 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:09.616Z,1683242769.616 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:09.616Z,1683242769.616 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:09.868Z,1683242769.868 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:09.869Z,1683242769.869 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:10.120Z,1683242770.120 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:10.121Z,1683242770.121 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:10.372Z,1683242770.372 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:10.373Z,1683242770.373 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:10.641Z,1683242770.641 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:10.641Z,1683242770.641 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:10.892Z,1683242770.892 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:10.893Z,1683242770.893 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:11.145Z,1683242771.145 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:11.145Z,1683242771.145 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:11.396Z,1683242771.396 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:11.397Z,1683242771.397 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:11.648Z,1683242771.648 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:11.649Z,1683242771.649 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:11.900Z,1683242771.900 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:11.900Z,1683242771.900 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:12.152Z,1683242772.152 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:12.153Z,1683242772.153 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:12.405Z,1683242772.405 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:12.405Z,1683242772.405 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:26:12.405Z,1683242772.405 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:26:12.406Z,1683242772.406 [DATMMP](INFO): Handled
2023-05-04T23:26:12.406Z,1683242772.406 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:12.406Z,1683242772.406 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:12.669Z,1683242772.669 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:12.670Z,1683242772.670 [DATMMP](INFO): timestamp rx: 	 x1	 x17	 x1A	 xA	 x498
2023-05-04T23:26:12.670Z,1683242772.670 [DATMMP](INFO): Rx dataTimestamp_ set to:1683242781.507904
2023-05-04T23:26:12.670Z,1683242772.670 [DATMMP](INFO): Rx ping set to:1683242772.669658
2023-05-04T23:26:12.671Z,1683242772.671 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:26:12.671Z,1683242772.671 [DATMMP](INFO): Handled
2023-05-04T23:26:12.671Z,1683242772.671 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:12.671Z,1683242772.671 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:12.920Z,1683242772.920 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:12.921Z,1683242772.921 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:13.173Z,1683242773.173 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:13.174Z,1683242773.174 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-04T23:26:13.174Z,1683242773.174 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-04T23:26:13.174Z,1683242773.174 [DATMMP](INFO): Received message type: remote_header
2023-05-04T23:26:13.174Z,1683242773.174 [DATMMP](INFO): Handled
2023-05-04T23:26:13.174Z,1683242773.174 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:13.175Z,1683242773.175 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:13.425Z,1683242773.425 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:13.426Z,1683242773.426 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:26:13.426Z,1683242773.426 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:26:13.426Z,1683242773.426 [DATMMP](INFO): Handled
2023-05-04T23:26:13.426Z,1683242773.426 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:13.426Z,1683242773.426 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:13.677Z,1683242773.677 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:13.678Z,1683242773.678 [DATMMP](INFO): directional_debug rx: 	 x43D	 x1CC	 x2A	 x36	 x23C0	 x27F1	 x2392	 x3533
2023-05-04T23:26:13.679Z,1683242773.679 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:26:13.679Z,1683242773.679 [DATMMP](INFO): Handled
2023-05-04T23:26:13.679Z,1683242773.679 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:13.679Z,1683242773.679 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:13.929Z,1683242773.929 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:13.930Z,1683242773.930 [DATMMP](INFO): directional rx: 	 x452	 x167	 x19F	 xFE34	 x57D	 xFE45	 x0	 x3D2	 x4	 xFFEC	 x2318600	 x0
2023-05-04T23:26:13.932Z,1683242773.932 [DATMMP](INFO): Received message type: directional
2023-05-04T23:26:13.932Z,1683242773.932 [DATMMP](INFO): Handled
2023-05-04T23:26:13.932Z,1683242773.932 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:13.932Z,1683242773.932 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:14.181Z,1683242774.181 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:14.182Z,1683242774.182 [DATMMP](INFO): acstats rx: 	 x6	 x5	 x0	 x207	 x27	 x2F	 xC2	 xBC	 xD	 xFFFF	 xA3	 x2D	 xC	 x0
2023-05-04T23:26:14.183Z,1683242774.183 [DATMMP](INFO): Received message type: acstats
2023-05-04T23:26:14.183Z,1683242774.183 [DATMMP](INFO): Handled
2023-05-04T23:26:14.183Z,1683242774.183 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:14.183Z,1683242774.183 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:14.286Z,1683242774.286 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-04T23:26:14.433Z,1683242774.433 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:14.433Z,1683242774.433 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-04T23:26:14.434Z,1683242774.434 [DATMMP](INFO): Check CRC
2023-05-04T23:26:14.434Z,1683242774.434 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-04T23:26:14.434Z,1683242774.434 [DATMMP](INFO): Got DATA: len 4
2023-05-04T23:26:14.434Z,1683242774.434 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-04T23:26:14.434Z,1683242774.434 [DATMMP](INFO): Got ~~ ack, bytes left 2
2023-05-04T23:26:14.434Z,1683242774.434 [DATMMP](INFO): Received command: 

2023-05-04T23:26:14.435Z,1683242774.435 [DATMMP](INFO): Received message type: data_rx
2023-05-04T23:26:14.435Z,1683242774.435 [DATMMP](INFO): Handled
2023-05-04T23:26:14.435Z,1683242774.435 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-04T23:26:14.435Z,1683242774.435 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-04T23:26:14.435Z,1683242774.435 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:14.684Z,1683242774.684 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-04T23:26:14.685Z,1683242774.685 [DATMMP](INFO): modem://6: set _.humidity 12.812844 percent
2023-05-04T23:26:14.686Z,1683242774.686 [DATMMP](INFO): #Outgoing data=1
2023-05-04T23:26:14.686Z,1683242774.686 [DATMMP](ERROR): Waiting for previous to be ack'd
2023-05-04T23:26:14.686Z,1683242774.686 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-04T23:26:14.686Z,1683242774.686 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:14.936Z,1683242774.936 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:26:14.937Z,1683242774.937 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-04T23:26:14.937Z,1683242774.937 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:15.190Z,1683242775.190 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-04T23:26:15.191Z,1683242775.191 [DATMMP](INFO): Tx to 6 len 32 xid 21
2023-05-04T23:26:15.193Z,1683242775.193 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-04T23:26:15.197Z,1683242775.197 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-04T23:26:15.197Z,1683242775.197 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:15.440Z,1683242775.440 [DATMMP](INFO): GSXN notify for xid: 21
2023-05-04T23:26:15.441Z,1683242775.441 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-04T23:26:15.442Z,1683242775.442 [DATMMP](DEBUG): Command complete send
2023-05-04T23:26:15.442Z,1683242775.442 [DATMMP](ERROR): 1:COMPLETE
2023-05-04T23:26:15.442Z,1683242775.442 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:26:15.442Z,1683242775.442 [DATMMP](INFO): Handled
2023-05-04T23:26:15.442Z,1683242775.442 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:15.693Z,1683242775.693 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:15.694Z,1683242775.694 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 x1A	 xD	 x10FB
2023-05-04T23:26:15.694Z,1683242775.694 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:26:15.694Z,1683242775.694 [DATMMP](INFO): Handled
2023-05-04T23:26:15.694Z,1683242775.694 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:15.694Z,1683242775.694 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:15.867Z,1683242775.867 [Default:A.Wait](INFO): Done Waiting.
2023-05-04T23:26:15.867Z,1683242775.867 [Default:A.Wait] Stopped
2023-05-04T23:26:15.868Z,1683242775.868 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:26:15.944Z,1683242775.944 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:15.945Z,1683242775.945 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:16.196Z,1683242776.196 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:16.197Z,1683242776.197 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:16.271Z,1683242776.271 [Default:CheckIn] Running Loop=1
2023-05-04T23:26:16.271Z,1683242776.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:26:16.271Z,1683242776.271 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:26:16.449Z,1683242776.449 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:16.449Z,1683242776.449 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:16.700Z,1683242776.700 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:16.701Z,1683242776.701 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:16.952Z,1683242776.952 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:16.952Z,1683242776.952 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:17.204Z,1683242777.204 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:17.205Z,1683242777.205 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:17.457Z,1683242777.457 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:17.457Z,1683242777.457 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:17.708Z,1683242777.708 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:17.709Z,1683242777.709 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:17.849Z,1683242777.849 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:26:17.960Z,1683242777.960 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:17.960Z,1683242777.960 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:18.212Z,1683242778.212 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:18.213Z,1683242778.213 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:18.465Z,1683242778.465 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:18.465Z,1683242778.465 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:18.732Z,1683242778.732 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:18.733Z,1683242778.733 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:18.985Z,1683242778.985 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:18.985Z,1683242778.985 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:19.236Z,1683242779.236 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:19.237Z,1683242779.237 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:19.488Z,1683242779.488 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:19.489Z,1683242779.489 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:19.740Z,1683242779.740 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:19.741Z,1683242779.741 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:19.993Z,1683242779.993 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:19.993Z,1683242779.993 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:26:19.994Z,1683242779.994 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:26:19.994Z,1683242779.994 [DATMMP](INFO): Handled
2023-05-04T23:26:19.994Z,1683242779.994 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:19.994Z,1683242779.994 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:20.245Z,1683242780.245 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:20.246Z,1683242780.246 [DATMMP](INFO): timestamp rx: 	 x1	 x17	 x1A	 x11	 x1C09
2023-05-04T23:26:20.246Z,1683242780.246 [DATMMP](INFO): Rx dataTimestamp_ set to:1683242783.092160
2023-05-04T23:26:20.247Z,1683242780.247 [DATMMP](INFO): Rx ping set to:1683242780.245893
2023-05-04T23:26:20.247Z,1683242780.247 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:26:20.247Z,1683242780.247 [DATMMP](INFO): Handled
2023-05-04T23:26:20.247Z,1683242780.247 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:20.247Z,1683242780.247 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:20.497Z,1683242780.497 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:20.497Z,1683242780.497 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:20.749Z,1683242780.749 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:20.751Z,1683242780.751 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-04T23:26:20.760Z,1683242780.760 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-04T23:26:20.760Z,1683242780.760 [DATMMP](INFO): Received message type: remote_header
2023-05-04T23:26:20.761Z,1683242780.761 [DATMMP](INFO): Handled
2023-05-04T23:26:20.761Z,1683242780.761 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:20.761Z,1683242780.761 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:21.001Z,1683242781.001 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:21.002Z,1683242781.002 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:26:21.002Z,1683242781.002 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:26:21.002Z,1683242781.002 [DATMMP](INFO): Handled
2023-05-04T23:26:21.002Z,1683242781.002 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:21.002Z,1683242781.002 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:21.253Z,1683242781.253 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:21.254Z,1683242781.254 [DATMMP](INFO): directional_debug rx: 	 x3FD	 x1A5	 x2C	 x39	 x22E0	 x26D1	 x22F2	 x3573
2023-05-04T23:26:21.255Z,1683242781.255 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:26:21.255Z,1683242781.255 [DATMMP](INFO): Handled
2023-05-04T23:26:21.255Z,1683242781.255 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:21.255Z,1683242781.255 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:21.505Z,1683242781.505 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:21.506Z,1683242781.506 [DATMMP](INFO): directional rx: 	 x40A	 x139	 x1DF	 xFE5B	 x59D	 xFE6C	 x0	 x3B5	 x4	 xFFED	 x2318600	 x0
2023-05-04T23:26:21.508Z,1683242781.508 [DATMMP](INFO): Received message type: directional
2023-05-04T23:26:21.508Z,1683242781.508 [DATMMP](INFO): Handled
2023-05-04T23:26:21.508Z,1683242781.508 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:21.508Z,1683242781.508 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:21.613Z,1683242781.613 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-04T23:26:21.757Z,1683242781.757 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:21.759Z,1683242781.759 [DATMMP](INFO): acstats rx: 	 x6	 x5	 x0	 x1F3	 x27	 x33	 xA1	 xA8	 xD	 xFFFF	 xA2	 x31	 xB	 x0
2023-05-04T23:26:21.759Z,1683242781.759 [DATMMP](INFO): Received message type: acstats
2023-05-04T23:26:21.759Z,1683242781.759 [DATMMP](INFO): Handled
2023-05-04T23:26:21.759Z,1683242781.759 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:26:21.759Z,1683242781.759 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:22.009Z,1683242782.009 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:26:22.010Z,1683242782.010 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-04T23:26:22.010Z,1683242782.010 [DATMMP](INFO): Check CRC
2023-05-04T23:26:22.010Z,1683242782.010 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-04T23:26:22.010Z,1683242782.010 [DATMMP](INFO): Got DATA: len 4
2023-05-04T23:26:22.010Z,1683242782.010 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-04T23:26:22.010Z,1683242782.010 [DATMMP](INFO): Got ~~ ack, bytes left 2
2023-05-04T23:26:22.011Z,1683242782.011 [DATMMP](INFO): Received command: 

2023-05-04T23:26:22.011Z,1683242782.011 [DATMMP](INFO): Received message type: data_rx
2023-05-04T23:26:22.011Z,1683242782.011 [DATMMP](INFO): Handled
2023-05-04T23:26:22.011Z,1683242782.011 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-04T23:26:22.011Z,1683242782.011 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-04T23:26:22.012Z,1683242782.012 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:22.261Z,1683242782.261 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:22.513Z,1683242782.513 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:22.764Z,1683242782.764 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:23.016Z,1683242783.016 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:23.269Z,1683242783.269 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:23.520Z,1683242783.520 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:23.773Z,1683242783.773 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:24.024Z,1683242784.024 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:24.276Z,1683242784.276 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:24.529Z,1683242784.529 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:24.781Z,1683242784.781 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:25.033Z,1683242785.033 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:25.284Z,1683242785.284 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:25.536Z,1683242785.536 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:26:27.301Z,1683242787.301 [CommandExec](IMPORTANT): got command stop
2023-05-04T23:26:32.089Z,1683242792.089 [CommandExec](IMPORTANT): got command restart application
2023-05-04T23:26:33.092Z,1683242793.092 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:33.092Z,1683242793.092 [CommandExec](INFO): Uninitializing the command executive.
2023-05-04T23:26:33.092Z,1683242793.092 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-04T23:26:33.093Z,1683242793.093 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:33.132Z,1683242793.132 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-04T23:26:33.132Z,1683242793.132 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-04T23:26:33.132Z,1683242793.132 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:33.133Z,1683242793.133 [NavChartDb](INFO): Join timeout helper Thread ID is 4008
2023-05-04T23:26:33.340Z,1683242793.340 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:33.340Z,1683242793.340 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:33.344Z,1683242793.344 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-04T23:26:33.344Z,1683242793.344 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:33.345Z,1683242793.345 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4009
2023-05-04T23:26:33.596Z,1683242793.596 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:33.597Z,1683242793.597 [WetLabsBB2FL](INFO): Powering down
2023-05-04T23:26:33.598Z,1683242793.598 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:33.604Z,1683242793.604 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-04T23:26:33.604Z,1683242793.604 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:33.605Z,1683242793.605 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4010
2023-05-04T23:26:34.329Z,1683242794.329 [CTD_Seabird](INFO): Powering down
2023-05-04T23:26:34.340Z,1683242794.340 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:34.340Z,1683242794.340 [CTD_Seabird](INFO): Powering down
2023-05-04T23:26:34.356Z,1683242794.356 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:34.368Z,1683242794.368 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-04T23:26:34.368Z,1683242794.368 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:34.368Z,1683242794.368 [DATMMP](INFO): Join timeout helper Thread ID is 4011
2023-05-04T23:26:34.612Z,1683242794.612 [DATMMP](INFO): Stop
2023-05-04T23:26:34.612Z,1683242794.612 [DATMMP](INFO): uninitialize
2023-05-04T23:26:34.612Z,1683242794.612 [DATMMP](INFO): Powering down
2023-05-04T23:26:34.689Z,1683242794.689 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:34.689Z,1683242794.689 [DATMMP](INFO): uninitialize
2023-05-04T23:26:34.689Z,1683242794.689 [DATMMP](INFO): Powering down
2023-05-04T23:26:34.690Z,1683242794.690 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:34.696Z,1683242794.696 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-04T23:26:34.696Z,1683242794.696 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:34.697Z,1683242794.697 [Radio_Surface](INFO): Join timeout helper Thread ID is 4012
2023-05-04T23:26:34.932Z,1683242794.932 [Radio_Surface](INFO): Powering down
2023-05-04T23:26:34.933Z,1683242794.933 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:34.933Z,1683242794.933 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:34.953Z,1683242794.953 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-04T23:26:34.953Z,1683242794.953 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:34.953Z,1683242794.953 [Onboard](INFO): Join timeout helper Thread ID is 4013
2023-05-04T23:26:37.300Z,1683242797.300 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:37.300Z,1683242797.300 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.317Z,1683242797.317 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-04T23:26:37.317Z,1683242797.317 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.317Z,1683242797.317 [DataOverHttps](INFO): Join timeout helper Thread ID is 4014
2023-05-04T23:26:37.492Z,1683242797.492 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:37.496Z,1683242797.496 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.513Z,1683242797.513 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-04T23:26:37.513Z,1683242797.513 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.513Z,1683242797.513 [BackseatComponent](INFO): Join timeout helper Thread ID is 4015
2023-05-04T23:26:37.592Z,1683242797.592 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:37.592Z,1683242797.592 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.613Z,1683242797.613 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-04T23:26:37.613Z,1683242797.613 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.613Z,1683242797.613 [logger](INFO): Join timeout helper Thread ID is 4016
2023-05-04T23:26:37.632Z,1683242797.632 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:37.632Z,1683242797.632 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.654Z,1683242797.654 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-04T23:26:37.654Z,1683242797.654 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.654Z,1683242797.654 [CommandLine](INFO): Join timeout helper Thread ID is 4017
2023-05-04T23:26:37.708Z,1683242797.708 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:37.709Z,1683242797.709 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.729Z,1683242797.729 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-04T23:26:37.729Z,1683242797.729 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.729Z,1683242797.729 [CommandExec](INFO): Join timeout helper Thread ID is 4018
2023-05-04T23:26:37.730Z,1683242797.730 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-04T23:26:37.730Z,1683242797.730 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:37.731Z,1683242797.731 [controlThread](INFO): Join timeout helper Thread ID is 4019
2023-05-04T23:26:38.052Z,1683242798.052 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:26:38.052Z,1683242798.052 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-04T23:26:38.053Z,1683242798.053 [AHRS_M2](INFO): Powering down
2023-05-04T23:26:38.125Z,1683242798.125 [NAL9602](INFO): Powering down
2023-05-04T23:26:38.128Z,1683242798.128 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-04T23:26:38.129Z,1683242798.129 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-04T23:26:38.129Z,1683242798.129 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-04T23:26:38.130Z,1683242798.130 [MissionManager](INFO): Uninitializing Mission Default
2023-05-04T23:26:38.130Z,1683242798.130 [Default] Stopped
2023-05-04T23:26:38.130Z,1683242798.130 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T23:26:38.130Z,1683242798.130 [Default:B.GoToSurface] Stopped
2023-05-04T23:26:38.130Z,1683242798.130 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:26:38.130Z,1683242798.130 [Default:CheckIn] Stopped
2023-05-04T23:26:38.130Z,1683242798.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:26:38.130Z,1683242798.130 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:26:38.133Z,1683242798.133 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-04T23:26:38.133Z,1683242798.133 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-04T23:26:38.133Z,1683242798.133 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-04T23:26:38.134Z,1683242798.134 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-04T23:26:38.134Z,1683242798.134 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-04T23:26:38.134Z,1683242798.134 [BuoyancyServo](INFO): Powering down
2023-05-04T23:26:38.148Z,1683242798.148 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-04T23:26:38.148Z,1683242798.148 [ElevatorServo](INFO): Powering down
2023-05-04T23:26:38.149Z,1683242798.149 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-04T23:26:38.149Z,1683242798.149 [MassServo](INFO): Powering down
2023-05-04T23:26:38.150Z,1683242798.150 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-04T23:26:38.150Z,1683242798.150 [RudderServo](INFO): Powering down
2023-05-04T23:26:38.151Z,1683242798.151 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-04T23:26:38.151Z,1683242798.151 [ThrusterHE](INFO): Powering down
2023-05-04T23:26:38.152Z,1683242798.152 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-04T23:26:38.152Z,1683242798.152 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-04T23:26:38.153Z,1683242798.153 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-04T23:26:38.153Z,1683242798.153 [CBIT](DEBUG): Powering off loads.
2023-05-04T23:26:38.164Z,1683242798.164 [CBIT](DEBUG): Disabling WDT.
2023-05-04T23:26:38.176Z,1683242798.176 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-04T23:26:38.177Z,1683242798.177 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.227Z,1683242798.227 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.235Z,1683242798.235 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.238Z,1683242798.238 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.244Z,1683242798.244 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.287Z,1683242798.287 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.326Z,1683242798.326 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.331Z,1683242798.331 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.363Z,1683242798.363 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:26:38.422Z,1683242798.422 [logger ThreadHandler](INFO): Thread cancelled.