2023-05-03T21:05:16.954Z,1683147916.954 [Supervisor](DEBUG): Initializing supervisor.
2023-05-03T21:05:16.959Z,1683147916.959 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-03T21:05:16.960Z,1683147916.960 [SyncHandler](INFO): Protected caller Thread ID is 1572
2023-05-03T21:05:16.960Z,1683147916.960 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-03T21:05:16.962Z,1683147916.962 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-03T21:05:16.962Z,1683147916.962 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1573
2023-05-03T21:05:16.966Z,1683147916.966 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-03T21:05:16.983Z,1683147916.983 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-03T21:05:16.984Z,1683147916.984 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-03T21:05:16.985Z,1683147916.985 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1574
2023-05-03T21:05:16.989Z,1683147916.989 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-03T21:05:16.990Z,1683147916.990 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-03T21:05:16.990Z,1683147916.990 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1575
2023-05-03T21:05:16.992Z,1683147916.992 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-03T21:05:16.993Z,1683147916.993 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-03T21:05:16.994Z,1683147916.994 [logger ThreadHandler](INFO): Protected caller Thread ID is 1576
2023-05-03T21:05:16.997Z,1683147916.997 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-03T21:05:16.997Z,1683147916.997 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-03T21:05:16.999Z,1683147916.999 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-03T21:05:17.448Z,1683147917.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-03T21:05:17.449Z,1683147917.449 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-03T21:05:17.654Z,1683147917.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-03T21:05:17.654Z,1683147917.654 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-03T21:05:17.735Z,1683147917.735 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-03T21:05:17.946Z,1683147917.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-03T21:05:17.947Z,1683147917.947 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-03T21:05:18.025Z,1683147918.025 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-03T21:05:18.125Z,1683147918.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-03T21:05:18.125Z,1683147918.125 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-03T21:05:18.539Z,1683147918.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-03T21:05:18.539Z,1683147918.539 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-03T21:05:18.715Z,1683147918.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-03T21:05:18.715Z,1683147918.715 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-03T21:05:19.286Z,1683147919.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-03T21:05:19.286Z,1683147919.286 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-03T21:05:19.464Z,1683147919.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-03T21:05:19.464Z,1683147919.464 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-03T21:05:19.848Z,1683147919.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-03T21:05:19.849Z,1683147919.849 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-03T21:05:20.119Z,1683147920.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-03T21:05:20.119Z,1683147920.119 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-03T21:05:20.228Z,1683147920.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-03T21:05:20.229Z,1683147920.229 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-03T21:05:20.982Z,1683147920.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-03T21:05:20.983Z,1683147920.983 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-03T21:05:21.301Z,1683147921.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-03T21:05:21.302Z,1683147921.302 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-03T21:05:21.516Z,1683147921.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-03T21:05:21.518Z,1683147921.518 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-03T21:05:21.519Z,1683147921.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-03T21:05:21.700Z,1683147921.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-03T21:05:21.783Z,1683147921.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-03T21:05:21.914Z,1683147921.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-03T21:05:21.998Z,1683147921.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-03T21:05:22.258Z,1683147922.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-03T21:05:22.259Z,1683147922.259 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-03T21:05:22.361Z,1683147922.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-03T21:05:22.471Z,1683147922.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-03T21:05:22.582Z,1683147922.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-03T21:05:22.727Z,1683147922.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-03T21:05:22.825Z,1683147922.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-03T21:05:22.918Z,1683147922.918 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-03T21:05:22.918Z,1683147922.918 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-03T21:05:22.930Z,1683147922.930 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-03T21:05:22.941Z,1683147922.941 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-03T21:05:22.941Z,1683147922.941 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-03T21:05:23.038Z,1683147923.038 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-03T21:05:23.038Z,1683147923.038 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-03T21:05:23.087Z,1683147923.087 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-03T21:05:23.146Z,1683147923.146 [VerticalControl] Loaded
2023-05-03T21:05:23.146Z,1683147923.146 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-03T21:05:23.149Z,1683147923.149 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-03T21:05:23.189Z,1683147923.189 [HorizontalControl] Loaded
2023-05-03T21:05:23.190Z,1683147923.190 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-03T21:05:23.192Z,1683147923.192 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-03T21:05:23.195Z,1683147923.195 [SpeedControl] Loaded
2023-05-03T21:05:23.195Z,1683147923.195 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-03T21:05:23.198Z,1683147923.198 [LoopControl](DEBUG): Construct LoopControl.
2023-05-03T21:05:23.198Z,1683147923.198 [LoopControl] Loaded
2023-05-03T21:05:23.198Z,1683147923.198 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-03T21:05:23.199Z,1683147923.199 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-03T21:05:23.199Z,1683147923.199 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-03T21:05:23.631Z,1683147923.631 [BuoyancyServo] Loaded
2023-05-03T21:05:23.632Z,1683147923.632 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-03T21:05:23.651Z,1683147923.651 [ElevatorServo] Loaded
2023-05-03T21:05:23.652Z,1683147923.652 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-03T21:05:23.702Z,1683147923.702 [MassServo] Loaded
2023-05-03T21:05:23.703Z,1683147923.703 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-03T21:05:23.721Z,1683147923.721 [RudderServo] Loaded
2023-05-03T21:05:23.721Z,1683147923.721 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-03T21:05:23.825Z,1683147923.825 [ThrusterHE] Loaded
2023-05-03T21:05:23.826Z,1683147923.826 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-03T21:05:23.826Z,1683147923.826 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-03T21:05:23.826Z,1683147923.826 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-03T21:05:23.980Z,1683147923.980 [DepthRateCalculator] Loaded
2023-05-03T21:05:23.980Z,1683147923.980 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-03T21:05:23.985Z,1683147923.985 [PitchRateCalculator] Loaded
2023-05-03T21:05:23.985Z,1683147923.985 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-03T21:05:23.995Z,1683147923.995 [SpeedCalculator] Loaded
2023-05-03T21:05:23.995Z,1683147923.995 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-03T21:05:23.999Z,1683147923.999 [YawRateCalculator] Loaded
2023-05-03T21:05:23.999Z,1683147923.999 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-03T21:05:24.018Z,1683147924.018 [ElevatorOffsetCalculator] Loaded
2023-05-03T21:05:24.018Z,1683147924.018 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-03T21:05:24.018Z,1683147924.018 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-03T21:05:24.019Z,1683147924.019 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-03T21:05:24.302Z,1683147924.302 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-03T21:05:24.302Z,1683147924.302 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-03T21:05:24.319Z,1683147924.319 [NavChart] Loaded
2023-05-03T21:05:24.319Z,1683147924.319 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-03T21:05:24.324Z,1683147924.324 [UniversalFixResidualReporter] Loaded
2023-05-03T21:05:24.325Z,1683147924.325 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-03T21:05:24.325Z,1683147924.325 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-03T21:05:24.326Z,1683147924.326 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-03T21:05:24.745Z,1683147924.745 [AHRS_M2] Loaded
2023-05-03T21:05:24.745Z,1683147924.745 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-03T21:05:24.780Z,1683147924.780 [BackseatComponent] Loaded
2023-05-03T21:05:24.780Z,1683147924.780 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-03T21:05:24.781Z,1683147924.781 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-03T21:05:24.782Z,1683147924.782 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1658
2023-05-03T21:05:24.784Z,1683147924.784 [LcmUniversalReporter] Loaded
2023-05-03T21:05:24.785Z,1683147924.785 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-03T21:05:25.596Z,1683147925.596 [BPC1] Loaded
2023-05-03T21:05:25.596Z,1683147925.596 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-03T21:05:25.667Z,1683147925.667 [DataOverHttps] Loaded
2023-05-03T21:05:25.668Z,1683147925.668 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-03T21:05:25.669Z,1683147925.669 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-03T21:05:25.669Z,1683147925.669 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1659
2023-05-03T21:05:25.690Z,1683147925.690 [Depth_Keller] Loaded
2023-05-03T21:05:25.690Z,1683147925.690 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-03T21:05:25.751Z,1683147925.751 [NAL9602] Loaded
2023-05-03T21:05:25.752Z,1683147925.752 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-03T21:05:25.827Z,1683147925.827 [Onboard] Loaded
2023-05-03T21:05:25.827Z,1683147925.827 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-03T21:05:25.857Z,1683147925.857 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-03T21:05:25.857Z,1683147925.857 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1660
2023-05-03T21:05:25.906Z,1683147925.906 [Power24vConverter] Loaded
2023-05-03T21:05:25.906Z,1683147925.906 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-03T21:05:25.919Z,1683147925.919 [Radio_Surface] Loaded
2023-05-03T21:05:25.920Z,1683147925.920 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-03T21:05:25.921Z,1683147925.921 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-03T21:05:25.921Z,1683147925.921 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1661
2023-05-03T21:05:26.837Z,1683147926.837 [DATMMP] Loaded
2023-05-03T21:05:26.837Z,1683147926.837 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-03T21:05:26.839Z,1683147926.839 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-03T21:05:26.839Z,1683147926.839 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1662
2023-05-03T21:05:26.840Z,1683147926.840 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-03T21:05:26.840Z,1683147926.840 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-03T21:05:26.979Z,1683147926.979 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-03T21:05:26.980Z,1683147926.980 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-03T21:05:27.222Z,1683147927.222 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-03T21:05:27.223Z,1683147927.223 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-03T21:05:27.248Z,1683147927.248 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-03T21:05:27.249Z,1683147927.249 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-03T21:05:27.413Z,1683147927.413 [CTD_Seabird] Loaded
2023-05-03T21:05:27.414Z,1683147927.414 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-03T21:05:27.415Z,1683147927.415 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2023-05-03T21:05:27.415Z,1683147927.415 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1663
2023-05-03T21:05:27.447Z,1683147927.447 [ESPComponent] Loaded
2023-05-03T21:05:27.448Z,1683147927.448 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-03T21:05:27.467Z,1683147927.467 [PAR_Licor] Loaded
2023-05-03T21:05:27.468Z,1683147927.468 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-03T21:05:27.498Z,1683147927.498 [WetLabsBB2FL] Loaded
2023-05-03T21:05:27.498Z,1683147927.498 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-03T21:05:27.499Z,1683147927.499 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0
2023-05-03T21:05:27.500Z,1683147927.500 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1664
2023-05-03T21:05:27.500Z,1683147927.500 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-03T21:05:27.501Z,1683147927.501 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-03T21:05:27.800Z,1683147927.800 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-03T21:05:27.800Z,1683147927.800 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-03T21:05:27.936Z,1683147927.936 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-03T21:05:27.946Z,1683147927.946 [SBIT] Loaded
2023-05-03T21:05:27.946Z,1683147927.946 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-03T21:05:27.949Z,1683147927.949 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-03T21:05:27.962Z,1683147927.962 [IBIT] Loaded
2023-05-03T21:05:27.962Z,1683147927.962 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-03T21:05:27.968Z,1683147927.968 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-03T21:05:28.071Z,1683147928.071 [CBIT] Loaded
2023-05-03T21:05:28.071Z,1683147928.071 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-03T21:05:28.072Z,1683147928.072 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-03T21:05:28.078Z,1683147928.078 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-03T21:05:28.081Z,1683147928.081 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-03T21:05:28.092Z,1683147928.092 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-03T21:05:28.093Z,1683147928.093 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2023-05-03T21:05:28.093Z,1683147928.093 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1665
2023-05-03T21:05:28.097Z,1683147928.097 [Supervisor](INFO): Main Thread ID is 829
2023-05-03T21:05:28.097Z,1683147928.097 [Supervisor](DEBUG): Running supervisor.
2023-05-03T21:05:28.098Z,1683147928.098 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1666
2023-05-03T21:05:28.099Z,1683147928.099 [CommandExec](INFO): Initializing the command executive.
2023-05-03T21:05:28.100Z,1683147928.100 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1667
2023-05-03T21:05:28.102Z,1683147928.102 [controlThread ThreadHandler](INFO): Handler Thread ID is 1668
2023-05-03T21:05:28.103Z,1683147928.103 [controlThread](DEBUG): Initializing ControlThread
2023-05-03T21:05:28.103Z,1683147928.103 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-03T21:05:28.105Z,1683147928.105 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-03T21:05:28.106Z,1683147928.106 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-03T21:05:28.106Z,1683147928.106 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-03T21:05:28.108Z,1683147928.108 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-03T21:05:28.108Z,1683147928.108 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-03T21:05:28.108Z,1683147928.108 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-03T21:05:28.109Z,1683147928.109 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-03T21:05:28.109Z,1683147928.109 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-03T21:05:28.110Z,1683147928.110 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-03T21:05:28.110Z,1683147928.110 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-03T21:05:28.116Z,1683147928.116 [SBIT](INFO): Initialize SBIT Component.
2023-05-03T21:05:28.117Z,1683147928.117 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-03T21:05:28.117Z,1683147928.117 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-03T21:05:28.117Z,1683147928.117 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-03T21:05:28.118Z,1683147928.118 [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-03T21:05:28.119Z,1683147928.119 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-03T21:05:28.120Z,1683147928.120 [IBIT](INFO): Initialize IBIT Component.
2023-05-03T21:05:28.121Z,1683147928.121 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-03T21:05:28.122Z,1683147928.122 [logger ThreadHandler](INFO): Handler Thread ID is 1669
2023-05-03T21:05:28.132Z,1683147928.132 [CBIT](DEBUG): Initialized mux pins.
2023-05-03T21:05:28.132Z,1683147928.132 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-03T21:05:28.140Z,1683147928.140 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1670
2023-05-03T21:05:28.152Z,1683147928.152 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1671
2023-05-03T21:05:28.153Z,1683147928.153 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-03T21:05:28.156Z,1683147928.156 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-03T21:05:28.156Z,1683147928.156 [CBIT](DEBUG): Initializing heartbeat.
2023-05-03T21:05:28.164Z,1683147928.164 [Onboard ThreadHandler](INFO): Handler Thread ID is 1672
2023-05-03T21:05:28.182Z,1683147928.182 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1673
2023-05-03T21:05:28.204Z,1683147928.204 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1674
2023-05-03T21:05:28.205Z,1683147928.205 [DATMMP](INFO): Start
2023-05-03T21:05:28.205Z,1683147928.205 [DATMMP](INFO): Powering up
2023-05-03T21:05:28.205Z,1683147928.205 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-03T21:05:28.209Z,1683147928.209 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1676
2023-05-03T21:05:28.210Z,1683147928.210 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-03T21:05:28.213Z,1683147928.213 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1678
2023-05-03T21:05:28.216Z,1683147928.216 [WetLabsBB2FL](INFO): Powering up
2023-05-03T21:05:28.218Z,1683147928.218 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1680
2023-05-03T21:05:28.221Z,1683147928.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-03T21:05:28.221Z,1683147928.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-03T21:05:28.221Z,1683147928.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-03T21:05:28.221Z,1683147928.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-03T21:05:28.221Z,1683147928.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-03T21:05:28.221Z,1683147928.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-03T21:05:28.222Z,1683147928.222 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-03T21:05:28.222Z,1683147928.222 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-03T21:05:28.228Z,1683147928.228 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-03T21:05:28.228Z,1683147928.228 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-03T21:05:28.264Z,1683147928.264 [CBIT](DEBUG): Backplane powered.
2023-05-03T21:05:28.265Z,1683147928.265 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-03T21:05:28.277Z,1683147928.277 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-03T21:05:28.301Z,1683147928.301 [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-03T21:05:28.301Z,1683147928.301 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-03T21:05:28.392Z,1683147928.392 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-03T21:05:28.393Z,1683147928.393 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-03T21:05:28.396Z,1683147928.396 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-03T21:05:28.437Z,1683147928.437 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-03T21:05:28.439Z,1683147928.439 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-03T21:05:28.485Z,1683147928.485 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-03T21:05:28.489Z,1683147928.489 [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-03T21:05:28.493Z,1683147928.493 [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-03T21:05:28.508Z,1683147928.508 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-03T21:05:28.584Z,1683147928.584 [Power24vConverter](INFO): Powering up.
2023-05-03T21:05:28.588Z,1683147928.588 [Radio_Surface](INFO): Powering up
2023-05-03T21:05:28.626Z,1683147928.626 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:05:28.632Z,1683147928.632 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-03T21:05:28.633Z,1683147928.633 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:05:28.640Z,1683147928.640 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-03T21:05:28.641Z,1683147928.641 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:05:28.648Z,1683147928.648 [MassServo](DEBUG): Initializing MassServo.
2023-05-03T21:05:28.649Z,1683147928.649 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:05:28.656Z,1683147928.656 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-03T21:05:28.657Z,1683147928.657 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-03T21:05:28.664Z,1683147928.664 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-03T21:05:30.324Z,1683147930.324 [WetLabsBB2FL](INFO): Powering down
2023-05-03T21:05:34.417Z,1683147934.417 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-03T21:05:35.241Z,1683147935.241 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-03T21:05:40.301Z,1683147940.301 [DATMMP](INFO): DAT read: 

2023-05-03T21:05:40.301Z,1683147940.301 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-03T21:05:42.065Z,1683147942.065 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-03T21:05:42.066Z,1683147942.066 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-03T21:05:42.066Z,1683147942.066 [DATMMP](INFO): DAT read: Apr  3 2000 21:05:36 

2023-05-03T21:05:43.325Z,1683147943.325 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-03T21:05:43.326Z,1683147943.326 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-03T21:05:43.327Z,1683147943.327 [DATMMP](INFO): commRate: 800
2023-05-03T21:05:45.392Z,1683147945.392 [DATMMP](INFO): entering command mode
2023-05-03T21:05:45.593Z,1683147945.593 [DATMMP](INFO): DAT read: 

2023-05-03T21:05:45.593Z,1683147945.593 [DATMMP](INFO): DAT read: user:1>

2023-05-03T21:05:45.594Z,1683147945.594 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-03T21:05:45.594Z,1683147945.594 [DATMMP](INFO): entering MMP mode
2023-05-03T21:05:45.845Z,1683147945.845 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:05:45.846Z,1683147945.846 [DATMMP](INFO): status rx: 	 x1
2023-05-03T21:05:45.847Z,1683147945.847 [DATMMP](INFO): MMP status: 1: started
2023-05-03T21:05:45.847Z,1683147945.847 [DATMMP](INFO): Received message type: status
2023-05-03T21:05:45.847Z,1683147945.847 [DATMMP](INFO): Handled
2023-05-03T21:05:46.097Z,1683147946.097 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-03T21:05:46.098Z,1683147946.098 [DATMMP](INFO): Setting local address: 5
2023-05-03T21:05:46.099Z,1683147946.099 [DATMMP](INFO): Setting time to: 21:5:46 And date to:5/3/2023
2023-05-03T21:05:46.348Z,1683147946.348 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:05:46.349Z,1683147946.349 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-03T21:05:46.350Z,1683147946.350 [DATMMP](INFO): Received message type: mdmpower
2023-05-03T21:05:46.350Z,1683147946.350 [DATMMP](INFO): Handled
2023-05-03T21:05:46.600Z,1683147946.600 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-03T21:05:46.601Z,1683147946.601 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-03T21:05:46.601Z,1683147946.601 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-03T21:05:46.601Z,1683147946.601 [DATMMP](INFO): Match for : sreg_txpower
2023-05-03T21:05:46.601Z,1683147946.601 [DATMMP](INFO): ACK
2023-05-03T21:05:46.853Z,1683147946.853 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:05:46.853Z,1683147946.853 [DATMMP](INFO): mdmlocal rx: 	 x5	 x0	 x0	 x0
2023-05-03T21:05:46.854Z,1683147946.854 [DATMMP](INFO): Received message type: mdmlocal
2023-05-03T21:05:46.854Z,1683147946.854 [DATMMP](INFO): Handled
2023-05-03T21:05:47.105Z,1683147947.105 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-03T21:05:47.105Z,1683147947.105 [DATMMP](INFO): sreg_locaddr rx: 	 x5
2023-05-03T21:05:47.105Z,1683147947.105 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-03T21:05:47.105Z,1683147947.105 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-03T21:05:47.106Z,1683147947.106 [DATMMP](INFO): ACK
2023-05-03T21:05:47.357Z,1683147947.357 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-03T21:05:47.358Z,1683147947.358 [DATMMP](INFO): timedate rx: 	 x0	 x15	 x5	 x2E	 x4	 x3	 x7B	 x0
2023-05-03T21:05:47.358Z,1683147947.358 [DATMMP](INFO): Received message type: timedate
2023-05-03T21:05:47.358Z,1683147947.358 [DATMMP](INFO): Match for : timedate
2023-05-03T21:05:47.358Z,1683147947.358 [DATMMP](INFO): ACK
2023-05-03T21:05:47.608Z,1683147947.608 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:05:47.609Z,1683147947.609 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-03T21:05:47.609Z,1683147947.609 [DATMMP](INFO): Received message type: sys_verb
2023-05-03T21:05:47.610Z,1683147947.610 [DATMMP](INFO): Handled
2023-05-03T21:05:47.860Z,1683147947.860 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-03T21:05:47.861Z,1683147947.861 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-03T21:05:47.861Z,1683147947.861 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-03T21:05:47.861Z,1683147947.861 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-03T21:05:47.862Z,1683147947.862 [DATMMP](INFO): ACK
2023-05-03T21:05:48.112Z,1683147948.112 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-03T21:05:48.113Z,1683147948.113 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-03T21:05:48.113Z,1683147948.113 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-03T21:05:48.114Z,1683147948.114 [DATMMP](INFO): Received message type: version
2023-05-03T21:05:48.114Z,1683147948.114 [DATMMP](INFO): Match for : version
2023-05-03T21:05:48.114Z,1683147948.114 [DATMMP](INFO): ACK
2023-05-03T21:05:48.114Z,1683147948.114 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-03T21:05:55.981Z,1683147955.981 [NAL9602](INFO): Powering up NAL9602
2023-05-03T21:06:02.733Z,1683147962.733 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:06:02.733Z,1683147962.733 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-03T21:06:02.734Z,1683147962.734 [DATMMP](INFO): Received message type: timesync
2023-05-03T21:06:02.734Z,1683147962.734 [DATMMP](INFO): Handled
2023-05-03T21:06:06.890Z,1683147966.890 [NAL9602](INFO): NAL9602 initialized
2023-05-03T21:06:12.569Z,1683147972.569 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:06:12.569Z,1683147972.569 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-03T21:06:12.570Z,1683147972.570 [DATMMP](INFO): Received message type: timesync
2023-05-03T21:06:12.570Z,1683147972.570 [DATMMP](INFO): Handled
2023-05-03T21:06:49.764Z,1683148009.764 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-03T21:06:49.796Z,1683148009.796 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-03T21:06:52.912Z,1683148012.912 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-03T21:06:52.916Z,1683148012.916 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-03T21:06:52.917Z,1683148012.917 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-03T21:06:52.920Z,1683148012.920 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-03T21:06:53.118Z,1683148013.118 [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,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-03T21:07:00.644Z,1683148020.644 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008467
CHAN A1 (24V): -0.007337
CHAN A2 (12V): -0.004851
CHAN A3 (5V): -0.002783
CHAN B0 (3.3V): -0.000999
CHAN B1 (3.15aV): -0.001417
CHAN B2 (3.15bV): -0.000517
CHAN B3 (GND): -0.000454
OPEN: 0.004415
Full Scale: +/- 1 mA
2023-05-03T21:07:03.934Z,1683148023.934 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683148012 second
2023-05-03T21:07:11.797Z,1683148031.797 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-03T21:07:11.801Z,1683148031.801 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-03T21:07:12.002Z,1683148032.002 [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-03T21:07:15.557Z,1683148035.557 [CommandExec](IMPORTANT): got command show stack
2023-05-03T21:07:15.558Z,1683148035.558 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-03T21:07:15.558Z,1683148035.558 [MissionManager](IMPORTANT): Mission loaded, but not running.
2023-05-03T21:07:43.872Z,1683148063.872 [SBIT](IMPORTANT): SBIT PASSED
2023-05-03T21:07:43.872Z,1683148063.872 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-03T21:07:43.874Z,1683148063.874 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-03T21:07:43.874Z,1683148063.874 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-03T21:07:43.874Z,1683148063.874 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-03T21:07:43.874Z,1683148063.874 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-03T21:07:43.874Z,1683148063.874 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-03T21:07:43.877Z,1683148063.877 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-03T21:07:44.294Z,1683148064.294 [MissionManager](IMPORTANT): Started mission Startup
2023-05-03T21:07:44.294Z,1683148064.294 [Startup] Running Loop=1
2023-05-03T21:07:44.294Z,1683148064.294 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-03T21:07:44.294Z,1683148064.294 [Startup:A.GoToSurface] Running Loop=1
2023-05-03T21:07:44.295Z,1683148064.295 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-03T21:07:44.295Z,1683148064.295 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-03T21:07:44.295Z,1683148064.295 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-03T21:07:44.296Z,1683148064.296 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-03T21:07:44.297Z,1683148064.297 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-03T21:07:44.297Z,1683148064.297 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-03T21:07:44.298Z,1683148064.298 [Startup:StartupSatComms] Running Loop=1
2023-05-03T21:07:44.298Z,1683148064.298 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-03T21:07:44.298Z,1683148064.298 [Startup:StartupSatComms:A] Running Loop=1
2023-05-03T21:07:44.707Z,1683148064.707 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-03T21:07:44.708Z,1683148064.708 [Startup:StartupSatComms:A] Stopped
2023-05-03T21:07:44.708Z,1683148064.708 [Startup:StartupSatComms:B] Running Loop=1
2023-05-03T21:07:45.090Z,1683148065.090 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-03T21:07:56.238Z,1683148076.238 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004718
2023-05-03T21:07:57.200Z,1683148077.200 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:08:03.559Z,1683148083.559 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T204723/Courier0007.lzma
2023-05-03T21:08:04.562Z,1683148084.562 [DataOverHttps](INFO): Moved sent file to Logs/20230503T204723/Courier0007.lzma.bak
2023-05-03T21:08:04.562Z,1683148084.562 [DataOverHttps](INFO): SBD MOMSN=18279919
2023-05-03T21:08:10.512Z,1683148090.512 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:08:20.548Z,1683148100.548 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230503T210516/Courier0000.lzma
2023-05-03T21:08:21.550Z,1683148101.550 [DataOverHttps](INFO): Moved sent file to Logs/20230503T210516/Courier0000.lzma.bak
2023-05-03T21:08:21.550Z,1683148101.550 [DataOverHttps](INFO): SBD MOMSN=18279921
2023-05-03T21:08:23.845Z,1683148103.845 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:08:37.580Z,1683148117.580 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:08:39.913Z,1683148119.913 [DataOverHttps](INFO): Sending 736 bytes from file Logs/20230503T204723/Express0008.lzma
2023-05-03T21:08:40.914Z,1683148120.914 [DataOverHttps](INFO): Moved sent file to Logs/20230503T204723/Express0008.lzma.bak
2023-05-03T21:08:40.914Z,1683148120.914 [DataOverHttps](INFO): SBD MOMSN=18279924
2023-05-03T21:08:44.863Z,1683148124.863 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-03T21:07:44.7Z
2023-05-03T21:08:44.863Z,1683148124.863 [Startup:StartupSatComms:B] Stopped
2023-05-03T21:08:44.864Z,1683148124.864 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-03T21:08:44.864Z,1683148124.864 [Startup:StartupSatComms] Stopped
2023-05-03T21:08:44.864Z,1683148124.864 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-03T21:08:44.865Z,1683148124.865 [Startup](INFO): Completed Startup
2023-05-03T21:08:44.865Z,1683148124.865 [MissionManager](INFO): Startup is completed.
2023-05-03T21:08:44.865Z,1683148124.865 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-03T21:08:44.865Z,1683148124.865 [Startup] Stopped
2023-05-03T21:08:44.865Z,1683148124.865 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-03T21:08:44.865Z,1683148124.865 [Startup:A.GoToSurface] Stopped
2023-05-03T21:08:44.866Z,1683148124.866 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-03T21:08:45.304Z,1683148125.304 [MissionManager](IMPORTANT): Started mission Default
2023-05-03T21:08:45.305Z,1683148125.305 [Default] Running Loop=1
2023-05-03T21:08:45.305Z,1683148125.305 [Default](DEBUG): Aggregate::initialize Default
2023-05-03T21:08:45.305Z,1683148125.305 [Default:B.GoToSurface] Running Loop=1
2023-05-03T21:08:45.305Z,1683148125.305 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-03T21:08:45.305Z,1683148125.305 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-03T21:08:45.306Z,1683148125.306 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-03T21:08:45.306Z,1683148125.306 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-03T21:08:45.306Z,1683148125.306 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-03T21:08:45.306Z,1683148125.306 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-03T21:08:45.307Z,1683148125.307 [Default:A.Wait] Running Loop=1
2023-05-03T21:08:45.307Z,1683148125.307 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:08:51.315Z,1683148131.315 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:08:57.349Z,1683148137.349 [CommandExec](IMPORTANT): got command show stack
2023-05-03T21:08:57.349Z,1683148137.349 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-03T21:08:57.350Z,1683148137.350 [Default](IMPORTANT): Priority 0: Default:A.Wait
2023-05-03T21:08:57.350Z,1683148137.350 [Default](IMPORTANT): Priority 1: Default:B.GoToSurface
2023-05-03T21:08:58.030Z,1683148138.030 [DataOverHttps](INFO): Sending 1235 bytes from file Logs/20230503T210516/Express0001.lzma
2023-05-03T21:08:58.606Z,1683148138.606 [Default:A.Wait](INFO): Done Waiting.
2023-05-03T21:08:58.606Z,1683148138.606 [Default:A.Wait] Stopped
2023-05-03T21:08:58.606Z,1683148138.606 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:08:59.019Z,1683148139.019 [Default:CheckIn] Running Loop=1
2023-05-03T21:08:59.019Z,1683148139.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T21:08:59.019Z,1683148139.019 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T21:08:59.030Z,1683148139.030 [DataOverHttps](INFO): Moved sent file to Logs/20230503T210516/Express0001.lzma.bak
2023-05-03T21:08:59.030Z,1683148139.030 [DataOverHttps](INFO): SBD MOMSN=18279967
2023-05-03T21:08:59.409Z,1683148139.409 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-03T21:09:04.649Z,1683148144.649 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:09:18.406Z,1683148158.406 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:09:32.121Z,1683148172.121 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:09:45.453Z,1683148185.453 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:09:58.784Z,1683148198.784 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:10:12.117Z,1683148212.117 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:11:10.698Z,1683148270.698 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T21:12:51.702Z,1683148371.702 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2023-05-03T21:12:51.705Z,1683148371.705 [BPC1](INFO): Received data from all battery sticks.
2023-05-03T21:12:53.729Z,1683148373.729 [CommandExec](IMPORTANT): got command show stack
2023-05-03T21:12:53.729Z,1683148373.729 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-03T21:12:53.729Z,1683148373.729 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2023-05-03T21:12:53.730Z,1683148373.730 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2023-05-03T21:13:07.735Z,1683148387.735 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-03T21:13:11.448Z,1683148391.448 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-03T21:13:11.449Z,1683148391.449 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-03T21:13:11.554Z,1683148391.554 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 6.000000 count
2023-05-03T21:13:11.557Z,1683148391.557 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m
2023-05-03T21:13:11.567Z,1683148391.567 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s
2023-05-03T21:13:11.591Z,1683148391.591 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count
2023-05-03T21:13:11.609Z,1683148391.609 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count
2023-05-03T21:13:11.612Z,1683148391.612 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count
2023-05-03T21:13:11.619Z,1683148391.619 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min
2023-05-03T21:13:11.622Z,1683148391.622 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 6.000000 enum
2023-05-03T21:13:11.641Z,1683148391.641 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum
2023-05-03T21:13:11.645Z,1683148391.645 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum
2023-05-03T21:13:11.734Z,1683148391.734 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Construct Wait.
2023-05-03T21:13:11.766Z,1683148391.766 [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-03T21:13:11.773Z,1683148391.773 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-03T21:13:11.922Z,1683148391.922 [Default] Stopped
2023-05-03T21:13:11.922Z,1683148391.922 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-03T21:13:11.922Z,1683148391.922 [Default:B.GoToSurface] Stopped
2023-05-03T21:13:11.922Z,1683148391.922 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-03T21:13:11.923Z,1683148391.923 [Default:CheckIn] Stopped
2023-05-03T21:13:11.923Z,1683148391.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T21:13:11.923Z,1683148391.923 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T21:13:11.923Z,1683148391.923 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test
2023-05-03T21:13:11.923Z,1683148391.923 [senddata_direct_and_track_test] Running Loop=1
2023-05-03T21:13:11.923Z,1683148391.923 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test
2023-05-03T21:13:11.923Z,1683148391.923 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-03T21:13:11.923Z,1683148391.923 [senddata_direct_and_track_test:A.](INFO): Initializing Tracking.
2023-05-03T21:13:11.924Z,1683148391.924 [senddata_direct_and_track_test:DirectData] Running Loop=1
2023-05-03T21:13:11.924Z,1683148391.924 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-03T21:13:11.924Z,1683148391.924 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-03T21:13:11.924Z,1683148391.924 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:13:11.925Z,1683148391.925 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-03T21:13:11.925Z,1683148391.925 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:12.337Z,1683148392.337 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:12.737Z,1683148392.737 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:13.123Z,1683148393.123 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:13.524Z,1683148393.524 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:13.932Z,1683148393.932 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:14.330Z,1683148394.330 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:14.734Z,1683148394.734 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:15.139Z,1683148395.139 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:15.556Z,1683148395.556 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:15.966Z,1683148395.966 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:16.354Z,1683148396.354 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:16.761Z,1683148396.760 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:17.170Z,1683148397.170 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:17.566Z,1683148397.566 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:17.970Z,1683148397.970 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:18.381Z,1683148398.381 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:18.774Z,1683148398.774 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:19.183Z,1683148399.183 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:19.588Z,1683148399.588 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:19.990Z,1683148399.990 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:20.390Z,1683148400.390 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:20.794Z,1683148400.794 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:21.198Z,1683148401.198 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:21.612Z,1683148401.612 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:22.011Z,1683148402.011 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:22.409Z,1683148402.409 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:22.814Z,1683148402.814 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:23.229Z,1683148403.229 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:23.624Z,1683148403.624 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:24.030Z,1683148404.030 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:24.439Z,1683148404.439 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:24.843Z,1683148404.843 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:25.243Z,1683148405.243 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:25.642Z,1683148405.642 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:26.046Z,1683148406.046 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:26.461Z,1683148406.461 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:26.854Z,1683148406.854 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:27.258Z,1683148407.258 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:27.259Z,1683148407.259 [senddata_direct_and_track_test:A.](INFO): update period (15.000000 s) has elapsed, querying...
2023-05-03T21:13:27.259Z,1683148407.259 [senddata_direct_and_track_test:A.](INFO): *** querying DAT ***
2023-05-03T21:13:27.397Z,1683148407.397 [DATMMP](INFO): ****** received valid address query ******
2023-05-03T21:13:27.397Z,1683148407.397 [DATMMP](INFO): ****** received valid ping request ******
2023-05-03T21:13:27.400Z,1683148407.400 [DATMMP](INFO): Querying Benthos address 6
2023-05-03T21:13:27.649Z,1683148407.649 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-03T21:13:27.649Z,1683148407.649 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x0	 x2
2023-05-03T21:13:27.650Z,1683148407.649 [DATMMP](INFO): Progress on cmd
2023-05-03T21:13:27.650Z,1683148407.650 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:13:27.650Z,1683148407.650 [DATMMP](INFO): Handled
2023-05-03T21:13:27.650Z,1683148407.650 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:27.705Z,1683148407.705 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:27.901Z,1683148407.901 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:27.902Z,1683148407.902 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:13:27.902Z,1683148407.902 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:13:27.902Z,1683148407.902 [DATMMP](INFO): Handled
2023-05-03T21:13:27.902Z,1683148407.902 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:28.068Z,1683148408.068 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:28.153Z,1683148408.153 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:28.154Z,1683148408.154 [DATMMP](INFO): timestamp rx: 	 x0	 x15	 xD	 x1A	 x1206
2023-05-03T21:13:28.154Z,1683148408.154 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:13:28.154Z,1683148408.154 [DATMMP](INFO): Handled
2023-05-03T21:13:28.154Z,1683148408.154 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:28.405Z,1683148408.405 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:28.475Z,1683148408.475 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:28.656Z,1683148408.656 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:28.880Z,1683148408.880 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:28.908Z,1683148408.908 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:29.160Z,1683148409.160 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:29.292Z,1683148409.292 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:29.412Z,1683148409.412 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:29.665Z,1683148409.665 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:29.688Z,1683148409.688 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:29.916Z,1683148409.916 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:30.091Z,1683148410.091 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:30.168Z,1683148410.168 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:30.430Z,1683148410.430 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:30.431Z,1683148410.431 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-03T21:13:30.431Z,1683148410.431 [DATMMP](INFO): Received message type: doppler
2023-05-03T21:13:30.431Z,1683148410.431 [DATMMP](INFO): Handled
2023-05-03T21:13:30.431Z,1683148410.431 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:30.499Z,1683148410.499 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:30.677Z,1683148410.677 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:30.677Z,1683148410.677 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:13:30.678Z,1683148410.678 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:13:30.678Z,1683148410.678 [DATMMP](INFO): Handled
2023-05-03T21:13:30.678Z,1683148410.678 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:30.914Z,1683148410.914 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:30.929Z,1683148410.929 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:30.930Z,1683148410.930 [DATMMP](INFO): directional_debug rx: 	 x401	 xFFF8	 x2F	 x64	 x2570	 x2AE1	 x2832	 x3473
2023-05-03T21:13:30.931Z,1683148410.931 [DATMMP](INFO): Received message type: directional_debug
2023-05-03T21:13:30.931Z,1683148410.931 [DATMMP](INFO): Handled
2023-05-03T21:13:30.931Z,1683148410.931 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:31.181Z,1683148411.181 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:31.182Z,1683148411.182 [DATMMP](INFO): directional rx: 	 x3F0	 x0	 x1DB	 x8	 x70A	 x18	 x6E32	 x52F	 x5	 xFFEF	 x27B5966	 x-43569E0
2023-05-03T21:13:31.184Z,1683148411.184 [DATMMP](INFO): Received message type: directional
2023-05-03T21:13:31.184Z,1683148411.184 [DATMMP](INFO): Handled
2023-05-03T21:13:31.185Z,1683148411.185 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:31.301Z,1683148411.301 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:31.301Z,1683148411.301 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:31.302Z,1683148411.302 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:31.311Z,1683148411.311 [Reporter](INFO): DATMMP.acoustic_contact_range 28210.000000 m
2023-05-03T21:13:31.433Z,1683148411.433 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-03T21:13:31.434Z,1683148411.434 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x1	 x6
2023-05-03T21:13:31.434Z,1683148411.434 [DATMMP](INFO): Progress on cmd
2023-05-03T21:13:31.434Z,1683148411.434 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:13:31.434Z,1683148411.434 [DATMMP](INFO): Handled
2023-05-03T21:13:31.434Z,1683148411.434 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:31.685Z,1683148411.685 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-03T21:13:31.685Z,1683148411.685 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x2	 x3
2023-05-03T21:13:31.686Z,1683148411.686 [DATMMP](ERROR): 3:COMPLETE
2023-05-03T21:13:31.686Z,1683148411.686 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:13:31.686Z,1683148411.686 [DATMMP](INFO): Handled
2023-05-03T21:13:31.686Z,1683148411.686 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:31.768Z,1683148411.768 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:31.769Z,1683148411.769 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:31.770Z,1683148411.770 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:31.937Z,1683148411.937 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:13:31.937Z,1683148411.937 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:13:31.938Z,1683148411.938 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:13:31.938Z,1683148411.938 [DATMMP](INFO): Handled
2023-05-03T21:13:31.938Z,1683148411.938 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:32.111Z,1683148412.111 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:32.111Z,1683148412.111 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:32.128Z,1683148412.128 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:32.189Z,1683148412.189 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:32.440Z,1683148412.440 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:32.517Z,1683148412.517 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:32.517Z,1683148412.517 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:32.518Z,1683148412.518 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:32.693Z,1683148412.693 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:32.920Z,1683148412.920 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:32.920Z,1683148412.920 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:32.921Z,1683148412.921 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:32.944Z,1683148412.944 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:33.197Z,1683148413.197 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:33.323Z,1683148413.323 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:33.323Z,1683148413.323 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:33.324Z,1683148413.324 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:33.448Z,1683148413.448 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:33.708Z,1683148413.708 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:33.723Z,1683148413.723 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:33.724Z,1683148413.724 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:33.744Z,1683148413.744 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:33.952Z,1683148413.952 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:34.125Z,1683148414.125 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:34.126Z,1683148414.126 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:34.126Z,1683148414.126 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:34.205Z,1683148414.205 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:34.456Z,1683148414.456 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:34.543Z,1683148414.543 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:34.543Z,1683148414.543 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:34.544Z,1683148414.544 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:34.708Z,1683148414.708 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:34.939Z,1683148414.939 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:34.939Z,1683148414.939 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:34.940Z,1683148414.940 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:34.960Z,1683148414.960 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:35.212Z,1683148415.212 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:35.354Z,1683148415.354 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:35.355Z,1683148415.355 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:35.355Z,1683148415.355 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:35.467Z,1683148415.467 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:35.721Z,1683148415.721 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:35.742Z,1683148415.742 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:35.743Z,1683148415.743 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:35.744Z,1683148415.744 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:35.972Z,1683148415.972 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:36.151Z,1683148416.151 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:36.152Z,1683148416.152 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:36.152Z,1683148416.152 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:36.224Z,1683148416.224 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:36.476Z,1683148416.476 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:36.551Z,1683148416.551 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:36.551Z,1683148416.551 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:36.564Z,1683148416.564 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:36.728Z,1683148416.728 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:36.954Z,1683148416.954 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:36.954Z,1683148416.954 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:36.955Z,1683148416.955 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:36.980Z,1683148416.980 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:37.232Z,1683148417.232 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:37.363Z,1683148417.363 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:37.364Z,1683148417.364 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:37.364Z,1683148417.364 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:37.484Z,1683148417.484 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:37.741Z,1683148417.741 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:37.822Z,1683148417.822 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:37.823Z,1683148417.823 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:37.823Z,1683148417.823 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:37.992Z,1683148417.992 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:13:38.187Z,1683148418.187 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:38.187Z,1683148418.187 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:38.192Z,1683148418.192 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:38.622Z,1683148418.622 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:38.622Z,1683148418.622 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:38.623Z,1683148418.623 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:38.974Z,1683148418.974 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:38.975Z,1683148418.975 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:38.975Z,1683148418.975 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:39.393Z,1683148419.393 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:39.394Z,1683148419.394 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:39.394Z,1683148419.394 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:39.817Z,1683148419.817 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:39.818Z,1683148419.818 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:39.819Z,1683148419.819 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:40.198Z,1683148420.198 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:40.198Z,1683148420.198 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:40.199Z,1683148420.199 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:40.599Z,1683148420.599 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:40.599Z,1683148420.599 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:40.604Z,1683148420.604 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:41.028Z,1683148421.028 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:41.028Z,1683148421.028 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:41.029Z,1683148421.029 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:41.403Z,1683148421.403 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:41.403Z,1683148421.403 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:41.404Z,1683148421.404 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:41.801Z,1683148421.801 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:41.802Z,1683148421.802 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:41.802Z,1683148421.802 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:42.210Z,1683148422.210 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:42.211Z,1683148422.211 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:42.212Z,1683148422.212 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:42.615Z,1683148422.615 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:42.615Z,1683148422.615 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:42.616Z,1683148422.616 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:43.014Z,1683148423.014 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:43.014Z,1683148423.014 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:43.015Z,1683148423.015 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:43.419Z,1683148423.419 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:43.419Z,1683148423.419 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:43.420Z,1683148423.420 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:43.827Z,1683148423.827 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:43.828Z,1683148423.828 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:43.829Z,1683148423.829 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:44.225Z,1683148424.225 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:44.226Z,1683148424.226 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:44.226Z,1683148424.226 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:44.625Z,1683148424.625 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T21:13:44.641Z,1683148424.641 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:44.641Z,1683148424.641 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:44.642Z,1683148424.642 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:45.038Z,1683148425.038 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:45.039Z,1683148425.039 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:45.039Z,1683148425.039 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:45.443Z,1683148425.443 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:45.444Z,1683148425.444 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:45.444Z,1683148425.444 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:45.850Z,1683148425.850 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:45.851Z,1683148425.851 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:45.852Z,1683148425.852 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:46.250Z,1683148426.250 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:46.250Z,1683148426.250 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:46.251Z,1683148426.251 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:46.655Z,1683148426.655 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:46.655Z,1683148426.655 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:46.656Z,1683148426.656 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:47.059Z,1683148427.059 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:47.059Z,1683148427.059 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:47.060Z,1683148427.060 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:47.463Z,1683148427.463 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:47.463Z,1683148427.463 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:47.465Z,1683148427.465 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:47.861Z,1683148427.861 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:47.862Z,1683148427.862 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:47.862Z,1683148427.862 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:48.271Z,1683148428.271 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:48.271Z,1683148428.271 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:48.272Z,1683148428.272 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:48.669Z,1683148428.669 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:48.670Z,1683148428.670 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:48.671Z,1683148428.671 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:49.074Z,1683148429.074 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:49.074Z,1683148429.074 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:49.075Z,1683148429.075 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:49.483Z,1683148429.483 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:49.483Z,1683148429.483 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:49.484Z,1683148429.484 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:49.887Z,1683148429.887 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:49.887Z,1683148429.887 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:49.888Z,1683148429.888 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:50.287Z,1683148430.287 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:50.287Z,1683148430.287 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:50.288Z,1683148430.288 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:50.695Z,1683148430.695 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:50.696Z,1683148430.696 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:50.697Z,1683148430.697 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:51.094Z,1683148431.094 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:51.094Z,1683148431.094 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:51.095Z,1683148431.095 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:51.497Z,1683148431.497 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:51.498Z,1683148431.498 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:51.499Z,1683148431.499 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:51.907Z,1683148431.907 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:51.907Z,1683148431.907 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:51.908Z,1683148431.908 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:52.306Z,1683148432.306 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:52.306Z,1683148432.306 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:52.307Z,1683148432.307 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:52.715Z,1683148432.715 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:52.715Z,1683148432.715 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:52.716Z,1683148432.716 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:53.119Z,1683148433.119 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:53.120Z,1683148433.120 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:53.121Z,1683148433.121 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:53.518Z,1683148433.518 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:53.519Z,1683148433.519 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:53.519Z,1683148433.519 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:53.922Z,1683148433.922 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:53.922Z,1683148433.922 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:53.923Z,1683148433.923 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:54.331Z,1683148434.331 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:54.331Z,1683148434.331 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:54.332Z,1683148434.332 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:54.730Z,1683148434.730 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:54.731Z,1683148434.731 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:54.731Z,1683148434.731 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:55.140Z,1683148435.140 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:55.140Z,1683148435.140 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:55.141Z,1683148435.141 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:55.543Z,1683148435.543 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:55.543Z,1683148435.543 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:55.544Z,1683148435.544 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:55.943Z,1683148435.943 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:55.944Z,1683148435.944 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:55.945Z,1683148435.945 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:56.346Z,1683148436.346 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:56.346Z,1683148436.346 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:56.347Z,1683148436.347 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:56.755Z,1683148436.755 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:56.756Z,1683148436.756 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:56.756Z,1683148436.756 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:57.155Z,1683148437.155 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:57.155Z,1683148437.155 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:57.157Z,1683148437.157 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:57.558Z,1683148437.558 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:57.558Z,1683148437.558 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:57.559Z,1683148437.559 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:57.967Z,1683148437.967 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:57.967Z,1683148437.967 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:57.968Z,1683148437.968 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:58.371Z,1683148438.371 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:58.372Z,1683148438.372 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:58.372Z,1683148438.372 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:58.775Z,1683148438.775 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:58.775Z,1683148438.775 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:58.776Z,1683148438.776 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:59.179Z,1683148439.179 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:59.180Z,1683148439.180 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:59.181Z,1683148439.181 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:59.579Z,1683148439.579 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:59.580Z,1683148439.580 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:59.581Z,1683148439.581 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:13:59.982Z,1683148439.982 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:13:59.983Z,1683148439.983 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:13:59.983Z,1683148439.983 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:00.391Z,1683148440.391 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:00.391Z,1683148440.391 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:00.392Z,1683148440.392 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:00.800Z,1683148440.800 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:00.801Z,1683148440.801 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:00.802Z,1683148440.802 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:01.194Z,1683148441.194 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:01.195Z,1683148441.195 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:01.195Z,1683148441.195 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:01.603Z,1683148441.603 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:01.603Z,1683148441.603 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:01.604Z,1683148441.604 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:02.007Z,1683148442.007 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:02.008Z,1683148442.008 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:02.009Z,1683148442.009 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:02.406Z,1683148442.406 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:02.406Z,1683148442.406 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:02.407Z,1683148442.407 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:02.815Z,1683148442.815 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:02.815Z,1683148442.815 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:02.816Z,1683148442.816 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:03.214Z,1683148443.214 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:03.215Z,1683148443.215 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:03.216Z,1683148443.216 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:03.641Z,1683148443.641 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:03.641Z,1683148443.641 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:03.642Z,1683148443.642 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:04.023Z,1683148444.023 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:04.024Z,1683148444.024 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:04.024Z,1683148444.024 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:04.431Z,1683148444.431 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:04.432Z,1683148444.432 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:04.433Z,1683148444.433 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:04.830Z,1683148444.830 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:04.830Z,1683148444.830 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:04.831Z,1683148444.831 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:05.239Z,1683148445.239 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:05.240Z,1683148445.240 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:05.241Z,1683148445.241 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:05.638Z,1683148445.638 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:05.638Z,1683148445.638 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:05.639Z,1683148445.639 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:06.042Z,1683148446.042 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:06.042Z,1683148446.042 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:06.043Z,1683148446.043 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:06.451Z,1683148446.451 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:06.451Z,1683148446.451 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:06.452Z,1683148446.452 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:06.850Z,1683148446.850 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:06.850Z,1683148446.850 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:06.851Z,1683148446.851 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:07.260Z,1683148447.260 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:07.260Z,1683148447.260 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:07.261Z,1683148447.261 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:07.663Z,1683148447.663 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:07.664Z,1683148447.664 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:07.664Z,1683148447.664 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:08.062Z,1683148448.062 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:08.062Z,1683148448.062 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:08.063Z,1683148448.063 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:08.466Z,1683148448.466 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:08.466Z,1683148448.466 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:08.467Z,1683148448.467 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:08.875Z,1683148448.875 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:08.875Z,1683148448.875 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:08.876Z,1683148448.876 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:09.275Z,1683148449.275 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:09.275Z,1683148449.275 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:09.276Z,1683148449.276 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:09.678Z,1683148449.678 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:09.678Z,1683148449.678 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:09.679Z,1683148449.679 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:10.087Z,1683148450.087 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:10.087Z,1683148450.087 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:10.088Z,1683148450.088 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:10.491Z,1683148450.491 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:10.492Z,1683148450.492 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:10.493Z,1683148450.493 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:10.890Z,1683148450.890 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:10.891Z,1683148450.891 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:10.891Z,1683148450.891 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:11.300Z,1683148451.300 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:11.300Z,1683148451.300 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:11.301Z,1683148451.301 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:11.699Z,1683148451.699 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:11.699Z,1683148451.699 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:11.700Z,1683148451.700 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:12.102Z,1683148452.102 [senddata_direct_and_track_test:DirectData:A.Wait](INFO): Done Waiting.
2023-05-03T21:14:12.102Z,1683148452.102 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-03T21:14:12.102Z,1683148452.102 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:14:12.102Z,1683148452.102 [senddata_direct_and_track_test:DirectData:B] Running Loop=1
2023-05-03T21:14:12.103Z,1683148452.103 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:12.103Z,1683148452.103 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:12.104Z,1683148452.104 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:12.511Z,1683148452.511 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Pressure with destination: modem:6:_.pressure
2023-05-03T21:14:12.511Z,1683148452.511 [senddata_direct_and_track_test:DirectData:B] Stopped
2023-05-03T21:14:12.511Z,1683148452.511 [senddata_direct_and_track_test:DirectData:C] Running Loop=1
2023-05-03T21:14:12.512Z,1683148452.512 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:12.512Z,1683148452.512 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:12.513Z,1683148452.513 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:12.552Z,1683148452.552 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-03T21:14:12.553Z,1683148452.553 [DATMMP](INFO): modem://6: set _.pressure 0.746690 atmosphere
2023-05-03T21:14:12.554Z,1683148452.554 [DATMMP](INFO): #Outgoing data=1
2023-05-03T21:14:12.555Z,1683148452.555 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-03T21:14:12.805Z,1683148452.805 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-03T21:14:12.805Z,1683148452.805 [DATMMP](INFO): depth rx: 	 x1	 x1	 x4	 x0
2023-05-03T21:14:12.806Z,1683148452.806 [DATMMP](INFO): Received message type: depth
2023-05-03T21:14:12.806Z,1683148452.806 [DATMMP](INFO): Match for : depth
2023-05-03T21:14:12.806Z,1683148452.806 [DATMMP](INFO): ACK
2023-05-03T21:14:12.806Z,1683148452.806 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-03T21:14:12.910Z,1683148452.910 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Temperature with destination: modem:6:_.temperature
2023-05-03T21:14:12.910Z,1683148452.910 [senddata_direct_and_track_test:DirectData:C] Stopped
2023-05-03T21:14:12.911Z,1683148452.911 [senddata_direct_and_track_test:DirectData:D] Running Loop=1
2023-05-03T21:14:12.911Z,1683148452.911 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:12.911Z,1683148452.911 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:12.913Z,1683148452.913 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:13.057Z,1683148453.057 [DATMMP](INFO): GSXN notify for xid: 17
2023-05-03T21:14:13.058Z,1683148453.058 [DATMMP](INFO): lat rx: 	 x2318600	 x6	 x1	 x0
2023-05-03T21:14:13.058Z,1683148453.058 [DATMMP](INFO): Received message type: lat
2023-05-03T21:14:13.058Z,1683148453.058 [DATMMP](INFO): Match for : lat
2023-05-03T21:14:13.058Z,1683148453.058 [DATMMP](INFO): ACK
2023-05-03T21:14:13.058Z,1683148453.058 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-03T21:14:13.309Z,1683148453.309 [DATMMP](INFO): GSXN notify for xid: 18
2023-05-03T21:14:13.327Z,1683148453.327 [senddata_direct_and_track_test:DirectData:D](INFO): Queuing up send data for Onboard.Humidity with destination: modem:6:_.humidity
2023-05-03T21:14:13.327Z,1683148453.327 [senddata_direct_and_track_test:DirectData:D] Stopped
2023-05-03T21:14:13.328Z,1683148453.328 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData
2023-05-03T21:14:13.332Z,1683148453.332 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-03T21:14:13.332Z,1683148453.332 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-03T21:14:13.332Z,1683148453.332 [senddata_direct_and_track_test:DirectData](INFO): Running loop #2
2023-05-03T21:14:13.332Z,1683148453.332 [senddata_direct_and_track_test:DirectData] Running Loop=2
2023-05-03T21:14:13.333Z,1683148453.333 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-03T21:14:13.333Z,1683148453.333 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-03T21:14:13.333Z,1683148453.333 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:14:13.333Z,1683148453.333 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:13.333Z,1683148453.333 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:13.334Z,1683148453.334 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:13.393Z,1683148453.393 [DATMMP](INFO): lon rx: 	 x0	 x6	 x1	 x0
2023-05-03T21:14:13.393Z,1683148453.393 [DATMMP](INFO): Received message type: lon
2023-05-03T21:14:13.393Z,1683148453.393 [DATMMP](INFO): Match for : lon
2023-05-03T21:14:13.393Z,1683148453.393 [DATMMP](INFO): ACK
2023-05-03T21:14:13.393Z,1683148453.393 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-03T21:14:13.393Z,1683148453.393 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-03T21:14:13.560Z,1683148453.560 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-03T21:14:13.561Z,1683148453.561 [DATMMP](INFO): Tx to 6 len 34 xid 19
2023-05-03T21:14:13.561Z,1683148453.561 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-03T21:14:13.562Z,1683148453.562 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-03T21:14:13.735Z,1683148453.735 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:13.736Z,1683148453.736 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:13.736Z,1683148453.736 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:13.812Z,1683148453.812 [DATMMP](INFO): GSXN notify for xid: 19
2023-05-03T21:14:13.813Z,1683148453.813 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-03T21:14:13.814Z,1683148453.814 [DATMMP](DEBUG): Command complete send
2023-05-03T21:14:13.814Z,1683148453.814 [DATMMP](ERROR): 1:COMPLETE
2023-05-03T21:14:13.814Z,1683148453.814 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:14:13.814Z,1683148453.814 [DATMMP](INFO): Handled
2023-05-03T21:14:13.814Z,1683148453.814 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:14.067Z,1683148454.067 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:14.068Z,1683148454.068 [DATMMP](INFO): timestamp rx: 	 x0	 x15	 xE	 xC	 x19C7
2023-05-03T21:14:14.068Z,1683148454.068 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:14:14.068Z,1683148454.068 [DATMMP](INFO): Handled
2023-05-03T21:14:14.068Z,1683148454.068 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:14.069Z,1683148454.069 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:14.141Z,1683148454.141 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:14.142Z,1683148454.142 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:14.143Z,1683148454.143 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:14.320Z,1683148454.320 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:14.321Z,1683148454.321 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:14.539Z,1683148454.539 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:14.544Z,1683148454.544 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:14.545Z,1683148454.545 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:14.572Z,1683148454.572 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:14.573Z,1683148454.573 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:14.824Z,1683148454.824 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:14.824Z,1683148454.824 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:14.939Z,1683148454.939 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:14.944Z,1683148454.944 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:14.945Z,1683148454.945 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:15.076Z,1683148455.076 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:15.077Z,1683148455.077 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:15.328Z,1683148455.328 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:15.350Z,1683148455.350 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:15.350Z,1683148455.350 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:15.351Z,1683148455.351 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:15.580Z,1683148455.580 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:15.581Z,1683148455.581 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:15.752Z,1683148455.752 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:15.756Z,1683148455.756 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:15.757Z,1683148455.757 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:15.832Z,1683148455.832 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:15.833Z,1683148455.833 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:16.084Z,1683148456.084 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:16.084Z,1683148456.084 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:16.184Z,1683148456.184 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:16.184Z,1683148456.184 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:16.185Z,1683148456.185 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:16.336Z,1683148456.336 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:16.336Z,1683148456.336 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:16.554Z,1683148456.554 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:16.554Z,1683148456.554 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:16.555Z,1683148456.555 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:16.588Z,1683148456.588 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:16.589Z,1683148456.589 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:16.840Z,1683148456.840 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:16.841Z,1683148456.841 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:16.950Z,1683148456.950 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:16.950Z,1683148456.950 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:16.951Z,1683148456.951 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:17.092Z,1683148457.092 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:17.093Z,1683148457.093 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:17.344Z,1683148457.344 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:17.345Z,1683148457.345 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:17.356Z,1683148457.356 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:17.357Z,1683148457.357 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:17.357Z,1683148457.357 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:17.596Z,1683148457.596 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:17.596Z,1683148457.596 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:17.759Z,1683148457.759 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:17.760Z,1683148457.760 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:17.761Z,1683148457.761 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:17.848Z,1683148457.848 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:17.849Z,1683148457.849 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:18.107Z,1683148458.107 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:18.107Z,1683148458.107 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:18.162Z,1683148458.162 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:18.163Z,1683148458.163 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:18.163Z,1683148458.163 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:18.353Z,1683148458.353 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:18.353Z,1683148458.353 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-03T21:14:18.353Z,1683148458.353 [DATMMP](INFO): Received message type: doppler
2023-05-03T21:14:18.353Z,1683148458.353 [DATMMP](INFO): Handled
2023-05-03T21:14:18.354Z,1683148458.354 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:18.354Z,1683148458.354 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:18.565Z,1683148458.565 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:18.566Z,1683148458.566 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:18.566Z,1683148458.566 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:18.605Z,1683148458.605 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:18.605Z,1683148458.605 [DATMMP](INFO): timestamp rx: 	 x1	 x15	 xE	 x10	 x1BA6
2023-05-03T21:14:18.606Z,1683148458.606 [DATMMP](INFO): Rx dataTimestamp_ set to:1683148461.126976
2023-05-03T21:14:18.607Z,1683148458.607 [DATMMP](INFO): Rx ping set to:1683148458.605558
2023-05-03T21:14:18.607Z,1683148458.607 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:14:18.607Z,1683148458.607 [DATMMP](INFO): Handled
2023-05-03T21:14:18.607Z,1683148458.607 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:18.607Z,1683148458.607 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:18.857Z,1683148458.857 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:18.858Z,1683148458.858 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-03T21:14:18.858Z,1683148458.858 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-03T21:14:18.858Z,1683148458.858 [DATMMP](INFO): Received message type: remote_header
2023-05-03T21:14:18.858Z,1683148458.858 [DATMMP](INFO): Handled
2023-05-03T21:14:18.859Z,1683148458.859 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:18.859Z,1683148458.859 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:18.972Z,1683148458.972 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:18.973Z,1683148458.973 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:18.973Z,1683148458.973 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:19.109Z,1683148459.109 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:19.109Z,1683148459.109 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:14:19.110Z,1683148459.110 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:14:19.110Z,1683148459.110 [DATMMP](INFO): Handled
2023-05-03T21:14:19.110Z,1683148459.110 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:19.110Z,1683148459.110 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:19.361Z,1683148459.361 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:19.362Z,1683148459.362 [DATMMP](INFO): directional_debug rx: 	 x3C2	 xF3	 x2D	 x49	 x2560	 x2A71	 x27F2	 x3493
2023-05-03T21:14:19.363Z,1683148459.363 [DATMMP](INFO): Received message type: directional_debug
2023-05-03T21:14:19.363Z,1683148459.363 [DATMMP](INFO): Handled
2023-05-03T21:14:19.363Z,1683148459.363 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:19.363Z,1683148459.363 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:19.378Z,1683148459.378 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:19.378Z,1683148459.378 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:19.379Z,1683148459.379 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:19.613Z,1683148459.613 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:19.614Z,1683148459.614 [DATMMP](INFO): directional rx: 	 x3B7	 xA9	 x21A	 xFF0D	 x718	 xFF1D	 x0	 x4FB	 x5	 xFFEF	 x2318600	 x0
2023-05-03T21:14:19.616Z,1683148459.616 [DATMMP](INFO): Received message type: directional
2023-05-03T21:14:19.616Z,1683148459.616 [DATMMP](INFO): Handled
2023-05-03T21:14:19.616Z,1683148459.616 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:19.616Z,1683148459.616 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:19.783Z,1683148459.783 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:19.784Z,1683148459.784 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:19.784Z,1683148459.784 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:19.793Z,1683148459.793 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-03T21:14:19.864Z,1683148459.864 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:19.866Z,1683148459.866 [DATMMP](INFO): acstats rx: 	 x6	 x5	 xFFFF	 x1D7	 x32	 x42	 xAE	 xAD	 xD	 xFFFF	 x8F	 x31	 xB	 x0
2023-05-03T21:14:19.866Z,1683148459.866 [DATMMP](INFO): Received message type: acstats
2023-05-03T21:14:19.867Z,1683148459.867 [DATMMP](INFO): Handled
2023-05-03T21:14:19.867Z,1683148459.867 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:19.867Z,1683148459.867 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:20.117Z,1683148460.117 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:20.117Z,1683148460.117 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-03T21:14:20.118Z,1683148460.118 [DATMMP](INFO): Check CRC
2023-05-03T21:14:20.118Z,1683148460.118 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-03T21:14:20.118Z,1683148460.118 [DATMMP](INFO): Got DATA: len 4
2023-05-03T21:14:20.118Z,1683148460.118 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-03T21:14:20.118Z,1683148460.118 [DATMMP](INFO): Got ~~ ack, bytes left 0
2023-05-03T21:14:20.119Z,1683148460.119 [DATMMP](INFO): Received command: 

2023-05-03T21:14:20.119Z,1683148460.119 [DATMMP](INFO): Received message type: data_rx
2023-05-03T21:14:20.119Z,1683148460.119 [DATMMP](INFO): Handled
2023-05-03T21:14:20.119Z,1683148460.119 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-03T21:14:20.119Z,1683148460.119 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-03T21:14:20.127Z,1683148460.127 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:20.182Z,1683148460.182 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:20.182Z,1683148460.182 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:20.183Z,1683148460.183 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:20.369Z,1683148460.369 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-03T21:14:20.370Z,1683148460.370 [DATMMP](INFO): modem://6: set _.temperature 25.105616 celsius
2023-05-03T21:14:20.370Z,1683148460.370 [DATMMP](INFO): #Outgoing data=1
2023-05-03T21:14:20.370Z,1683148460.370 [DATMMP](ERROR): Waiting for previous to be ack'd
2023-05-03T21:14:20.370Z,1683148460.370 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-03T21:14:20.370Z,1683148460.370 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:20.586Z,1683148460.586 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:20.586Z,1683148460.586 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:20.587Z,1683148460.587 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:20.620Z,1683148460.620 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-03T21:14:20.620Z,1683148460.620 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-03T21:14:20.621Z,1683148460.621 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:20.872Z,1683148460.872 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-03T21:14:20.873Z,1683148460.873 [DATMMP](INFO): Tx to 6 len 35 xid 20
2023-05-03T21:14:20.873Z,1683148460.873 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-03T21:14:20.873Z,1683148460.873 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-03T21:14:20.874Z,1683148460.874 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:20.995Z,1683148460.995 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:20.995Z,1683148460.995 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:20.996Z,1683148460.996 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:21.125Z,1683148461.125 [DATMMP](INFO): GSXN notify for xid: 20
2023-05-03T21:14:21.125Z,1683148461.125 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-03T21:14:21.126Z,1683148461.126 [DATMMP](DEBUG): Command complete send
2023-05-03T21:14:21.126Z,1683148461.126 [DATMMP](ERROR): 1:COMPLETE
2023-05-03T21:14:21.126Z,1683148461.126 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:14:21.126Z,1683148461.126 [DATMMP](INFO): Handled
2023-05-03T21:14:21.126Z,1683148461.126 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:21.126Z,1683148461.126 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:21.377Z,1683148461.377 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:21.378Z,1683148461.378 [DATMMP](INFO): timestamp rx: 	 x0	 x15	 xE	 x13	 x257E
2023-05-03T21:14:21.378Z,1683148461.378 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:14:21.378Z,1683148461.378 [DATMMP](INFO): Handled
2023-05-03T21:14:21.378Z,1683148461.378 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:21.378Z,1683148461.378 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:21.404Z,1683148461.404 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:21.404Z,1683148461.404 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:21.405Z,1683148461.405 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:21.628Z,1683148461.628 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:21.629Z,1683148461.629 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:21.803Z,1683148461.803 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:21.804Z,1683148461.804 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:21.805Z,1683148461.805 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:21.880Z,1683148461.880 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:21.881Z,1683148461.881 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:22.132Z,1683148462.132 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:22.132Z,1683148462.132 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:22.207Z,1683148462.207 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:22.207Z,1683148462.207 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:22.208Z,1683148462.208 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:22.384Z,1683148462.384 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:22.384Z,1683148462.384 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:22.611Z,1683148462.611 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:22.612Z,1683148462.612 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:22.613Z,1683148462.613 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:22.636Z,1683148462.636 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:22.637Z,1683148462.637 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:22.888Z,1683148462.888 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:22.889Z,1683148462.889 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:23.010Z,1683148463.010 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:23.010Z,1683148463.010 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:23.011Z,1683148463.011 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:23.140Z,1683148463.140 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:23.141Z,1683148463.141 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:23.393Z,1683148463.393 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:23.393Z,1683148463.393 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:23.415Z,1683148463.415 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:23.416Z,1683148463.416 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:23.417Z,1683148463.417 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:23.644Z,1683148463.644 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:23.645Z,1683148463.645 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:23.818Z,1683148463.818 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:23.819Z,1683148463.819 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:23.819Z,1683148463.819 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:23.896Z,1683148463.896 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:23.897Z,1683148463.897 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:24.148Z,1683148464.148 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:24.149Z,1683148464.149 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:24.222Z,1683148464.222 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:24.222Z,1683148464.222 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:24.223Z,1683148464.223 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:24.401Z,1683148464.401 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:24.401Z,1683148464.401 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:24.653Z,1683148464.653 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:24.653Z,1683148464.653 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:24.652Z,1683148464.652 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:24.654Z,1683148464.654 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:24.655Z,1683148464.655 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:24.904Z,1683148464.904 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:24.905Z,1683148464.905 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:25.064Z,1683148465.064 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:25.065Z,1683148465.065 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:25.065Z,1683148465.065 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:25.156Z,1683148465.156 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:25.157Z,1683148465.157 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:25.233Z,1683148465.233 [CommandExec](IMPORTANT): got command stop
2023-05-03T21:14:25.233Z,1683148465.233 [CommandExec](IMPORTANT): Scheduling is paused
2023-05-03T21:14:25.233Z,1683148465.233 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-05-03T21:14:25.408Z,1683148465.408 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:25.409Z,1683148465.409 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:25.441Z,1683148465.441 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:14:25.441Z,1683148465.441 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:14:25.442Z,1683148465.442 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:14:25.442Z,1683148465.442 [MissionManager](INFO): MissionManager is completed.
2023-05-03T21:14:25.442Z,1683148465.442 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test
2023-05-03T21:14:25.442Z,1683148465.442 [senddata_direct_and_track_test] Stopped
2023-05-03T21:14:25.442Z,1683148465.442 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test
2023-05-03T21:14:25.442Z,1683148465.442 [senddata_direct_and_track_test:A.] Stopped
2023-05-03T21:14:25.442Z,1683148465.442 [senddata_direct_and_track_test:A.](DEBUG): Uninitializing Tracking.
2023-05-03T21:14:25.443Z,1683148465.443 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-03T21:14:25.443Z,1683148465.443 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-03T21:14:25.443Z,1683148465.443 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-03T21:14:25.443Z,1683148465.443 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:14:25.661Z,1683148465.661 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:25.664Z,1683148465.664 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-03T21:14:25.665Z,1683148465.665 [DATMMP](INFO): Received message type: doppler
2023-05-03T21:14:25.665Z,1683148465.665 [DATMMP](INFO): Handled
2023-05-03T21:14:25.666Z,1683148465.666 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:25.667Z,1683148465.667 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:25.848Z,1683148465.848 [MissionManager](IMPORTANT): Started mission Default
2023-05-03T21:14:25.848Z,1683148465.848 [Default] Running Loop=1
2023-05-03T21:14:25.848Z,1683148465.848 [Default](DEBUG): Aggregate::initialize Default
2023-05-03T21:14:25.848Z,1683148465.848 [Default:B.GoToSurface] Running Loop=1
2023-05-03T21:14:25.848Z,1683148465.848 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-03T21:14:25.849Z,1683148465.849 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-03T21:14:25.849Z,1683148465.849 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-03T21:14:25.849Z,1683148465.849 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-03T21:14:25.850Z,1683148465.850 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-03T21:14:25.850Z,1683148465.850 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-03T21:14:25.851Z,1683148465.851 [Default:A.Wait] Running Loop=1
2023-05-03T21:14:25.851Z,1683148465.851 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:14:25.913Z,1683148465.913 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:25.914Z,1683148465.914 [DATMMP](INFO): timestamp rx: 	 x1	 x15	 xE	 x18	 x243
2023-05-03T21:14:25.914Z,1683148465.914 [DATMMP](INFO): Rx dataTimestamp_ set to:1683148469.686080
2023-05-03T21:14:25.914Z,1683148465.914 [DATMMP](INFO): Rx ping set to:1683148465.913614
2023-05-03T21:14:25.915Z,1683148465.915 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:14:25.915Z,1683148465.915 [DATMMP](INFO): Handled
2023-05-03T21:14:25.915Z,1683148465.915 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:25.915Z,1683148465.915 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:26.165Z,1683148466.165 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:26.166Z,1683148466.166 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-03T21:14:26.166Z,1683148466.166 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-03T21:14:26.166Z,1683148466.166 [DATMMP](INFO): Received message type: remote_header
2023-05-03T21:14:26.167Z,1683148466.167 [DATMMP](INFO): Handled
2023-05-03T21:14:26.167Z,1683148466.167 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:26.167Z,1683148466.167 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:26.417Z,1683148466.417 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:26.418Z,1683148466.418 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:14:26.418Z,1683148466.418 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:14:26.418Z,1683148466.418 [DATMMP](INFO): Handled
2023-05-03T21:14:26.418Z,1683148466.418 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:26.418Z,1683148466.418 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:26.669Z,1683148466.669 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:26.670Z,1683148466.670 [DATMMP](INFO): directional_debug rx: 	 x411	 xFFE6	 x2C	 x6A	 x2590	 x2AC1	 x27F2	 x33F3
2023-05-03T21:14:26.671Z,1683148466.671 [DATMMP](INFO): Received message type: directional_debug
2023-05-03T21:14:26.671Z,1683148466.671 [DATMMP](INFO): Handled
2023-05-03T21:14:26.671Z,1683148466.671 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:26.671Z,1683148466.671 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:26.921Z,1683148466.921 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:26.922Z,1683148466.922 [DATMMP](INFO): directional rx: 	 x3FF	 xFFEE	 x1CB	 x1A	 x6C2	 x28	 x0	 x4F7	 x5	 xFFF1	 x2318600	 x0
2023-05-03T21:14:26.924Z,1683148466.924 [DATMMP](INFO): Received message type: directional
2023-05-03T21:14:26.924Z,1683148466.924 [DATMMP](INFO): Handled
2023-05-03T21:14:26.924Z,1683148466.924 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:26.924Z,1683148466.924 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:27.082Z,1683148467.082 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-03T21:14:27.173Z,1683148467.173 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:27.175Z,1683148467.175 [DATMMP](INFO): acstats rx: 	 x6	 x5	 xFFFF	 x227	 x32	 x46	 xC6	 xC6	 xD	 xFFFF	 xAD	 x32	 xC	 x0
2023-05-03T21:14:27.175Z,1683148467.175 [DATMMP](INFO): Received message type: acstats
2023-05-03T21:14:27.175Z,1683148467.175 [DATMMP](INFO): Handled
2023-05-03T21:14:27.175Z,1683148467.175 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:27.175Z,1683148467.175 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:27.425Z,1683148467.425 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](INFO): Check CRC
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](INFO): Got DATA: len 4
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](INFO): Got ~~ ack, bytes left 2
2023-05-03T21:14:27.426Z,1683148467.426 [DATMMP](INFO): Received command: 

2023-05-03T21:14:27.427Z,1683148467.427 [DATMMP](INFO): Received message type: data_rx
2023-05-03T21:14:27.427Z,1683148467.427 [DATMMP](INFO): Handled
2023-05-03T21:14:27.427Z,1683148467.427 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-03T21:14:27.427Z,1683148467.427 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-03T21:14:27.427Z,1683148467.427 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:27.677Z,1683148467.677 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-03T21:14:27.677Z,1683148467.677 [DATMMP](INFO): modem://6: set _.humidity 12.702968 percent
2023-05-03T21:14:27.678Z,1683148467.678 [DATMMP](INFO): #Outgoing data=1
2023-05-03T21:14:27.678Z,1683148467.678 [DATMMP](ERROR): Waiting for previous to be ack'd
2023-05-03T21:14:27.678Z,1683148467.678 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-03T21:14:27.678Z,1683148467.678 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:27.929Z,1683148467.929 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-03T21:14:27.929Z,1683148467.929 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-03T21:14:27.929Z,1683148467.929 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:28.180Z,1683148468.180 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-03T21:14:28.181Z,1683148468.181 [DATMMP](INFO): Tx to 6 len 32 xid 21
2023-05-03T21:14:28.181Z,1683148468.181 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-03T21:14:28.181Z,1683148468.181 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-03T21:14:28.182Z,1683148468.182 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:28.433Z,1683148468.433 [DATMMP](INFO): GSXN notify for xid: 21
2023-05-03T21:14:28.434Z,1683148468.434 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-03T21:14:28.434Z,1683148468.434 [DATMMP](DEBUG): Command complete send
2023-05-03T21:14:28.434Z,1683148468.434 [DATMMP](ERROR): 1:COMPLETE
2023-05-03T21:14:28.434Z,1683148468.434 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:14:28.434Z,1683148468.434 [DATMMP](INFO): Handled
2023-05-03T21:14:28.434Z,1683148468.434 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:28.435Z,1683148468.435 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:28.685Z,1683148468.685 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:28.686Z,1683148468.686 [DATMMP](INFO): timestamp rx: 	 x0	 x15	 xE	 x1B	 xA26
2023-05-03T21:14:28.686Z,1683148468.686 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:14:28.686Z,1683148468.686 [DATMMP](INFO): Handled
2023-05-03T21:14:28.686Z,1683148468.686 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:28.686Z,1683148468.686 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:28.937Z,1683148468.937 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:28.937Z,1683148468.937 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:29.188Z,1683148469.188 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:29.189Z,1683148469.189 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:29.440Z,1683148469.440 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:29.440Z,1683148469.440 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:29.692Z,1683148469.692 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:29.693Z,1683148469.693 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:29.945Z,1683148469.945 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:29.945Z,1683148469.945 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:30.196Z,1683148470.196 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:30.197Z,1683148470.197 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:30.449Z,1683148470.449 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:30.449Z,1683148470.449 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:30.700Z,1683148470.700 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:30.701Z,1683148470.701 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:30.952Z,1683148470.952 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:30.953Z,1683148470.953 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:31.205Z,1683148471.205 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:31.205Z,1683148471.205 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:31.457Z,1683148471.457 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:31.457Z,1683148471.457 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:31.708Z,1683148471.708 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:31.709Z,1683148471.709 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:31.960Z,1683148471.960 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:31.961Z,1683148471.961 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:32.212Z,1683148472.212 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:32.213Z,1683148472.213 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:32.465Z,1683148472.465 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:32.465Z,1683148472.465 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:32.717Z,1683148472.717 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:32.717Z,1683148472.717 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:32.969Z,1683148472.969 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:32.969Z,1683148472.969 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-03T21:14:32.969Z,1683148472.969 [DATMMP](INFO): Received message type: doppler
2023-05-03T21:14:32.970Z,1683148472.970 [DATMMP](INFO): Handled
2023-05-03T21:14:32.970Z,1683148472.970 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:32.970Z,1683148472.970 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:33.221Z,1683148473.221 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:33.222Z,1683148473.222 [DATMMP](INFO): timestamp rx: 	 x1	 x15	 xE	 x1F	 xDFB
2023-05-03T21:14:33.222Z,1683148473.222 [DATMMP](INFO): Rx dataTimestamp_ set to:1683148505.997376
2023-05-03T21:14:33.222Z,1683148473.222 [DATMMP](INFO): Rx ping set to:1683148473.221690
2023-05-03T21:14:33.223Z,1683148473.223 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:14:33.223Z,1683148473.223 [DATMMP](INFO): Handled
2023-05-03T21:14:33.223Z,1683148473.223 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:33.223Z,1683148473.223 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:33.481Z,1683148473.481 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:33.482Z,1683148473.482 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-03T21:14:33.482Z,1683148473.482 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-03T21:14:33.482Z,1683148473.482 [DATMMP](INFO): Received message type: remote_header
2023-05-03T21:14:33.483Z,1683148473.483 [DATMMP](INFO): Handled
2023-05-03T21:14:33.483Z,1683148473.483 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:33.483Z,1683148473.483 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:33.733Z,1683148473.733 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:33.734Z,1683148473.734 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:14:33.734Z,1683148473.734 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:14:33.734Z,1683148473.734 [DATMMP](INFO): Handled
2023-05-03T21:14:33.734Z,1683148473.734 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:33.734Z,1683148473.734 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:33.985Z,1683148473.985 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:33.986Z,1683148473.986 [DATMMP](INFO): directional_debug rx: 	 x3D5	 x57	 x2E	 x4C	 x25F0	 x2AC1	 x2712	 x33F3
2023-05-03T21:14:33.987Z,1683148473.987 [DATMMP](INFO): Received message type: directional_debug
2023-05-03T21:14:33.987Z,1683148473.987 [DATMMP](INFO): Handled
2023-05-03T21:14:33.987Z,1683148473.987 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:33.987Z,1683148473.987 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:34.237Z,1683148474.237 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:34.238Z,1683148474.238 [DATMMP](INFO): directional rx: 	 x3C7	 x44	 x207	 xFFA9	 x702	 xFFB9	 x0	 x4F9	 x4	 xFFF0	 x2318600	 x0
2023-05-03T21:14:34.240Z,1683148474.240 [DATMMP](INFO): Received message type: directional
2023-05-03T21:14:34.240Z,1683148474.240 [DATMMP](INFO): Handled
2023-05-03T21:14:34.240Z,1683148474.240 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:34.240Z,1683148474.240 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:34.351Z,1683148474.351 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-03T21:14:34.489Z,1683148474.489 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:34.490Z,1683148474.490 [DATMMP](INFO): acstats rx: 	 x6	 x5	 xFFFF	 x22B	 x32	 x4E	 xBD	 xC3	 xD	 xFFFF	 x98	 x30	 xB	 x0
2023-05-03T21:14:34.491Z,1683148474.491 [DATMMP](INFO): Received message type: acstats
2023-05-03T21:14:34.491Z,1683148474.491 [DATMMP](INFO): Handled
2023-05-03T21:14:34.491Z,1683148474.491 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-03T21:14:34.491Z,1683148474.491 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:34.741Z,1683148474.741 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](INFO): Check CRC
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](INFO): Got DATA: len 4
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](INFO): Got ~~ ack, bytes left 2
2023-05-03T21:14:34.742Z,1683148474.742 [DATMMP](INFO): Received command: 

2023-05-03T21:14:34.743Z,1683148474.743 [DATMMP](INFO): Received message type: data_rx
2023-05-03T21:14:34.743Z,1683148474.743 [DATMMP](INFO): Handled
2023-05-03T21:14:34.743Z,1683148474.743 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-03T21:14:34.743Z,1683148474.743 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-03T21:14:34.743Z,1683148474.743 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:34.993Z,1683148474.993 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:35.244Z,1683148475.244 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:35.496Z,1683148475.496 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:35.748Z,1683148475.748 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:36.001Z,1683148476.001 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:36.253Z,1683148476.253 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:36.504Z,1683148476.504 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:36.756Z,1683148476.756 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:37.008Z,1683148477.008 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:37.261Z,1683148477.261 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:37.512Z,1683148477.512 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:37.764Z,1683148477.764 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:38.017Z,1683148478.017 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:38.268Z,1683148478.268 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:38.521Z,1683148478.521 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:14:39.171Z,1683148479.171 [Default:A.Wait](INFO): Done Waiting.
2023-05-03T21:14:39.171Z,1683148479.171 [Default:A.Wait] Stopped
2023-05-03T21:14:39.171Z,1683148479.171 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:14:39.576Z,1683148479.576 [Default:CheckIn] Running Loop=1
2023-05-03T21:14:39.576Z,1683148479.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T21:14:39.576Z,1683148479.576 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T21:15:12.292Z,1683148512.292 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-03T21:15:12.372Z,1683148512.372 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-03T21:19:39.758Z,1683148779.758 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T21:14:39.6Z
2023-05-03T21:19:39.758Z,1683148779.758 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T21:19:39.758Z,1683148779.758 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T21:19:40.162Z,1683148780.162 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-03T21:19:43.373Z,1683148783.373 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T21:19:47.283Z,1683148787.283 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230503T210516/Courier0004.lzma
2023-05-03T21:19:48.286Z,1683148788.286 [DataOverHttps](INFO): Moved sent file to Logs/20230503T210516/Courier0004.lzma.bak
2023-05-03T21:19:48.286Z,1683148788.286 [DataOverHttps](INFO): SBD MOMSN=18280032
2023-05-03T21:20:05.241Z,1683148805.241 [DataOverHttps](INFO): Sending 518 bytes from file Logs/20230503T210516/Express0005.lzma
2023-05-03T21:20:06.242Z,1683148806.242 [DataOverHttps](INFO): Moved sent file to Logs/20230503T210516/Express0005.lzma.bak
2023-05-03T21:20:06.242Z,1683148806.242 [DataOverHttps](INFO): SBD MOMSN=18280037
2023-05-03T21:20:08.835Z,1683148808.835 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T21:20:08.835Z,1683148808.835 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T21:20:08.835Z,1683148808.835 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:20:14.077Z,1683148814.077 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T21:20:34.317Z,1683148834.317 [CommandExec](IMPORTANT): got command failComponent
2023-05-03T21:20:34.317Z,1683148834.317 [CommandExec](IMPORTANT): Failed components:
2023-05-03T21:20:34.317Z,1683148834.317 [CommandExec](IMPORTANT): No failed Components.
2023-05-03T21:25:09.423Z,1683149109.423 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T21:25:09.423Z,1683149109.423 [Default:CheckIn:C.Wait] Stopped
2023-05-03T21:25:09.423Z,1683149109.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:25:09.423Z,1683149109.423 [Default:CheckIn:D] Running Loop=1
2023-05-03T21:25:09.827Z,1683149109.827 [Default:CheckIn:D] Stopped
2023-05-03T21:25:09.827Z,1683149109.827 [Default:CheckIn:E] Running Loop=1
2023-05-03T21:25:10.231Z,1683149110.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.732976 min 
2023-05-03T21:25:10.231Z,1683149110.231 [Default:CheckIn:E] Stopped
2023-05-03T21:25:10.231Z,1683149110.231 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T21:25:10.231Z,1683149110.231 [Default:CheckIn] Stopped
2023-05-03T21:25:10.231Z,1683149110.231 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T21:25:10.232Z,1683149110.232 [Default:CheckIn](INFO): Running loop #2
2023-05-03T21:25:10.232Z,1683149110.232 [Default:CheckIn] Running Loop=2
2023-05-03T21:25:10.232Z,1683149110.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T21:25:10.232Z,1683149110.232 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T21:25:11.837Z,1683149111.837 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-03T21:25:11.837Z,1683149111.837 [NAL9602] Data Fault, FailCount= 1
2023-05-03T21:25:11.837Z,1683149111.837 [NAL9602](ERROR): Data Fault
2023-05-03T21:25:11.857Z,1683149111.857 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-03T21:25:12.237Z,1683149112.237 [NAL9602](INFO): Powering down
2023-05-03T21:25:13.072Z,1683149113.072 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-03T21:25:13.072Z,1683149113.072 [NAL9602] No Fault, FailCount= 1
2023-05-03T21:25:42.542Z,1683149142.542 [NAL9602](INFO): Powering up NAL9602
2023-05-03T21:25:53.453Z,1683149153.453 [NAL9602](INFO): NAL9602 initialized