2023-05-04T23:09:59.569Z,1683241799.569 [Supervisor](DEBUG): Initializing supervisor.
2023-05-04T23:09:59.573Z,1683241799.573 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-04T23:09:59.574Z,1683241799.574 [SyncHandler](INFO): Protected caller Thread ID is 3728
2023-05-04T23:09:59.574Z,1683241799.574 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-04T23:09:59.575Z,1683241799.575 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-04T23:09:59.575Z,1683241799.575 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3729
2023-05-04T23:09:59.579Z,1683241799.579 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-04T23:09:59.597Z,1683241799.597 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-04T23:09:59.597Z,1683241799.597 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-04T23:09:59.598Z,1683241799.598 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3730
2023-05-04T23:09:59.602Z,1683241799.602 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-04T23:09:59.603Z,1683241799.603 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-04T23:09:59.603Z,1683241799.603 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3731
2023-05-04T23:09:59.605Z,1683241799.605 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-04T23:09:59.606Z,1683241799.606 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-04T23:09:59.607Z,1683241799.607 [logger ThreadHandler](INFO): Protected caller Thread ID is 3732
2023-05-04T23:09:59.610Z,1683241799.610 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-04T23:09:59.610Z,1683241799.610 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-04T23:09:59.612Z,1683241799.612 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-04T23:10:00.056Z,1683241800.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-04T23:10:00.056Z,1683241800.056 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-04T23:10:00.271Z,1683241800.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-04T23:10:00.272Z,1683241800.272 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-04T23:10:00.376Z,1683241800.376 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-04T23:10:00.619Z,1683241800.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-04T23:10:00.619Z,1683241800.619 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-04T23:10:00.696Z,1683241800.696 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-04T23:10:00.789Z,1683241800.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-04T23:10:00.789Z,1683241800.789 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-04T23:10:01.110Z,1683241801.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-04T23:10:01.110Z,1683241801.110 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-04T23:10:01.237Z,1683241801.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-04T23:10:01.238Z,1683241801.238 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-04T23:10:01.714Z,1683241801.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-04T23:10:01.714Z,1683241801.714 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-04T23:10:01.889Z,1683241801.889 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-04T23:10:01.889Z,1683241801.889 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-04T23:10:02.268Z,1683241802.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-04T23:10:02.268Z,1683241802.268 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-04T23:10:02.535Z,1683241802.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-04T23:10:02.536Z,1683241802.536 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-04T23:10:02.642Z,1683241802.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-04T23:10:02.642Z,1683241802.642 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-04T23:10:03.387Z,1683241803.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-04T23:10:03.387Z,1683241803.387 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-04T23:10:03.703Z,1683241803.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-04T23:10:03.703Z,1683241803.703 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-04T23:10:03.914Z,1683241803.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-04T23:10:03.916Z,1683241803.916 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-04T23:10:03.917Z,1683241803.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-04T23:10:04.095Z,1683241804.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-04T23:10:04.176Z,1683241804.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-04T23:10:04.305Z,1683241804.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-04T23:10:04.387Z,1683241804.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-04T23:10:04.643Z,1683241804.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-04T23:10:04.644Z,1683241804.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-04T23:10:04.745Z,1683241804.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-04T23:10:04.853Z,1683241804.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-04T23:10:04.961Z,1683241804.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-04T23:10:05.104Z,1683241805.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-04T23:10:05.199Z,1683241805.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-04T23:10:05.353Z,1683241805.353 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-04T23:10:05.365Z,1683241805.365 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-04T23:10:05.376Z,1683241805.376 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-04T23:10:05.376Z,1683241805.376 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-04T23:10:05.648Z,1683241805.648 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-04T23:10:05.649Z,1683241805.649 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-04T23:10:05.839Z,1683241805.839 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-04T23:10:05.897Z,1683241805.897 [VerticalControl] Loaded
2023-05-04T23:10:05.897Z,1683241805.897 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-04T23:10:05.900Z,1683241805.900 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-04T23:10:05.940Z,1683241805.940 [HorizontalControl] Loaded
2023-05-04T23:10:05.940Z,1683241805.940 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-04T23:10:05.942Z,1683241805.942 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-04T23:10:05.945Z,1683241805.945 [SpeedControl] Loaded
2023-05-04T23:10:05.946Z,1683241805.946 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-04T23:10:05.948Z,1683241805.948 [LoopControl](DEBUG): Construct LoopControl.
2023-05-04T23:10:05.949Z,1683241805.949 [LoopControl] Loaded
2023-05-04T23:10:05.949Z,1683241805.949 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-04T23:10:05.949Z,1683241805.949 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-04T23:10:05.950Z,1683241805.950 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-04T23:10:06.173Z,1683241806.173 [BuoyancyServo] Loaded
2023-05-04T23:10:06.174Z,1683241806.174 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-04T23:10:06.193Z,1683241806.193 [ElevatorServo] Loaded
2023-05-04T23:10:06.194Z,1683241806.194 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-04T23:10:06.212Z,1683241806.212 [MassServo] Loaded
2023-05-04T23:10:06.213Z,1683241806.213 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-04T23:10:06.230Z,1683241806.230 [RudderServo] Loaded
2023-05-04T23:10:06.231Z,1683241806.231 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-04T23:10:06.245Z,1683241806.245 [ThrusterHE] Loaded
2023-05-04T23:10:06.245Z,1683241806.245 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-04T23:10:06.245Z,1683241806.245 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-04T23:10:06.246Z,1683241806.246 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-04T23:10:06.295Z,1683241806.295 [DepthRateCalculator] Loaded
2023-05-04T23:10:06.295Z,1683241806.295 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-04T23:10:06.300Z,1683241806.300 [PitchRateCalculator] Loaded
2023-05-04T23:10:06.300Z,1683241806.300 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-04T23:10:06.310Z,1683241806.310 [SpeedCalculator] Loaded
2023-05-04T23:10:06.310Z,1683241806.310 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-04T23:10:06.314Z,1683241806.314 [YawRateCalculator] Loaded
2023-05-04T23:10:06.314Z,1683241806.314 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-04T23:10:06.333Z,1683241806.333 [ElevatorOffsetCalculator] Loaded
2023-05-04T23:10:06.333Z,1683241806.333 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-04T23:10:06.333Z,1683241806.333 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-04T23:10:06.334Z,1683241806.334 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-04T23:10:06.444Z,1683241806.444 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-04T23:10:06.444Z,1683241806.444 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-04T23:10:06.458Z,1683241806.458 [NavChart] Loaded
2023-05-04T23:10:06.458Z,1683241806.458 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-04T23:10:06.463Z,1683241806.463 [UniversalFixResidualReporter] Loaded
2023-05-04T23:10:06.464Z,1683241806.464 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-04T23:10:06.464Z,1683241806.464 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-04T23:10:06.465Z,1683241806.465 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-04T23:10:06.888Z,1683241806.888 [AHRS_M2] Loaded
2023-05-04T23:10:06.889Z,1683241806.889 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-04T23:10:06.923Z,1683241806.923 [BackseatComponent] Loaded
2023-05-04T23:10:06.923Z,1683241806.923 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-04T23:10:06.925Z,1683241806.925 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-04T23:10:06.925Z,1683241806.925 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3814
2023-05-04T23:10:06.928Z,1683241806.928 [LcmUniversalReporter] Loaded
2023-05-04T23:10:06.928Z,1683241806.928 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-04T23:10:07.747Z,1683241807.747 [BPC1] Loaded
2023-05-04T23:10:07.747Z,1683241807.747 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-04T23:10:07.818Z,1683241807.818 [DataOverHttps] Loaded
2023-05-04T23:10:07.818Z,1683241807.818 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-04T23:10:07.819Z,1683241807.819 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-04T23:10:07.819Z,1683241807.819 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3815
2023-05-04T23:10:07.840Z,1683241807.840 [Depth_Keller] Loaded
2023-05-04T23:10:07.840Z,1683241807.840 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-04T23:10:07.901Z,1683241807.901 [NAL9602] Loaded
2023-05-04T23:10:07.902Z,1683241807.902 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-04T23:10:07.931Z,1683241807.931 [Onboard] Loaded
2023-05-04T23:10:07.931Z,1683241807.931 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-04T23:10:07.932Z,1683241807.932 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-04T23:10:07.933Z,1683241807.933 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3816
2023-05-04T23:10:07.944Z,1683241807.944 [Power24vConverter] Loaded
2023-05-04T23:10:07.945Z,1683241807.945 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-04T23:10:07.958Z,1683241807.958 [Radio_Surface] Loaded
2023-05-04T23:10:07.958Z,1683241807.958 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-04T23:10:07.959Z,1683241807.959 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-04T23:10:07.959Z,1683241807.959 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3817
2023-05-04T23:10:08.045Z,1683241808.045 [DATMMP] Loaded
2023-05-04T23:10:08.045Z,1683241808.045 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-04T23:10:08.046Z,1683241808.046 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-04T23:10:08.047Z,1683241808.047 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3818
2023-05-04T23:10:08.047Z,1683241808.047 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-04T23:10:08.048Z,1683241808.048 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-04T23:10:08.120Z,1683241808.120 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-04T23:10:08.120Z,1683241808.120 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-04T23:10:08.268Z,1683241808.268 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-04T23:10:08.268Z,1683241808.268 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-04T23:10:08.294Z,1683241808.294 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-04T23:10:08.295Z,1683241808.295 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-04T23:10:08.460Z,1683241808.460 [CTD_Seabird] Loaded
2023-05-04T23:10:08.460Z,1683241808.460 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-04T23:10:08.461Z,1683241808.461 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2023-05-04T23:10:08.462Z,1683241808.462 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3819
2023-05-04T23:10:08.494Z,1683241808.494 [ESPComponent] Loaded
2023-05-04T23:10:08.494Z,1683241808.494 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-04T23:10:08.514Z,1683241808.514 [PAR_Licor] Loaded
2023-05-04T23:10:08.514Z,1683241808.514 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-04T23:10:08.544Z,1683241808.544 [WetLabsBB2FL] Loaded
2023-05-04T23:10:08.545Z,1683241808.545 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-04T23:10:08.545Z,1683241808.545 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0
2023-05-04T23:10:08.546Z,1683241808.546 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3820
2023-05-04T23:10:08.547Z,1683241808.547 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-04T23:10:08.547Z,1683241808.547 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-04T23:10:08.847Z,1683241808.847 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-04T23:10:08.848Z,1683241808.848 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-04T23:10:08.984Z,1683241808.984 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-04T23:10:08.994Z,1683241808.994 [SBIT] Loaded
2023-05-04T23:10:08.994Z,1683241808.994 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-04T23:10:08.997Z,1683241808.997 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-04T23:10:09.010Z,1683241809.010 [IBIT] Loaded
2023-05-04T23:10:09.010Z,1683241809.010 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-04T23:10:09.016Z,1683241809.016 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-04T23:10:09.120Z,1683241809.120 [CBIT] Loaded
2023-05-04T23:10:09.120Z,1683241809.120 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-04T23:10:09.120Z,1683241809.120 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-04T23:10:09.126Z,1683241809.126 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-04T23:10:09.129Z,1683241809.129 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-04T23:10:09.140Z,1683241809.140 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-04T23:10:09.141Z,1683241809.141 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2023-05-04T23:10:09.141Z,1683241809.141 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3821
2023-05-04T23:10:09.146Z,1683241809.146 [Supervisor](INFO): Main Thread ID is 3727
2023-05-04T23:10:09.146Z,1683241809.146 [Supervisor](DEBUG): Running supervisor.
2023-05-04T23:10:09.147Z,1683241809.147 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3822
2023-05-04T23:10:09.147Z,1683241809.147 [CommandExec](INFO): Initializing the command executive.
2023-05-04T23:10:09.149Z,1683241809.149 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3823
2023-05-04T23:10:09.151Z,1683241809.151 [controlThread ThreadHandler](INFO): Handler Thread ID is 3824
2023-05-04T23:10:09.151Z,1683241809.151 [controlThread](DEBUG): Initializing ControlThread
2023-05-04T23:10:09.152Z,1683241809.152 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-04T23:10:09.154Z,1683241809.154 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-04T23:10:09.154Z,1683241809.154 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-04T23:10:09.155Z,1683241809.155 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-04T23:10:09.156Z,1683241809.156 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-04T23:10:09.157Z,1683241809.157 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-04T23:10:09.157Z,1683241809.157 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-04T23:10:09.158Z,1683241809.158 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-04T23:10:09.158Z,1683241809.158 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-04T23:10:09.159Z,1683241809.159 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-04T23:10:09.159Z,1683241809.159 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-04T23:10:09.168Z,1683241809.168 [SBIT](INFO): Initialize SBIT Component.
2023-05-04T23:10:09.169Z,1683241809.169 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-04T23:10:09.169Z,1683241809.169 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-04T23:10:09.169Z,1683241809.169 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-04T23:10:09.170Z,1683241809.170 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-05-04T23:10:09.171Z,1683241809.171 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-04T23:10:09.172Z,1683241809.172 [IBIT](INFO): Initialize IBIT Component.
2023-05-04T23:10:09.173Z,1683241809.173 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-04T23:10:09.174Z,1683241809.174 [logger ThreadHandler](INFO): Handler Thread ID is 3825
2023-05-04T23:10:09.184Z,1683241809.184 [CBIT](DEBUG): Initialized mux pins.
2023-05-04T23:10:09.184Z,1683241809.184 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-04T23:10:09.193Z,1683241809.193 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3826
2023-05-04T23:10:09.204Z,1683241809.204 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3827
2023-05-04T23:10:09.205Z,1683241809.205 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-04T23:10:09.208Z,1683241809.208 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-04T23:10:09.209Z,1683241809.209 [CBIT](DEBUG): Initializing heartbeat.
2023-05-04T23:10:09.217Z,1683241809.217 [Onboard ThreadHandler](INFO): Handler Thread ID is 3828
2023-05-04T23:10:09.234Z,1683241809.234 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3829
2023-05-04T23:10:09.252Z,1683241809.252 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3830
2023-05-04T23:10:09.253Z,1683241809.253 [DATMMP](INFO): Start
2023-05-04T23:10:09.253Z,1683241809.253 [DATMMP](INFO): Powering up
2023-05-04T23:10:09.253Z,1683241809.253 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-04T23:10:09.257Z,1683241809.257 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3832
2023-05-04T23:10:09.258Z,1683241809.258 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-04T23:10:09.261Z,1683241809.261 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3834
2023-05-04T23:10:09.264Z,1683241809.264 [WetLabsBB2FL](INFO): Powering up
2023-05-04T23:10:09.266Z,1683241809.266 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3836
2023-05-04T23:10:09.269Z,1683241809.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-04T23:10:09.269Z,1683241809.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-04T23:10:09.269Z,1683241809.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-04T23:10:09.269Z,1683241809.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-04T23:10:09.269Z,1683241809.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-04T23:10:09.269Z,1683241809.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-04T23:10:09.270Z,1683241809.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-04T23:10:09.270Z,1683241809.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-04T23:10:09.280Z,1683241809.280 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-04T23:10:09.280Z,1683241809.280 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-04T23:10:09.316Z,1683241809.316 [CBIT](DEBUG): Backplane powered.
2023-05-04T23:10:09.317Z,1683241809.317 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-04T23:10:09.330Z,1683241809.330 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-04T23:10:09.362Z,1683241809.362 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-05-04T23:10:09.368Z,1683241809.368 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-04T23:10:09.442Z,1683241809.442 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-04T23:10:09.443Z,1683241809.443 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-04T23:10:09.446Z,1683241809.446 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-04T23:10:09.481Z,1683241809.481 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-04T23:10:09.484Z,1683241809.484 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-04T23:10:09.507Z,1683241809.507 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-04T23:10:09.510Z,1683241809.510 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-05-04T23:10:09.524Z,1683241809.524 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-04T23:10:09.536Z,1683241809.536 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-04T23:10:09.602Z,1683241809.602 [Power24vConverter](INFO): Powering up.
2023-05-04T23:10:09.640Z,1683241809.640 [Radio_Surface](INFO): Powering up
2023-05-04T23:10:09.666Z,1683241809.666 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:10:09.673Z,1683241809.673 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-04T23:10:09.674Z,1683241809.674 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:10:09.681Z,1683241809.681 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-04T23:10:09.682Z,1683241809.682 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:10:09.688Z,1683241809.688 [MassServo](DEBUG): Initializing MassServo.
2023-05-04T23:10:09.689Z,1683241809.689 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:10:09.696Z,1683241809.696 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-04T23:10:09.697Z,1683241809.697 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-04T23:10:09.704Z,1683241809.704 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-04T23:10:10.672Z,1683241810.672 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-05-04T23:10:10.672Z,1683241810.672 [RudderServo](FAULT): Rudder failed to initialize
2023-05-04T23:10:10.672Z,1683241810.672 [RudderServo] Communications Fault, FailCount= 1
2023-05-04T23:10:10.672Z,1683241810.672 [RudderServo](ERROR): Communications Fault
2023-05-04T23:10:10.676Z,1683241810.676 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-05-04T23:10:10.909Z,1683241810.909 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-04T23:10:10.909Z,1683241810.909 [RudderServo](INFO): Powering down
2023-05-04T23:10:11.372Z,1683241811.372 [WetLabsBB2FL](INFO): Powering down
2023-05-04T23:10:11.577Z,1683241811.577 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-04T23:10:11.697Z,1683241811.697 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-04T23:10:11.701Z,1683241811.701 [CBIT](INFO): Clearing failed state for component RudderServo
2023-05-04T23:10:11.701Z,1683241811.701 [RudderServo] No Fault, FailCount= 1
2023-05-04T23:10:15.561Z,1683241815.561 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-04T23:10:16.288Z,1683241816.288 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-04T23:10:22.952Z,1683241822.952 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005823
2023-05-04T23:10:23.112Z,1683241823.112 [DATMMP](INFO): DAT read: 

2023-05-04T23:10:23.113Z,1683241823.113 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-04T23:10:24.877Z,1683241824.877 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-04T23:10:24.878Z,1683241824.878 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-04T23:10:24.878Z,1683241824.878 [DATMMP](INFO): DAT read: Apr  4 2000 21:08:19 

2023-05-04T23:10:26.137Z,1683241826.137 [DATMMP](INFO): DAT read: Features enabled [Bearing]

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

2023-05-04T23:10:26.138Z,1683241826.138 [DATMMP](INFO): commRate: 800
2023-05-04T23:10:28.204Z,1683241828.204 [DATMMP](INFO): entering command mode
2023-05-04T23:10:28.404Z,1683241828.404 [DATMMP](INFO): DAT read: 

2023-05-04T23:10:28.405Z,1683241828.405 [DATMMP](INFO): DAT read: user:1>

2023-05-04T23:10:28.405Z,1683241828.405 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-04T23:10:28.405Z,1683241828.405 [DATMMP](INFO): entering MMP mode
2023-05-04T23:10:28.657Z,1683241828.657 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:10:28.658Z,1683241828.658 [DATMMP](INFO): status rx: 	 x1
2023-05-04T23:10:28.659Z,1683241828.659 [DATMMP](INFO): MMP status: 1: started
2023-05-04T23:10:28.659Z,1683241828.659 [DATMMP](INFO): Received message type: status
2023-05-04T23:10:28.659Z,1683241828.659 [DATMMP](INFO): Handled
2023-05-04T23:10:28.909Z,1683241828.909 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-04T23:10:28.910Z,1683241828.910 [DATMMP](INFO): Setting local address: 5
2023-05-04T23:10:28.911Z,1683241828.911 [DATMMP](INFO): Setting time to: 23:10:28 And date to:5/4/2023
2023-05-04T23:10:29.160Z,1683241829.160 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:10:29.161Z,1683241829.161 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-04T23:10:29.162Z,1683241829.162 [DATMMP](INFO): Received message type: mdmpower
2023-05-04T23:10:29.162Z,1683241829.162 [DATMMP](INFO): Handled
2023-05-04T23:10:29.413Z,1683241829.413 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-04T23:10:29.413Z,1683241829.413 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-04T23:10:29.413Z,1683241829.413 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-04T23:10:29.414Z,1683241829.414 [DATMMP](INFO): Match for : sreg_txpower
2023-05-04T23:10:29.414Z,1683241829.414 [DATMMP](INFO): ACK
2023-05-04T23:10:29.665Z,1683241829.665 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:10:29.665Z,1683241829.665 [DATMMP](INFO): mdmlocal rx: 	 x5	 x0	 x0	 x0
2023-05-04T23:10:29.666Z,1683241829.666 [DATMMP](INFO): Received message type: mdmlocal
2023-05-04T23:10:29.666Z,1683241829.666 [DATMMP](INFO): Handled
2023-05-04T23:10:29.916Z,1683241829.916 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-04T23:10:29.917Z,1683241829.917 [DATMMP](INFO): sreg_locaddr rx: 	 x5
2023-05-04T23:10:29.917Z,1683241829.917 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-04T23:10:29.917Z,1683241829.917 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-04T23:10:29.917Z,1683241829.917 [DATMMP](INFO): ACK
2023-05-04T23:10:30.168Z,1683241830.168 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-04T23:10:30.170Z,1683241830.170 [DATMMP](INFO): timedate rx: 	 x0	 x17	 xA	 x1C	 x4	 x4	 x7B	 x0
2023-05-04T23:10:30.170Z,1683241830.170 [DATMMP](INFO): Received message type: timedate
2023-05-04T23:10:30.170Z,1683241830.170 [DATMMP](INFO): Match for : timedate
2023-05-04T23:10:30.170Z,1683241830.170 [DATMMP](INFO): ACK
2023-05-04T23:10:30.420Z,1683241830.420 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:10:30.421Z,1683241830.421 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-04T23:10:30.421Z,1683241830.421 [DATMMP](INFO): Received message type: sys_verb
2023-05-04T23:10:30.421Z,1683241830.421 [DATMMP](INFO): Handled
2023-05-04T23:10:30.672Z,1683241830.672 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-04T23:10:30.673Z,1683241830.673 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-04T23:10:30.673Z,1683241830.673 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-04T23:10:30.673Z,1683241830.673 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-04T23:10:30.674Z,1683241830.674 [DATMMP](INFO): ACK
2023-05-04T23:10:30.925Z,1683241830.925 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-04T23:10:30.925Z,1683241830.925 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-04T23:10:30.926Z,1683241830.926 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-04T23:10:30.926Z,1683241830.926 [DATMMP](INFO): Received message type: version
2023-05-04T23:10:30.926Z,1683241830.926 [DATMMP](INFO): Match for : version
2023-05-04T23:10:30.926Z,1683241830.926 [DATMMP](INFO): ACK
2023-05-04T23:10:30.926Z,1683241830.926 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-04T23:10:38.261Z,1683241838.261 [NAL9602](INFO): Powering up NAL9602
2023-05-04T23:10:44.893Z,1683241844.893 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-04T23:10:44.901Z,1683241844.901 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-04T23:10:44.901Z,1683241844.901 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-04T23:10:44.940Z,1683241844.940 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-04T23:10:45.202Z,1683241845.202 [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-04T23:10:45.540Z,1683241845.540 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:10:45.541Z,1683241845.541 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-04T23:10:45.542Z,1683241845.542 [DATMMP](INFO): Received message type: timesync
2023-05-04T23:10:45.542Z,1683241845.542 [DATMMP](INFO): Handled
2023-05-04T23:10:49.173Z,1683241849.173 [NAL9602](INFO): NAL9602 initialized
2023-05-04T23:10:55.385Z,1683241855.385 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:10:55.386Z,1683241855.386 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-04T23:10:55.386Z,1683241855.386 [DATMMP](INFO): Received message type: timesync
2023-05-04T23:10:55.386Z,1683241855.386 [DATMMP](INFO): Handled
2023-05-04T23:10:57.753Z,1683241857.753 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-04T23:10:57.757Z,1683241857.757 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-04T23:10:58.106Z,1683241858.106 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-04T23:11:10.887Z,1683241870.887 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683241855 second
2023-05-04T23:11:20.280Z,1683241880.280 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T23:11:30.886Z,1683241890.886 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-04T23:11:30.895Z,1683241890.895 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-04T23:11:38.251Z,1683241898.251 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2023-05-04T23:11:39.201Z,1683241899.201 [CommandExec](IMPORTANT): got command show stack
2023-05-04T23:11:39.201Z,1683241899.201 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-04T23:11:39.201Z,1683241899.201 [MissionManager](IMPORTANT): Mission loaded, but not running.
2023-05-04T23:11:41.765Z,1683241901.765 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006631
CHAN A1 (24V): -0.008811
CHAN A2 (12V): -0.005178
CHAN A3 (5V): -0.002837
CHAN B0 (3.3V): -0.000848
CHAN B1 (3.15aV): -0.001075
CHAN B2 (3.15bV): -0.001203
CHAN B3 (GND): -0.000530
OPEN: 0.004437
Full Scale: +/- 1 mA
2023-05-04T23:11:52.220Z,1683241912.220 [CommandExec](IMPORTANT): got command show stack
2023-05-04T23:11:52.220Z,1683241912.220 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-04T23:11:52.220Z,1683241912.220 [MissionManager](IMPORTANT): Mission loaded, but not running.
2023-05-04T23:12:07.726Z,1683241927.726 [CommandExec](IMPORTANT): got command show stack
2023-05-04T23:12:07.726Z,1683241927.726 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-04T23:12:07.727Z,1683241927.727 [MissionManager](IMPORTANT): Mission loaded, but not running.
2023-05-04T23:12:24.584Z,1683241944.584 [SBIT](IMPORTANT): SBIT PASSED
2023-05-04T23:12:24.584Z,1683241944.584 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-04T23:12:24.585Z,1683241944.585 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-04T23:12:24.585Z,1683241944.585 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-04T23:12:24.585Z,1683241944.585 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-04T23:12:24.585Z,1683241944.585 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-04T23:12:24.585Z,1683241944.585 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-04T23:12:24.585Z,1683241944.585 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-04T23:12:24.586Z,1683241944.586 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-04T23:12:24.586Z,1683241944.586 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-04T23:12:24.586Z,1683241944.586 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-04T23:12:24.586Z,1683241944.586 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-04T23:12:24.586Z,1683241944.586 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-04T23:12:24.586Z,1683241944.586 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-04T23:12:25.042Z,1683241945.042 [MissionManager](IMPORTANT): Started mission Startup
2023-05-04T23:12:25.043Z,1683241945.043 [Startup] Running Loop=1
2023-05-04T23:12:25.043Z,1683241945.043 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-04T23:12:25.043Z,1683241945.043 [Startup:A.GoToSurface] Running Loop=1
2023-05-04T23:12:25.043Z,1683241945.043 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T23:12:25.043Z,1683241945.043 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T23:12:25.044Z,1683241945.044 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T23:12:25.044Z,1683241945.044 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T23:12:25.045Z,1683241945.045 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T23:12:25.045Z,1683241945.045 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T23:12:25.047Z,1683241945.047 [Startup:StartupSatComms] Running Loop=1
2023-05-04T23:12:25.047Z,1683241945.047 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-04T23:12:25.047Z,1683241945.047 [Startup:StartupSatComms:A] Running Loop=1
2023-05-04T23:12:25.366Z,1683241945.366 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-04T23:12:25.366Z,1683241945.366 [Startup:StartupSatComms:A] Stopped
2023-05-04T23:12:25.366Z,1683241945.366 [Startup:StartupSatComms:B] Running Loop=1
2023-05-04T23:12:25.779Z,1683241945.779 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-04T23:12:34.372Z,1683241954.372 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230504T202841/Courier0010.lzma
2023-05-04T23:12:35.374Z,1683241955.374 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Courier0010.lzma.bak
2023-05-04T23:12:35.374Z,1683241955.374 [DataOverHttps](INFO): SBD MOMSN=18281866
2023-05-04T23:12:38.641Z,1683241958.641 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683241945 second
2023-05-04T23:12:38.714Z,1683241958.714 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:12:51.634Z,1683241971.634 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:12:54.424Z,1683241974.424 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230504T230959/Courier0000.lzma
2023-05-04T23:12:55.426Z,1683241975.426 [DataOverHttps](INFO): Moved sent file to Logs/20230504T230959/Courier0000.lzma.bak
2023-05-04T23:12:55.426Z,1683241975.426 [DataOverHttps](INFO): SBD MOMSN=18281871
2023-05-04T23:12:56.895Z,1683241976.895 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2023-05-04T23:12:56.908Z,1683241976.908 [Startup:StartupSatComms:B] Stopped
2023-05-04T23:12:56.912Z,1683241976.912 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-04T23:12:56.912Z,1683241976.912 [Startup:StartupSatComms] Stopped
2023-05-04T23:12:56.912Z,1683241976.912 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-04T23:12:56.913Z,1683241976.913 [Startup](INFO): Completed Startup
2023-05-04T23:12:56.913Z,1683241976.913 [MissionManager](INFO): Startup is completed.
2023-05-04T23:12:56.913Z,1683241976.913 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-04T23:12:56.913Z,1683241976.913 [Startup] Stopped
2023-05-04T23:12:56.914Z,1683241976.914 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-04T23:12:56.914Z,1683241976.914 [Startup:A.GoToSurface] Stopped
2023-05-04T23:12:56.914Z,1683241976.914 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:12:57.291Z,1683241977.291 [MissionManager](IMPORTANT): Started mission Default
2023-05-04T23:12:57.291Z,1683241977.291 [Default] Running Loop=1
2023-05-04T23:12:57.292Z,1683241977.292 [Default](DEBUG): Aggregate::initialize Default
2023-05-04T23:12:57.292Z,1683241977.292 [Default:B.GoToSurface] Running Loop=1
2023-05-04T23:12:57.292Z,1683241977.292 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T23:12:57.292Z,1683241977.292 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T23:12:57.292Z,1683241977.292 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T23:12:57.293Z,1683241977.293 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T23:12:57.293Z,1683241977.293 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T23:12:57.293Z,1683241977.293 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T23:12:57.293Z,1683241977.293 [Default:A.Wait] Running Loop=1
2023-05-04T23:12:57.294Z,1683241977.294 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:13:04.970Z,1683241984.970 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:13:10.653Z,1683241990.653 [Default:A.Wait](INFO): Done Waiting.
2023-05-04T23:13:10.653Z,1683241990.653 [Default:A.Wait] Stopped
2023-05-04T23:13:10.653Z,1683241990.653 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:13:11.079Z,1683241991.079 [Default:CheckIn] Running Loop=1
2023-05-04T23:13:11.079Z,1683241991.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:13:11.079Z,1683241991.079 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:13:11.369Z,1683241991.369 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-04T23:13:11.464Z,1683241991.464 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-04T23:13:13.736Z,1683241993.736 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T23:13:13.736Z,1683241993.736 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T23:13:13.795Z,1683241993.795 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 6.000000 count
2023-05-04T23:13:13.798Z,1683241993.798 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m
2023-05-04T23:13:13.800Z,1683241993.800 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s
2023-05-04T23:13:13.803Z,1683241993.803 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count
2023-05-04T23:13:13.806Z,1683241993.806 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count
2023-05-04T23:13:13.809Z,1683241993.809 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count
2023-05-04T23:13:13.811Z,1683241993.811 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min
2023-05-04T23:13:13.862Z,1683241993.862 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 6.000000 enum
2023-05-04T23:13:13.873Z,1683241993.873 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum
2023-05-04T23:13:13.892Z,1683241993.892 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum
2023-05-04T23:13:14.036Z,1683241994.036 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Construct Wait.
2023-05-04T23:13:14.060Z,1683241994.060 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Sensor="Tethys/Sensor"
    xmlns:Units="Tethys/Units"
    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
    xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                        Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                        Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
    Id="senddata_direct_and_track_test">


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

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

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

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

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

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

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


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



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

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

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

  </Aggregate>

</Mission>


2023-05-04T23:13:14.063Z,1683241994.063 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T23:13:14.274Z,1683241994.274 [Default] Stopped
2023-05-04T23:13:14.275Z,1683241994.275 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T23:13:14.275Z,1683241994.275 [Default:B.GoToSurface] Stopped
2023-05-04T23:13:14.275Z,1683241994.275 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:13:14.275Z,1683241994.275 [Default:CheckIn] Stopped
2023-05-04T23:13:14.275Z,1683241994.275 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:13:14.275Z,1683241994.275 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:13:14.275Z,1683241994.275 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test
2023-05-04T23:13:14.275Z,1683241994.275 [senddata_direct_and_track_test] Running Loop=1
2023-05-04T23:13:14.275Z,1683241994.275 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test
2023-05-04T23:13:14.275Z,1683241994.275 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-04T23:13:14.276Z,1683241994.276 [senddata_direct_and_track_test:A.](INFO): Initializing Tracking.
2023-05-04T23:13:14.276Z,1683241994.276 [senddata_direct_and_track_test:DirectData] Running Loop=1
2023-05-04T23:13:14.276Z,1683241994.276 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-04T23:13:14.276Z,1683241994.276 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-04T23:13:14.276Z,1683241994.276 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:13:14.277Z,1683241994.277 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-04T23:13:14.277Z,1683241994.277 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:14.278Z,1683241994.278 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:14.692Z,1683241994.692 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:14.693Z,1683241994.693 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:15.085Z,1683241995.085 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:15.086Z,1683241995.086 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:15.490Z,1683241995.490 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:15.491Z,1683241995.491 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:15.890Z,1683241995.890 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:15.891Z,1683241995.891 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:16.279Z,1683241996.279 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:16.279Z,1683241996.279 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:16.684Z,1683241996.684 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:16.685Z,1683241996.685 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:17.103Z,1683241997.103 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:17.103Z,1683241997.103 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:17.491Z,1683241997.491 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:17.492Z,1683241997.492 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:18.044Z,1683241998.044 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:18.045Z,1683241998.045 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:18.300Z,1683241998.300 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:13:18.319Z,1683241998.319 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:18.320Z,1683241998.320 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:18.733Z,1683241998.733 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:18.733Z,1683241998.733 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:19.153Z,1683241999.153 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:19.154Z,1683241999.154 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:19.547Z,1683241999.547 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:19.547Z,1683241999.547 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:19.937Z,1683241999.937 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:19.938Z,1683241999.938 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:20.336Z,1683242000.336 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:20.337Z,1683242000.337 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:20.724Z,1683242000.724 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:20.725Z,1683242000.725 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:21.127Z,1683242001.127 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:21.128Z,1683242001.128 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:21.538Z,1683242001.538 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:21.538Z,1683242001.538 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:21.950Z,1683242001.950 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:21.951Z,1683242001.951 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:22.338Z,1683242002.338 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:22.339Z,1683242002.339 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:22.747Z,1683242002.747 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:22.748Z,1683242002.748 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:23.155Z,1683242003.155 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:23.156Z,1683242003.156 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:23.563Z,1683242003.563 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:23.563Z,1683242003.563 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:23.959Z,1683242003.959 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:23.960Z,1683242003.960 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:24.413Z,1683242004.413 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:24.414Z,1683242004.414 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:24.784Z,1683242004.784 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:24.785Z,1683242004.785 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:25.185Z,1683242005.185 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:25.185Z,1683242005.185 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:25.599Z,1683242005.599 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:25.599Z,1683242005.599 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:25.975Z,1683242005.975 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:25.976Z,1683242005.976 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:26.386Z,1683242006.386 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:26.386Z,1683242006.386 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:26.788Z,1683242006.788 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:26.789Z,1683242006.789 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:27.196Z,1683242007.196 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:27.197Z,1683242007.197 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:27.606Z,1683242007.606 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:27.607Z,1683242007.607 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:27.999Z,1683242007.999 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:27.000Z,1683242008.000 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:28.399Z,1683242008.399 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:28.400Z,1683242008.400 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:28.805Z,1683242008.805 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:28.806Z,1683242008.806 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:29.223Z,1683242009.223 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:29.223Z,1683242009.223 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:29.639Z,1683242009.639 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:29.640Z,1683242009.640 [senddata_direct_and_track_test:A.](INFO): update period (15.000000 s) has elapsed, querying...
2023-05-04T23:13:29.640Z,1683242009.640 [senddata_direct_and_track_test:A.](INFO): *** querying DAT ***
2023-05-04T23:13:29.809Z,1683242009.809 [DATMMP](INFO): ****** received valid address query ******
2023-05-04T23:13:29.809Z,1683242009.809 [DATMMP](INFO): ****** received valid ping request ******
2023-05-04T23:13:29.810Z,1683242009.810 [DATMMP](INFO): Querying Benthos address 6
2023-05-04T23:13:30.033Z,1683242010.033 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:30.034Z,1683242010.034 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:30.061Z,1683242010.061 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T23:13:30.062Z,1683242010.062 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x0	 x2
2023-05-04T23:13:30.062Z,1683242010.062 [DATMMP](INFO): Progress on cmd
2023-05-04T23:13:30.062Z,1683242010.062 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:13:30.062Z,1683242010.062 [DATMMP](INFO): Handled
2023-05-04T23:13:30.062Z,1683242010.062 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:30.313Z,1683242010.313 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:30.313Z,1683242010.313 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 xD	 x1C	 x196
2023-05-04T23:13:30.314Z,1683242010.314 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:13:30.314Z,1683242010.314 [DATMMP](INFO): Handled
2023-05-04T23:13:30.314Z,1683242010.314 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:30.435Z,1683242010.435 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:30.436Z,1683242010.436 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:30.565Z,1683242010.565 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:30.566Z,1683242010.566 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:13:30.566Z,1683242010.566 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:13:30.566Z,1683242010.566 [DATMMP](INFO): Handled
2023-05-04T23:13:30.566Z,1683242010.566 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:30.816Z,1683242010.816 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:30.850Z,1683242010.850 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:30.851Z,1683242010.851 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:31.068Z,1683242011.068 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:31.320Z,1683242011.320 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:31.369Z,1683242011.369 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:31.369Z,1683242011.369 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:31.572Z,1683242011.572 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:31.634Z,1683242011.634 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:13:31.647Z,1683242011.647 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:31.648Z,1683242011.648 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:31.824Z,1683242011.824 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:32.074Z,1683242012.074 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:32.075Z,1683242012.075 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:32.077Z,1683242012.077 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:32.328Z,1683242012.328 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:32.480Z,1683242012.480 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:32.481Z,1683242012.481 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:32.580Z,1683242012.580 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:32.833Z,1683242012.833 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:32.833Z,1683242012.833 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:13:32.833Z,1683242012.833 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:13:32.833Z,1683242012.833 [DATMMP](INFO): Handled
2023-05-04T23:13:32.834Z,1683242012.834 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:32.847Z,1683242012.847 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:32.848Z,1683242012.848 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:33.085Z,1683242013.085 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:33.086Z,1683242013.086 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:13:33.086Z,1683242013.086 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:13:33.086Z,1683242013.086 [DATMMP](INFO): Handled
2023-05-04T23:13:33.086Z,1683242013.086 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:33.266Z,1683242013.266 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:33.268Z,1683242013.268 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T23:13:33.337Z,1683242013.337 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:33.338Z,1683242013.338 [DATMMP](INFO): directional_debug rx: 	 x6D5	 x83	 x2E	 x72	 x5450	 x4DA1	 x49F2	 x69C3
2023-05-04T23:13:33.339Z,1683242013.339 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:13:33.339Z,1683242013.339 [DATMMP](INFO): Handled
2023-05-04T23:13:33.339Z,1683242013.339 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:33.589Z,1683242013.589 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:33.590Z,1683242013.590 [DATMMP](INFO): directional rx: 	 x6CD	 x66	 xD17	 xFF7D	 x4E2	 xFF78	 x6E6E	 x5D8	 x3	 xFFEF	 x27B5966	 x-43569E0
2023-05-04T23:13:33.592Z,1683242013.592 [DATMMP](INFO): Received message type: directional
2023-05-04T23:13:33.592Z,1683242013.592 [DATMMP](INFO): Handled
2023-05-04T23:13:33.592Z,1683242013.592 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:33.691Z,1683242013.691 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:33.692Z,1683242013.692 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:33.693Z,1683242013.693 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:33.704Z,1683242013.704 [Reporter](INFO): DATMMP.acoustic_contact_range 28270.000000 m
2023-05-04T23:13:33.841Z,1683242013.841 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T23:13:33.841Z,1683242013.841 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x1	 x6
2023-05-04T23:13:33.842Z,1683242013.842 [DATMMP](INFO): Progress on cmd
2023-05-04T23:13:33.842Z,1683242013.842 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:13:33.842Z,1683242013.842 [DATMMP](INFO): Handled
2023-05-04T23:13:33.842Z,1683242013.842 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:34.068Z,1683242014.068 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:34.069Z,1683242014.069 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:34.070Z,1683242014.070 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:34.093Z,1683242014.093 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T23:13:34.093Z,1683242014.093 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x2	 x3
2023-05-04T23:13:34.094Z,1683242014.094 [DATMMP](ERROR): 3:COMPLETE
2023-05-04T23:13:34.094Z,1683242014.094 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:13:34.094Z,1683242014.094 [DATMMP](INFO): Handled
2023-05-04T23:13:34.094Z,1683242014.094 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:34.345Z,1683242014.345 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:13:34.346Z,1683242014.346 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:13:34.346Z,1683242014.346 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:13:34.346Z,1683242014.346 [DATMMP](INFO): Handled
2023-05-04T23:13:34.346Z,1683242014.346 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:34.480Z,1683242014.480 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:34.481Z,1683242014.481 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:34.482Z,1683242014.482 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:34.596Z,1683242014.596 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:34.850Z,1683242014.850 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:34.901Z,1683242014.901 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:34.901Z,1683242014.901 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:34.902Z,1683242014.902 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:35.101Z,1683242015.101 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:35.286Z,1683242015.286 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:35.287Z,1683242015.287 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:35.288Z,1683242015.288 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:35.352Z,1683242015.352 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:35.604Z,1683242015.604 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:35.707Z,1683242015.707 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:35.712Z,1683242015.712 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:35.713Z,1683242015.713 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:35.856Z,1683242015.856 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:36.108Z,1683242016.108 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:36.116Z,1683242016.116 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:36.117Z,1683242016.117 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:36.118Z,1683242016.118 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:36.361Z,1683242016.361 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:36.505Z,1683242016.505 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:36.506Z,1683242016.506 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:36.507Z,1683242016.507 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:36.612Z,1683242016.612 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:36.865Z,1683242016.865 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:36.913Z,1683242016.913 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:36.914Z,1683242016.914 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:36.915Z,1683242016.915 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:37.116Z,1683242017.116 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:37.320Z,1683242017.320 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:37.321Z,1683242017.321 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:37.322Z,1683242017.322 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:37.368Z,1683242017.368 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:37.620Z,1683242017.620 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:37.712Z,1683242017.712 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:37.713Z,1683242017.713 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:37.714Z,1683242017.714 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:37.873Z,1683242017.873 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:38.121Z,1683242018.121 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:38.122Z,1683242018.122 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:38.123Z,1683242018.123 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:38.124Z,1683242018.124 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:38.376Z,1683242018.376 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:38.529Z,1683242018.529 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:38.530Z,1683242018.530 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:38.531Z,1683242018.531 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:38.628Z,1683242018.628 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:38.880Z,1683242018.880 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:38.906Z,1683242018.906 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:38.907Z,1683242018.907 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:38.908Z,1683242018.908 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:39.132Z,1683242019.132 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:39.321Z,1683242019.321 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:39.322Z,1683242019.322 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:39.323Z,1683242019.323 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:39.385Z,1683242019.385 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:39.637Z,1683242019.637 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T23:13:39.720Z,1683242019.720 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:39.721Z,1683242019.721 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:39.721Z,1683242019.721 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:40.157Z,1683242020.157 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:40.158Z,1683242020.158 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:40.158Z,1683242020.158 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:40.224Z,1683242020.224 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20230504T202841/Express0011.lzma
2023-05-04T23:13:40.549Z,1683242020.549 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:40.550Z,1683242020.550 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:40.551Z,1683242020.551 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:40.953Z,1683242020.953 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:40.954Z,1683242020.954 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:40.955Z,1683242020.955 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:41.226Z,1683242021.226 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Express0011.lzma.bak
2023-05-04T23:13:41.226Z,1683242021.226 [DataOverHttps](INFO): SBD MOMSN=18281914
2023-05-04T23:13:41.356Z,1683242021.356 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:41.357Z,1683242021.357 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:41.358Z,1683242021.358 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:41.739Z,1683242021.739 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:41.741Z,1683242021.741 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:41.741Z,1683242021.741 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:42.140Z,1683242022.140 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:42.141Z,1683242022.141 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:42.142Z,1683242022.142 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:42.555Z,1683242022.555 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:42.557Z,1683242022.557 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:42.557Z,1683242022.557 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:42.951Z,1683242022.951 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:42.953Z,1683242022.953 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:42.953Z,1683242022.953 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:43.355Z,1683242023.355 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:43.357Z,1683242023.357 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:43.357Z,1683242023.357 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:43.763Z,1683242023.763 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:43.764Z,1683242023.764 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:43.765Z,1683242023.765 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:44.167Z,1683242024.167 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:44.168Z,1683242024.168 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:44.173Z,1683242024.173 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:44.635Z,1683242024.635 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:44.636Z,1683242024.636 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:44.637Z,1683242024.637 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:45.077Z,1683242025.077 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:45.078Z,1683242025.078 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:45.078Z,1683242025.078 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:45.374Z,1683242025.374 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:13:45.428Z,1683242025.428 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:45.429Z,1683242025.429 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:45.429Z,1683242025.429 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:45.814Z,1683242025.814 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:45.815Z,1683242025.815 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:45.816Z,1683242025.816 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:46.222Z,1683242026.222 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:46.223Z,1683242026.223 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:46.226Z,1683242026.226 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:46.583Z,1683242026.583 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:46.583Z,1683242026.583 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:46.585Z,1683242026.585 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:46.996Z,1683242026.996 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:46.997Z,1683242026.997 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:46.998Z,1683242026.998 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:47.402Z,1683242027.402 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:47.403Z,1683242027.403 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:47.403Z,1683242027.403 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:47.795Z,1683242027.795 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:47.797Z,1683242027.797 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:47.797Z,1683242027.797 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:48.203Z,1683242028.203 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:48.204Z,1683242028.204 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:48.205Z,1683242028.205 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:48.611Z,1683242028.611 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:48.612Z,1683242028.612 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:48.613Z,1683242028.613 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:49.018Z,1683242029.018 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:49.019Z,1683242029.019 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:49.020Z,1683242029.020 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:49.445Z,1683242029.445 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:49.446Z,1683242029.446 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:49.447Z,1683242029.447 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:49.819Z,1683242029.819 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:49.820Z,1683242029.820 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:49.821Z,1683242029.821 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:50.237Z,1683242030.237 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:50.238Z,1683242030.238 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:50.239Z,1683242030.239 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:50.638Z,1683242030.638 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:50.639Z,1683242030.639 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:50.640Z,1683242030.640 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:51.056Z,1683242031.056 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:51.057Z,1683242031.057 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:51.058Z,1683242031.058 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:51.467Z,1683242031.467 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:51.468Z,1683242031.468 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:51.469Z,1683242031.469 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:51.840Z,1683242031.840 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:51.841Z,1683242031.841 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:51.842Z,1683242031.842 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:52.252Z,1683242032.252 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:52.253Z,1683242032.253 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:52.254Z,1683242032.254 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:52.643Z,1683242032.643 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:52.644Z,1683242032.644 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:52.645Z,1683242032.645 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:53.052Z,1683242033.052 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:53.053Z,1683242033.053 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:53.054Z,1683242033.054 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:53.459Z,1683242033.459 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:53.460Z,1683242033.460 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:53.461Z,1683242033.461 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:53.866Z,1683242033.866 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:53.867Z,1683242033.867 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:53.868Z,1683242033.868 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:54.264Z,1683242034.264 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:54.265Z,1683242034.265 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:54.266Z,1683242034.266 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:54.664Z,1683242034.664 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:54.665Z,1683242034.665 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:54.666Z,1683242034.666 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:55.093Z,1683242035.093 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:55.094Z,1683242035.094 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:55.095Z,1683242035.095 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:55.487Z,1683242035.487 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:55.488Z,1683242035.488 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:55.489Z,1683242035.489 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:55.879Z,1683242035.879 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:55.880Z,1683242035.880 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:55.881Z,1683242035.881 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:56.412Z,1683242036.412 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:56.413Z,1683242036.413 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:56.414Z,1683242036.414 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:56.690Z,1683242036.690 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:56.691Z,1683242036.691 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:56.693Z,1683242036.693 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:57.092Z,1683242037.092 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:57.093Z,1683242037.093 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:57.094Z,1683242037.094 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:57.503Z,1683242037.503 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:57.504Z,1683242037.504 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:57.505Z,1683242037.505 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:57.996Z,1683242037.996 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:57.997Z,1683242037.997 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:57.998Z,1683242037.998 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:58.308Z,1683242038.308 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:58.309Z,1683242038.309 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:58.309Z,1683242038.309 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:58.812Z,1683242038.812 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:58.813Z,1683242038.813 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:58.814Z,1683242038.814 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:59.106Z,1683242039.106 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:13:59.146Z,1683242039.146 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:59.147Z,1683242039.147 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:59.148Z,1683242039.148 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:59.542Z,1683242039.542 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:59.543Z,1683242039.543 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:59.545Z,1683242039.545 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:13:59.936Z,1683242039.936 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:13:59.937Z,1683242039.937 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:13:59.937Z,1683242039.937 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:00.330Z,1683242040.330 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:00.331Z,1683242040.331 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:00.333Z,1683242040.333 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:00.723Z,1683242040.723 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:00.724Z,1683242040.724 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:00.725Z,1683242040.725 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:01.127Z,1683242041.127 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:01.129Z,1683242041.129 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:01.129Z,1683242041.129 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:01.536Z,1683242041.536 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:01.537Z,1683242041.537 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:01.538Z,1683242041.538 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:01.955Z,1683242041.955 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:01.956Z,1683242041.956 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:01.957Z,1683242041.957 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:02.339Z,1683242042.339 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:02.340Z,1683242042.340 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:02.341Z,1683242042.341 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:02.749Z,1683242042.749 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:02.750Z,1683242042.750 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:02.750Z,1683242042.750 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:03.161Z,1683242043.161 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:03.162Z,1683242043.162 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:03.163Z,1683242043.163 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:03.555Z,1683242043.555 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:03.572Z,1683242043.572 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:03.573Z,1683242043.573 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:03.960Z,1683242043.960 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:03.961Z,1683242043.961 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:03.962Z,1683242043.962 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:04.366Z,1683242044.366 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:04.366Z,1683242044.366 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:04.367Z,1683242044.367 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:04.772Z,1683242044.772 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:04.773Z,1683242044.773 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:04.774Z,1683242044.774 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:05.167Z,1683242045.167 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:05.168Z,1683242045.168 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:05.169Z,1683242045.169 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:05.579Z,1683242045.579 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:05.580Z,1683242045.580 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:05.581Z,1683242045.581 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:05.974Z,1683242045.974 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:05.975Z,1683242045.975 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:05.992Z,1683242045.992 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:06.383Z,1683242046.383 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:06.384Z,1683242046.384 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:06.385Z,1683242046.385 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:06.787Z,1683242046.787 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:06.788Z,1683242046.788 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:06.789Z,1683242046.789 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:07.213Z,1683242047.213 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:07.214Z,1683242047.214 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:07.215Z,1683242047.215 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:07.613Z,1683242047.613 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:07.614Z,1683242047.614 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:07.615Z,1683242047.615 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:07.995Z,1683242047.995 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:07.996Z,1683242047.996 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:07.997Z,1683242047.997 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:08.403Z,1683242048.403 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:08.404Z,1683242048.404 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:08.405Z,1683242048.405 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:08.811Z,1683242048.811 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:08.812Z,1683242048.812 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:08.813Z,1683242048.813 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:09.219Z,1683242049.219 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:09.220Z,1683242049.220 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:09.221Z,1683242049.221 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:09.611Z,1683242049.611 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:09.612Z,1683242049.612 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:09.613Z,1683242049.613 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:10.015Z,1683242050.015 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:10.016Z,1683242050.016 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:10.017Z,1683242050.017 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:10.421Z,1683242050.421 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:10.422Z,1683242050.422 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:10.423Z,1683242050.423 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:10.834Z,1683242050.834 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:10.835Z,1683242050.835 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:10.836Z,1683242050.836 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:11.232Z,1683242051.232 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:11.233Z,1683242051.233 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:11.234Z,1683242051.234 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:11.771Z,1683242051.771 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:11.771Z,1683242051.771 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:11.776Z,1683242051.776 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:12.089Z,1683242052.089 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:12.090Z,1683242052.090 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:12.091Z,1683242052.091 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:12.596Z,1683242052.596 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:12.597Z,1683242052.597 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:12.597Z,1683242052.597 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:12.846Z,1683242052.846 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:14:12.891Z,1683242052.891 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:12.891Z,1683242052.891 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:12.900Z,1683242052.900 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:13.281Z,1683242053.281 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:13.282Z,1683242053.282 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:13.283Z,1683242053.283 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:13.663Z,1683242053.663 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:13.673Z,1683242053.673 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:13.674Z,1683242053.674 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:14.087Z,1683242054.087 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:14.092Z,1683242054.092 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:14.093Z,1683242054.093 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:14.508Z,1683242054.508 [senddata_direct_and_track_test:DirectData:A.Wait](INFO): Done Waiting.
2023-05-04T23:14:14.508Z,1683242054.508 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-04T23:14:14.508Z,1683242054.508 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:14:14.509Z,1683242054.509 [senddata_direct_and_track_test:DirectData:B] Running Loop=1
2023-05-04T23:14:14.509Z,1683242054.509 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:14.510Z,1683242054.510 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:14.511Z,1683242054.511 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:14.886Z,1683242054.886 [senddata_direct_and_track_test:DirectData:B](INFO): Queuing up send data for Onboard.Pressure with destination: modem:6:_.pressure
2023-05-04T23:14:14.886Z,1683242054.886 [senddata_direct_and_track_test:DirectData:B] Stopped
2023-05-04T23:14:14.886Z,1683242054.886 [senddata_direct_and_track_test:DirectData:C] Running Loop=1
2023-05-04T23:14:14.886Z,1683242054.886 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:14.887Z,1683242054.887 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:14.888Z,1683242054.888 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:14.984Z,1683242054.984 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-04T23:14:14.985Z,1683242054.985 [DATMMP](INFO): modem://6: set _.pressure 0.741217 atmosphere
2023-05-04T23:14:14.986Z,1683242054.986 [DATMMP](INFO): #Outgoing data=1
2023-05-04T23:14:14.987Z,1683242054.987 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-04T23:14:15.237Z,1683242055.237 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-04T23:14:15.237Z,1683242055.237 [DATMMP](INFO): depth rx: 	 x1	 x1	 x4	 x0
2023-05-04T23:14:15.238Z,1683242055.238 [DATMMP](INFO): Received message type: depth
2023-05-04T23:14:15.238Z,1683242055.238 [DATMMP](INFO): Match for : depth
2023-05-04T23:14:15.238Z,1683242055.238 [DATMMP](INFO): ACK
2023-05-04T23:14:15.238Z,1683242055.238 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:14:15.300Z,1683242055.300 [senddata_direct_and_track_test:DirectData:C](INFO): Queuing up send data for Onboard.Temperature with destination: modem:6:_.temperature
2023-05-04T23:14:15.301Z,1683242055.301 [senddata_direct_and_track_test:DirectData:C] Stopped
2023-05-04T23:14:15.301Z,1683242055.301 [senddata_direct_and_track_test:DirectData:D] Running Loop=1
2023-05-04T23:14:15.301Z,1683242055.301 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:15.302Z,1683242055.302 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:15.303Z,1683242055.303 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:15.489Z,1683242055.489 [DATMMP](INFO): GSXN notify for xid: 17
2023-05-04T23:14:15.489Z,1683242055.489 [DATMMP](INFO): lat rx: 	 x2318600	 x6	 x1	 x0
2023-05-04T23:14:15.490Z,1683242055.490 [DATMMP](INFO): Received message type: lat
2023-05-04T23:14:15.490Z,1683242055.490 [DATMMP](INFO): Match for : lat
2023-05-04T23:14:15.490Z,1683242055.490 [DATMMP](INFO): ACK
2023-05-04T23:14:15.490Z,1683242055.490 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:14:15.678Z,1683242055.678 [senddata_direct_and_track_test:DirectData:D](INFO): Queuing up send data for Onboard.Humidity with destination: modem:6:_.humidity
2023-05-04T23:14:15.678Z,1683242055.678 [senddata_direct_and_track_test:DirectData:D] Stopped
2023-05-04T23:14:15.678Z,1683242055.678 [senddata_direct_and_track_test:DirectData](INFO): Completed senddata_direct_and_track_test:DirectData
2023-05-04T23:14:15.678Z,1683242055.678 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-04T23:14:15.678Z,1683242055.678 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-04T23:14:15.679Z,1683242055.679 [senddata_direct_and_track_test:DirectData](INFO): Running loop #2
2023-05-04T23:14:15.679Z,1683242055.679 [senddata_direct_and_track_test:DirectData] Running Loop=2
2023-05-04T23:14:15.679Z,1683242055.679 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-04T23:14:15.679Z,1683242055.679 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-04T23:14:15.679Z,1683242055.679 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:14:15.679Z,1683242055.679 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:15.686Z,1683242055.686 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:15.687Z,1683242055.687 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:15.741Z,1683242055.741 [DATMMP](INFO): GSXN notify for xid: 18
2023-05-04T23:14:15.821Z,1683242055.821 [DATMMP](INFO): lon rx: 	 x0	 x6	 x1	 x0
2023-05-04T23:14:15.821Z,1683242055.821 [DATMMP](INFO): Received message type: lon
2023-05-04T23:14:15.821Z,1683242055.821 [DATMMP](INFO): Match for : lon
2023-05-04T23:14:15.821Z,1683242055.821 [DATMMP](INFO): ACK
2023-05-04T23:14:15.821Z,1683242055.821 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:14:15.821Z,1683242055.821 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-04T23:14:15.992Z,1683242055.992 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-04T23:14:15.993Z,1683242055.993 [DATMMP](INFO): Tx to 6 len 34 xid 19
2023-05-04T23:14:15.993Z,1683242055.993 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-04T23:14:15.994Z,1683242055.994 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-04T23:14:16.095Z,1683242056.095 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:16.096Z,1683242056.096 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:16.097Z,1683242056.097 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:16.245Z,1683242056.245 [DATMMP](INFO): GSXN notify for xid: 19
2023-05-04T23:14:16.245Z,1683242056.245 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-04T23:14:16.246Z,1683242056.246 [DATMMP](DEBUG): Command complete send
2023-05-04T23:14:16.246Z,1683242056.246 [DATMMP](ERROR): 1:COMPLETE
2023-05-04T23:14:16.246Z,1683242056.246 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:14:16.246Z,1683242056.246 [DATMMP](INFO): Handled
2023-05-04T23:14:16.246Z,1683242056.246 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:16.497Z,1683242056.497 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:16.497Z,1683242056.497 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 xE	 xE	 xB4D
2023-05-04T23:14:16.498Z,1683242056.498 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:14:16.498Z,1683242056.498 [DATMMP](INFO): Handled
2023-05-04T23:14:16.507Z,1683242056.507 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:16.508Z,1683242056.508 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:16.509Z,1683242056.509 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:16.748Z,1683242056.748 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:16.905Z,1683242056.905 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:16.906Z,1683242056.906 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:16.907Z,1683242056.907 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:17.000Z,1683242057.000 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:17.252Z,1683242057.252 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:17.323Z,1683242057.323 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:17.324Z,1683242057.324 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:17.325Z,1683242057.325 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:17.504Z,1683242057.504 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:17.691Z,1683242057.691 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:17.693Z,1683242057.693 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:17.694Z,1683242057.694 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:17.756Z,1683242057.756 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:18.008Z,1683242058.008 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:18.103Z,1683242058.103 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:18.105Z,1683242058.105 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:18.105Z,1683242058.105 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:18.260Z,1683242058.260 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:18.511Z,1683242058.511 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:18.512Z,1683242058.512 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:18.513Z,1683242058.513 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:18.514Z,1683242058.514 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:18.764Z,1683242058.764 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:18.902Z,1683242058.902 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:18.903Z,1683242058.903 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:18.904Z,1683242058.904 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:19.017Z,1683242059.017 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:19.268Z,1683242059.268 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:19.310Z,1683242059.310 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:19.311Z,1683242059.311 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:19.311Z,1683242059.311 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:19.520Z,1683242059.520 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:19.779Z,1683242059.779 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:19.780Z,1683242059.780 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:19.781Z,1683242059.781 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:20.024Z,1683242060.024 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:20.125Z,1683242060.125 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:20.126Z,1683242060.126 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:20.126Z,1683242060.126 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:20.277Z,1683242060.277 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:20.523Z,1683242060.523 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:20.533Z,1683242060.533 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:20.540Z,1683242060.540 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:20.541Z,1683242060.541 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:20.784Z,1683242060.784 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:20.956Z,1683242060.956 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:20.957Z,1683242060.957 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:20.957Z,1683242060.957 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:21.037Z,1683242061.037 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:21.037Z,1683242061.037 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:14:21.037Z,1683242061.037 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:14:21.037Z,1683242061.037 [DATMMP](INFO): Handled
2023-05-04T23:14:21.038Z,1683242061.038 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:21.289Z,1683242061.289 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:21.290Z,1683242061.290 [DATMMP](INFO): timestamp rx: 	 x1	 x17	 xE	 x12	 x1E0B
2023-05-04T23:14:21.290Z,1683242061.290 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:14:21.290Z,1683242061.290 [DATMMP](INFO): Handled
2023-05-04T23:14:21.290Z,1683242061.290 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:21.367Z,1683242061.367 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:21.377Z,1683242061.377 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:21.378Z,1683242061.378 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:21.540Z,1683242061.540 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:21.763Z,1683242061.763 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:21.765Z,1683242061.765 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:21.765Z,1683242061.765 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:21.793Z,1683242061.793 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:21.794Z,1683242061.794 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-04T23:14:21.794Z,1683242061.794 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-04T23:14:21.794Z,1683242061.794 [DATMMP](INFO): Received message type: remote_header
2023-05-04T23:14:21.794Z,1683242061.794 [DATMMP](INFO): Handled
2023-05-04T23:14:21.795Z,1683242061.795 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:22.045Z,1683242062.045 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:22.046Z,1683242062.046 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:14:22.046Z,1683242062.046 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:14:22.046Z,1683242062.046 [DATMMP](INFO): Handled
2023-05-04T23:14:22.046Z,1683242062.046 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:22.169Z,1683242062.169 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:22.170Z,1683242062.170 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242013.588120 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:22.171Z,1683242062.171 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:22.297Z,1683242062.297 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:22.298Z,1683242062.298 [DATMMP](INFO): directional_debug rx: 	 x474	 x18D	 x2D	 x3B	 x2420	 x28A1	 x2482	 x3643
2023-05-04T23:14:22.299Z,1683242062.299 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:14:22.299Z,1683242062.299 [DATMMP](INFO): Handled
2023-05-04T23:14:22.299Z,1683242062.299 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:22.547Z,1683242062.547 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:22.549Z,1683242062.549 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:22.550Z,1683242062.550 [DATMMP](INFO): directional rx: 	 x48A	 x114	 x168	 xFE73	 x6E7	 xFE81	 x0	 x575	 x4	 xFFED	 x2318600	 x0
2023-05-04T23:14:22.552Z,1683242062.552 [DATMMP](INFO): Received message type: directional
2023-05-04T23:14:22.552Z,1683242062.552 [DATMMP](INFO): Handled
2023-05-04T23:14:22.552Z,1683242062.552 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:22.553Z,1683242062.553 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:22.554Z,1683242062.554 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:22.564Z,1683242062.564 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-04T23:14:22.801Z,1683242062.801 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:22.802Z,1683242062.802 [DATMMP](INFO): acstats rx: 	 x6	 x5	 x0	 x236	 x27	 x36	 xB6	 xB1	 xB	 xFFFF	 x9E	 x30	 xB	 x0
2023-05-04T23:14:22.803Z,1683242062.803 [DATMMP](INFO): Received message type: acstats
2023-05-04T23:14:22.803Z,1683242062.803 [DATMMP](INFO): Handled
2023-05-04T23:14:22.803Z,1683242062.803 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:22.954Z,1683242062.954 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:22.955Z,1683242062.955 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:22.956Z,1683242062.956 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:23.053Z,1683242063.053 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:23.053Z,1683242063.053 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-04T23:14:23.054Z,1683242063.054 [DATMMP](INFO): Check CRC
2023-05-04T23:14:23.054Z,1683242063.054 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-04T23:14:23.054Z,1683242063.054 [DATMMP](INFO): Got DATA: len 4
2023-05-04T23:14:23.054Z,1683242063.054 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-04T23:14:23.055Z,1683242063.055 [DATMMP](INFO): Got ~~ ack, bytes left 0
2023-05-04T23:14:23.055Z,1683242063.055 [DATMMP](INFO): Received command: 

2023-05-04T23:14:23.055Z,1683242063.055 [DATMMP](INFO): Received message type: data_rx
2023-05-04T23:14:23.055Z,1683242063.055 [DATMMP](INFO): Handled
2023-05-04T23:14:23.055Z,1683242063.055 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-04T23:14:23.055Z,1683242063.055 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-04T23:14:23.304Z,1683242063.304 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-04T23:14:23.305Z,1683242063.305 [DATMMP](INFO): modem://6: set _.temperature 23.816370 celsius
2023-05-04T23:14:23.306Z,1683242063.306 [DATMMP](INFO): #Outgoing data=1
2023-05-04T23:14:23.306Z,1683242063.306 [DATMMP](ERROR): Waiting for previous to be ack'd
2023-05-04T23:14:23.306Z,1683242063.306 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-04T23:14:23.370Z,1683242063.370 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:23.371Z,1683242063.371 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:23.372Z,1683242063.372 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:23.556Z,1683242063.556 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:14:23.556Z,1683242063.556 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-04T23:14:23.753Z,1683242063.753 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:23.754Z,1683242063.754 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:23.755Z,1683242063.755 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:23.809Z,1683242063.809 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-04T23:14:23.809Z,1683242063.809 [DATMMP](INFO): Tx to 6 len 35 xid 20
2023-05-04T23:14:23.810Z,1683242063.810 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-04T23:14:23.810Z,1683242063.810 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-04T23:14:24.061Z,1683242064.061 [DATMMP](INFO): GSXN notify for xid: 20
2023-05-04T23:14:24.061Z,1683242064.061 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-04T23:14:24.062Z,1683242064.062 [DATMMP](DEBUG): Command complete send
2023-05-04T23:14:24.062Z,1683242064.062 [DATMMP](ERROR): 1:COMPLETE
2023-05-04T23:14:24.062Z,1683242064.062 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:14:24.062Z,1683242064.062 [DATMMP](INFO): Handled
2023-05-04T23:14:24.062Z,1683242064.062 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:24.163Z,1683242064.163 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:24.164Z,1683242064.164 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:24.165Z,1683242064.165 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:24.313Z,1683242064.313 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:24.314Z,1683242064.314 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 xE	 x16	 x37D
2023-05-04T23:14:24.314Z,1683242064.314 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:14:24.314Z,1683242064.314 [DATMMP](INFO): Handled
2023-05-04T23:14:24.314Z,1683242064.314 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:24.573Z,1683242064.573 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:24.573Z,1683242064.573 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:24.574Z,1683242064.574 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:24.816Z,1683242064.816 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:25.035Z,1683242065.035 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:25.036Z,1683242065.036 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:25.037Z,1683242065.037 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:25.068Z,1683242065.068 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:25.321Z,1683242065.321 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:25.377Z,1683242065.377 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:25.378Z,1683242065.378 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:25.378Z,1683242065.378 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:25.572Z,1683242065.572 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:25.802Z,1683242065.802 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:25.803Z,1683242065.803 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:25.804Z,1683242065.804 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:25.824Z,1683242065.824 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:26.076Z,1683242066.076 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:26.285Z,1683242066.285 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:26.286Z,1683242066.286 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:26.286Z,1683242066.286 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:26.328Z,1683242066.328 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:26.570Z,1683242066.570 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:14:26.625Z,1683242066.625 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:26.625Z,1683242066.625 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:26.626Z,1683242066.626 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:26.832Z,1683242066.832 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:27.033Z,1683242067.033 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:27.034Z,1683242067.034 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:27.034Z,1683242067.034 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:27.085Z,1683242067.085 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:27.336Z,1683242067.336 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:27.411Z,1683242067.411 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:27.412Z,1683242067.412 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:27.413Z,1683242067.413 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:27.588Z,1683242067.588 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:27.795Z,1683242067.795 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:27.796Z,1683242067.796 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:27.797Z,1683242067.797 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:27.842Z,1683242067.842 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:28.092Z,1683242068.092 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:28.201Z,1683242068.201 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:28.201Z,1683242068.201 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:28.202Z,1683242068.202 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:28.344Z,1683242068.344 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:28.597Z,1683242068.597 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:28.597Z,1683242068.597 [DATMMP](INFO): doppler rx: 	 x3
2023-05-04T23:14:28.597Z,1683242068.597 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:14:28.597Z,1683242068.597 [DATMMP](INFO): Handled
2023-05-04T23:14:28.598Z,1683242068.598 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:28.610Z,1683242068.610 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:28.610Z,1683242068.610 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:28.611Z,1683242068.611 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:28.849Z,1683242068.849 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:28.850Z,1683242068.850 [DATMMP](INFO): timestamp rx: 	 x1	 x17	 xE	 x1A	 xE6B
2023-05-04T23:14:28.850Z,1683242068.850 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:14:28.850Z,1683242068.850 [DATMMP](INFO): Handled
2023-05-04T23:14:28.850Z,1683242068.850 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:29.005Z,1683242069.005 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:29.006Z,1683242069.006 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:29.007Z,1683242069.007 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:29.100Z,1683242069.100 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:29.353Z,1683242069.353 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:29.355Z,1683242069.355 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-04T23:14:29.356Z,1683242069.356 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-04T23:14:29.356Z,1683242069.356 [DATMMP](INFO): Received message type: remote_header
2023-05-04T23:14:29.356Z,1683242069.356 [DATMMP](INFO): Handled
2023-05-04T23:14:29.356Z,1683242069.356 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:29.455Z,1683242069.455 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:29.464Z,1683242069.464 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:29.464Z,1683242069.464 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:29.605Z,1683242069.605 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:29.606Z,1683242069.606 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:14:29.606Z,1683242069.606 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:14:29.606Z,1683242069.606 [DATMMP](INFO): Handled
2023-05-04T23:14:29.606Z,1683242069.606 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:29.828Z,1683242069.828 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:29.829Z,1683242069.829 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242062.548351 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:29.829Z,1683242069.829 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:29.856Z,1683242069.856 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:29.858Z,1683242069.858 [DATMMP](INFO): directional_debug rx: 	 x4A0	 x1E0	 x2A	 x35	 x2370	 x2671	 x2452	 x3593
2023-05-04T23:14:29.859Z,1683242069.859 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:14:29.859Z,1683242069.859 [DATMMP](INFO): Handled
2023-05-04T23:14:29.859Z,1683242069.859 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:30.109Z,1683242070.109 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:30.111Z,1683242070.111 [DATMMP](INFO): directional rx: 	 x4AE	 x173	 x13C	 xFE20	 x6AD	 xFE2F	 x0	 x563	 x5	 xFFED	 x2318600	 x0
2023-05-04T23:14:30.112Z,1683242070.112 [DATMMP](INFO): Received message type: directional
2023-05-04T23:14:30.112Z,1683242070.112 [DATMMP](INFO): Handled
2023-05-04T23:14:30.113Z,1683242070.113 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:30.229Z,1683242070.229 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:30.230Z,1683242070.230 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:30.231Z,1683242070.231 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:30.254Z,1683242070.254 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-04T23:14:30.361Z,1683242070.361 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:30.362Z,1683242070.362 [DATMMP](INFO): acstats rx: 	 x6	 x5	 x3	 x237	 x27	 x2A	 xB8	 xAF	 xD	 xFFFF	 xA5	 x2D	 xB	 x0
2023-05-04T23:14:30.362Z,1683242070.362 [DATMMP](INFO): Received message type: acstats
2023-05-04T23:14:30.363Z,1683242070.363 [DATMMP](INFO): Handled
2023-05-04T23:14:30.363Z,1683242070.363 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:30.612Z,1683242070.612 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:30.613Z,1683242070.613 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-04T23:14:30.613Z,1683242070.613 [DATMMP](INFO): Check CRC
2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](INFO): Got DATA: len 4
2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](INFO): Got ~~ ack, bytes left 2
2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](INFO): Received command: 

2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](INFO): Received message type: data_rx
2023-05-04T23:14:30.614Z,1683242070.614 [DATMMP](INFO): Handled
2023-05-04T23:14:30.615Z,1683242070.615 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-04T23:14:30.615Z,1683242070.615 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-04T23:14:30.671Z,1683242070.671 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:30.672Z,1683242070.672 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:30.673Z,1683242070.673 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:30.865Z,1683242070.865 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-04T23:14:30.866Z,1683242070.866 [DATMMP](INFO): modem://6: set _.humidity 12.812844 percent
2023-05-04T23:14:30.866Z,1683242070.866 [DATMMP](INFO): #Outgoing data=1
2023-05-04T23:14:30.866Z,1683242070.866 [DATMMP](ERROR): Waiting for previous to be ack'd
2023-05-04T23:14:30.866Z,1683242070.866 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-04T23:14:31.042Z,1683242071.042 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:31.043Z,1683242071.043 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:31.044Z,1683242071.044 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:31.116Z,1683242071.116 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-04T23:14:31.116Z,1683242071.116 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-04T23:14:31.368Z,1683242071.368 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-04T23:14:31.369Z,1683242071.369 [DATMMP](INFO): Tx to 6 len 32 xid 21
2023-05-04T23:14:31.369Z,1683242071.369 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-04T23:14:31.370Z,1683242071.369 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-04T23:14:31.473Z,1683242071.473 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:31.474Z,1683242071.474 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:31.474Z,1683242071.474 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:31.621Z,1683242071.621 [DATMMP](INFO): GSXN notify for xid: 21
2023-05-04T23:14:31.622Z,1683242071.622 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-04T23:14:31.622Z,1683242071.622 [DATMMP](DEBUG): Command complete send
2023-05-04T23:14:31.622Z,1683242071.622 [DATMMP](ERROR): 1:COMPLETE
2023-05-04T23:14:31.622Z,1683242071.622 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T23:14:31.622Z,1683242071.622 [DATMMP](INFO): Handled
2023-05-04T23:14:31.622Z,1683242071.622 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:31.869Z,1683242071.869 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:31.869Z,1683242071.869 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:31.870Z,1683242071.870 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:31.873Z,1683242071.873 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:31.874Z,1683242071.874 [DATMMP](INFO): timestamp rx: 	 x0	 x17	 xE	 x1D	 x1AEC
2023-05-04T23:14:31.874Z,1683242071.874 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:14:31.874Z,1683242071.874 [DATMMP](INFO): Handled
2023-05-04T23:14:31.874Z,1683242071.874 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:32.124Z,1683242072.124 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:32.267Z,1683242072.267 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:32.268Z,1683242072.268 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:32.269Z,1683242072.269 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:32.376Z,1683242072.376 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:32.629Z,1683242072.629 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:32.647Z,1683242072.647 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:32.648Z,1683242072.648 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:32.649Z,1683242072.649 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:32.881Z,1683242072.881 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:33.056Z,1683242073.056 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:33.057Z,1683242073.057 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:33.058Z,1683242073.058 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:33.132Z,1683242073.132 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:33.384Z,1683242073.384 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:33.451Z,1683242073.451 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:33.452Z,1683242073.452 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:33.453Z,1683242073.453 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:33.636Z,1683242073.636 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:33.855Z,1683242073.855 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:33.856Z,1683242073.856 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:33.857Z,1683242073.857 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:33.888Z,1683242073.888 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:34.140Z,1683242074.140 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:34.261Z,1683242074.261 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:34.262Z,1683242074.262 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:34.263Z,1683242074.263 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:34.392Z,1683242074.392 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:34.644Z,1683242074.644 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:34.679Z,1683242074.679 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:34.680Z,1683242074.680 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:34.681Z,1683242074.681 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:34.896Z,1683242074.896 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:35.064Z,1683242075.064 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:35.065Z,1683242075.065 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:35.066Z,1683242075.066 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:35.148Z,1683242075.148 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:35.400Z,1683242075.400 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:35.470Z,1683242075.470 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:35.471Z,1683242075.471 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:35.473Z,1683242075.473 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:35.652Z,1683242075.652 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:35.881Z,1683242075.881 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:35.881Z,1683242075.881 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:35.882Z,1683242075.882 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:35.905Z,1683242075.905 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:35.905Z,1683242075.905 [DATMMP](INFO): doppler rx: 	 x0
2023-05-04T23:14:35.905Z,1683242075.905 [DATMMP](INFO): Received message type: doppler
2023-05-04T23:14:35.905Z,1683242075.905 [DATMMP](INFO): Handled
2023-05-04T23:14:35.906Z,1683242075.906 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:36.157Z,1683242076.157 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:36.158Z,1683242076.158 [DATMMP](INFO): timestamp rx: 	 x1	 x17	 xE	 x21	 x1A24
2023-05-04T23:14:36.158Z,1683242076.158 [DATMMP](INFO): Received message type: timestamp
2023-05-04T23:14:36.158Z,1683242076.158 [DATMMP](INFO): Handled
2023-05-04T23:14:36.158Z,1683242076.158 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:36.301Z,1683242076.301 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:36.301Z,1683242076.301 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:36.302Z,1683242076.302 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:36.408Z,1683242076.408 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:36.665Z,1683242076.665 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:36.666Z,1683242076.666 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x4	 x0	 x5	 x6	 x0
2023-05-04T23:14:36.666Z,1683242076.666 [DATMMP](INFO): DATA Src=6, Dst=5
2023-05-04T23:14:36.666Z,1683242076.666 [DATMMP](INFO): Received message type: remote_header
2023-05-04T23:14:36.666Z,1683242076.666 [DATMMP](INFO): Handled
2023-05-04T23:14:36.666Z,1683242076.666 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:36.752Z,1683242076.752 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:36.753Z,1683242076.753 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:36.754Z,1683242076.754 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:36.917Z,1683242076.917 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:36.917Z,1683242076.917 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T23:14:36.918Z,1683242076.918 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T23:14:36.918Z,1683242076.918 [DATMMP](INFO): Handled
2023-05-04T23:14:36.918Z,1683242076.918 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:37.127Z,1683242077.127 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:37.128Z,1683242077.128 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242070.108660 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:37.129Z,1683242077.129 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:37.169Z,1683242077.169 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:37.170Z,1683242077.170 [DATMMP](INFO): directional_debug rx: 	 x3B0	 xFF84	 x2C	 xFD	 x24D0	 x28C1	 x2672	 x36A3
2023-05-04T23:14:37.171Z,1683242077.171 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T23:14:37.171Z,1683242077.171 [DATMMP](INFO): Handled
2023-05-04T23:14:37.171Z,1683242077.171 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:37.421Z,1683242077.421 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:37.422Z,1683242077.422 [DATMMP](INFO): directional rx: 	 x39E	 xFF9B	 x22C	 x7C	 x77E	 x8D	 x0	 x554	 x5	 xFFEE	 x2318600	 x0
2023-05-04T23:14:37.424Z,1683242077.424 [DATMMP](INFO): Received message type: directional
2023-05-04T23:14:37.424Z,1683242077.424 [DATMMP](INFO): Handled
2023-05-04T23:14:37.424Z,1683242077.424 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:37.515Z,1683242077.515 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:37.517Z,1683242077.517 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:37.517Z,1683242077.517 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:37.527Z,1683242077.527 [Reporter](INFO): DATMMP.acoustic_contact_range 0.000000 m
2023-05-04T23:14:37.673Z,1683242077.673 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:37.675Z,1683242077.675 [DATMMP](INFO): acstats rx: 	 x6	 x5	 x0	 x20B	 x26	 x32	 xB3	 xAE	 xD	 xFFFF	 x9D	 x31	 xB	 x0
2023-05-04T23:14:37.675Z,1683242077.675 [DATMMP](INFO): Received message type: acstats
2023-05-04T23:14:37.675Z,1683242077.675 [DATMMP](INFO): Handled
2023-05-04T23:14:37.675Z,1683242077.675 [DATMMP](INFO): *** SENDING_ACK_WAITING ***
2023-05-04T23:14:37.907Z,1683242077.907 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:37.908Z,1683242077.908 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:37.909Z,1683242077.909 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:37.925Z,1683242077.925 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T23:14:37.925Z,1683242077.925 [DATMMP](INFO): data_rx rx: 	 x6	 x0	 x0	 x4
2023-05-04T23:14:37.925Z,1683242077.925 [DATMMP](INFO): Check CRC
2023-05-04T23:14:37.926Z,1683242077.926 [DATMMP](INFO): data rx parseDataResponse 4
2023-05-04T23:14:37.926Z,1683242077.926 [DATMMP](INFO): Got DATA: len 4
2023-05-04T23:14:37.926Z,1683242077.926 [DATMMP](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2023-05-04T23:14:37.926Z,1683242077.926 [DATMMP](INFO): Got ~~ ack, bytes left 2
2023-05-04T23:14:37.926Z,1683242077.926 [DATMMP](INFO): Received command: 

2023-05-04T23:14:37.926Z,1683242077.926 [DATMMP](INFO): Received message type: data_rx
2023-05-04T23:14:37.927Z,1683242077.927 [DATMMP](INFO): Handled
2023-05-04T23:14:37.927Z,1683242077.927 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-04T23:14:37.927Z,1683242077.927 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-04T23:14:38.358Z,1683242078.358 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:38.359Z,1683242078.359 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:38.380Z,1683242078.380 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:38.709Z,1683242078.709 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:38.710Z,1683242078.710 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:38.711Z,1683242078.711 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:39.124Z,1683242079.124 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:39.125Z,1683242079.125 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:39.126Z,1683242079.126 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:39.582Z,1683242079.582 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:39.582Z,1683242079.582 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:39.583Z,1683242079.583 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:39.900Z,1683242079.900 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:14:39.913Z,1683242079.913 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:39.914Z,1683242079.914 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:39.914Z,1683242079.914 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:40.335Z,1683242080.335 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:40.353Z,1683242080.353 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:40.354Z,1683242080.354 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:40.739Z,1683242080.739 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:40.740Z,1683242080.740 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:40.741Z,1683242080.741 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:41.131Z,1683242081.131 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:41.132Z,1683242081.132 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:41.133Z,1683242081.133 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:41.556Z,1683242081.556 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:41.557Z,1683242081.557 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:41.557Z,1683242081.557 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:41.970Z,1683242081.970 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:41.971Z,1683242081.971 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:41.972Z,1683242081.972 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:42.321Z,1683242082.321 [CommandExec](IMPORTANT): got command stop
2023-05-04T23:14:42.321Z,1683242082.321 [CommandExec](IMPORTANT): Scheduling is paused
2023-05-04T23:14:42.321Z,1683242082.321 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-05-04T23:14:42.357Z,1683242082.357 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T23:14:42.358Z,1683242082.358 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683242077.420349 .And dataStartTime_:1683242009.640093
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T23:14:42.359Z,1683242082.359 [MissionManager](INFO): MissionManager is completed.
2023-05-04T23:14:42.359Z,1683242082.359 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test] Stopped
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test:A.] Stopped
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test:A.](DEBUG): Uninitializing Tracking.
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-04T23:14:42.359Z,1683242082.359 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-04T23:14:42.360Z,1683242082.360 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-04T23:14:42.360Z,1683242082.360 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:14:42.760Z,1683242082.760 [MissionManager](IMPORTANT): Started mission Default
2023-05-04T23:14:42.760Z,1683242082.760 [Default] Running Loop=1
2023-05-04T23:14:42.760Z,1683242082.760 [Default](DEBUG): Aggregate::initialize Default
2023-05-04T23:14:42.760Z,1683242082.760 [Default:B.GoToSurface] Running Loop=1
2023-05-04T23:14:42.760Z,1683242082.760 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T23:14:42.760Z,1683242082.760 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T23:14:42.761Z,1683242082.761 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T23:14:42.761Z,1683242082.761 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T23:14:42.761Z,1683242082.761 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T23:14:42.762Z,1683242082.762 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T23:14:42.762Z,1683242082.762 [Default:A.Wait] Running Loop=1
2023-05-04T23:14:42.762Z,1683242082.762 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:14:53.234Z,1683242093.234 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T23:14:56.075Z,1683242096.075 [Default:A.Wait](INFO): Done Waiting.
2023-05-04T23:14:56.076Z,1683242096.076 [Default:A.Wait] Stopped
2023-05-04T23:14:56.076Z,1683242096.076 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:14:56.477Z,1683242096.477 [Default:CheckIn] Running Loop=1
2023-05-04T23:14:56.477Z,1683242096.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T23:14:56.477Z,1683242096.477 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T23:17:33.226Z,1683242253.226 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5.
2023-05-04T23:17:33.229Z,1683242253.229 [BPC1](INFO): Received data from all battery sticks.
2023-05-04T23:18:14.837Z,1683242294.837 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-04T23:19:56.681Z,1683242396.681 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-04T23:14:56.5Z
2023-05-04T23:19:56.681Z,1683242396.681 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T23:19:56.682Z,1683242396.682 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T23:19:57.078Z,1683242397.078 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-04T23:20:04.072Z,1683242404.072 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230504T230959/Courier0004.lzma
2023-05-04T23:20:05.074Z,1683242405.074 [DataOverHttps](INFO): Moved sent file to Logs/20230504T230959/Courier0004.lzma.bak
2023-05-04T23:20:05.074Z,1683242405.074 [DataOverHttps](INFO): SBD MOMSN=18281943
2023-05-04T23:20:24.589Z,1683242424.589 [DataOverHttps](INFO): Sending 1317 bytes from file Logs/20230504T230959/Express0001.lzma
2023-05-04T23:20:25.595Z,1683242425.595 [DataOverHttps](INFO): Moved sent file to Logs/20230504T230959/Express0001.lzma.bak
2023-05-04T23:20:25.595Z,1683242425.595 [DataOverHttps](INFO): SBD MOMSN=18281945
2023-05-04T23:20:29.417Z,1683242429.417 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T23:20:47.053Z,1683242447.053 [DataOverHttps](INFO): Sending 446 bytes from file Logs/20230504T230959/Express0005.lzma
2023-05-04T23:20:48.068Z,1683242448.068 [DataOverHttps](INFO): Moved sent file to Logs/20230504T230959/Express0005.lzma.bak
2023-05-04T23:20:48.068Z,1683242448.068 [DataOverHttps](INFO): SBD MOMSN=18281983
2023-05-04T23:20:50.856Z,1683242450.856 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T23:20:50.856Z,1683242450.856 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T23:20:50.856Z,1683242450.856 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T23:21:20.921Z,1683242480.921 [CommandExec](IMPORTANT): got command restart application
2023-05-04T23:21:21.924Z,1683242481.924 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:21.924Z,1683242481.924 [CommandExec](INFO): Uninitializing the command executive.
2023-05-04T23:21:21.924Z,1683242481.924 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-04T23:21:21.924Z,1683242481.924 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:21.956Z,1683242481.956 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-04T23:21:21.956Z,1683242481.956 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-04T23:21:21.956Z,1683242481.956 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:21.957Z,1683242481.957 [NavChartDb](INFO): Join timeout helper Thread ID is 3867
2023-05-04T23:21:22.080Z,1683242482.080 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:22.080Z,1683242482.080 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:22.096Z,1683242482.096 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-04T23:21:22.096Z,1683242482.096 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:22.097Z,1683242482.097 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3868
2023-05-04T23:21:22.412Z,1683242482.412 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:22.412Z,1683242482.412 [WetLabsBB2FL](INFO): Powering down
2023-05-04T23:21:22.413Z,1683242482.413 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:22.420Z,1683242482.420 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-04T23:21:22.420Z,1683242482.420 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:22.420Z,1683242482.420 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3869
2023-05-04T23:21:23.188Z,1683242483.188 [CTD_Seabird](INFO): Powering down
2023-05-04T23:21:23.200Z,1683242483.200 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:23.200Z,1683242483.200 [CTD_Seabird](INFO): Powering down
2023-05-04T23:21:23.212Z,1683242483.212 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:23.232Z,1683242483.232 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-04T23:21:23.232Z,1683242483.232 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:23.233Z,1683242483.233 [DATMMP](INFO): Join timeout helper Thread ID is 3870
2023-05-04T23:21:23.376Z,1683242483.376 [DATMMP](INFO): Stop
2023-05-04T23:21:23.376Z,1683242483.376 [DATMMP](INFO): uninitialize
2023-05-04T23:21:23.376Z,1683242483.376 [DATMMP](INFO): Powering down
2023-05-04T23:21:23.453Z,1683242483.453 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:23.453Z,1683242483.453 [DATMMP](INFO): uninitialize
2023-05-04T23:21:23.453Z,1683242483.453 [DATMMP](INFO): Powering down
2023-05-04T23:21:23.454Z,1683242483.454 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:23.460Z,1683242483.460 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-04T23:21:23.460Z,1683242483.460 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:23.460Z,1683242483.460 [Radio_Surface](INFO): Join timeout helper Thread ID is 3871
2023-05-04T23:21:23.724Z,1683242483.724 [Radio_Surface](INFO): Powering down
2023-05-04T23:21:23.725Z,1683242483.725 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:23.725Z,1683242483.725 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:23.733Z,1683242483.733 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-04T23:21:23.733Z,1683242483.733 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:23.733Z,1683242483.733 [Onboard](INFO): Join timeout helper Thread ID is 3872
2023-05-04T23:21:24.596Z,1683242484.596 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-04T23:21:24.768Z,1683242484.768 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:24.768Z,1683242484.768 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:24.770Z,1683242484.770 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-04T23:21:24.770Z,1683242484.770 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:24.770Z,1683242484.770 [DataOverHttps](INFO): Join timeout helper Thread ID is 3873
2023-05-04T23:21:25.600Z,1683242485.600 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:25.601Z,1683242485.601 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.621Z,1683242485.621 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-04T23:21:25.621Z,1683242485.621 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.621Z,1683242485.621 [BackseatComponent](INFO): Join timeout helper Thread ID is 3874
2023-05-04T23:21:25.749Z,1683242485.749 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:25.750Z,1683242485.750 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.752Z,1683242485.752 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-04T23:21:25.752Z,1683242485.752 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.752Z,1683242485.752 [logger](INFO): Join timeout helper Thread ID is 3875
2023-05-04T23:21:25.765Z,1683242485.765 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:25.765Z,1683242485.765 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.773Z,1683242485.773 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-04T23:21:25.773Z,1683242485.773 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.773Z,1683242485.773 [CommandLine](INFO): Join timeout helper Thread ID is 3876
2023-05-04T23:21:25.868Z,1683242485.868 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:25.868Z,1683242485.868 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.873Z,1683242485.873 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-04T23:21:25.873Z,1683242485.873 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.873Z,1683242485.873 [CommandExec](INFO): Join timeout helper Thread ID is 3877
2023-05-04T23:21:25.874Z,1683242485.874 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-04T23:21:25.874Z,1683242485.874 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:25.875Z,1683242485.875 [controlThread](INFO): Join timeout helper Thread ID is 3878
2023-05-04T23:21:25.964Z,1683242485.964 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T23:21:25.964Z,1683242485.964 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-04T23:21:25.965Z,1683242485.965 [AHRS_M2](INFO): Powering down
2023-05-04T23:21:26.037Z,1683242486.037 [NAL9602](INFO): Powering down
2023-05-04T23:21:26.039Z,1683242486.039 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-04T23:21:26.040Z,1683242486.040 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-04T23:21:26.041Z,1683242486.041 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-04T23:21:26.041Z,1683242486.041 [MissionManager](INFO): Uninitializing Mission Default
2023-05-04T23:21:26.041Z,1683242486.041 [Default] Stopped
2023-05-04T23:21:26.041Z,1683242486.041 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T23:21:26.041Z,1683242486.041 [Default:B.GoToSurface] Stopped
2023-05-04T23:21:26.041Z,1683242486.041 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T23:21:26.042Z,1683242486.042 [Default:CheckIn] Stopped
2023-05-04T23:21:26.042Z,1683242486.042 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T23:21:26.042Z,1683242486.042 [Default:CheckIn:C.Wait] Stopped
2023-05-04T23:21:26.042Z,1683242486.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T23:21:26.044Z,1683242486.044 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-04T23:21:26.045Z,1683242486.045 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-04T23:21:26.045Z,1683242486.045 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-04T23:21:26.045Z,1683242486.045 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-04T23:21:26.046Z,1683242486.046 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-04T23:21:26.046Z,1683242486.046 [BuoyancyServo](INFO): Powering down
2023-05-04T23:21:26.060Z,1683242486.060 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-04T23:21:26.060Z,1683242486.060 [ElevatorServo](INFO): Powering down
2023-05-04T23:21:26.061Z,1683242486.061 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-04T23:21:26.061Z,1683242486.061 [MassServo](INFO): Powering down
2023-05-04T23:21:26.062Z,1683242486.062 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-04T23:21:26.062Z,1683242486.062 [RudderServo](INFO): Powering down
2023-05-04T23:21:26.063Z,1683242486.063 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-04T23:21:26.063Z,1683242486.063 [ThrusterHE](INFO): Powering down
2023-05-04T23:21:26.064Z,1683242486.064 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-04T23:21:26.064Z,1683242486.064 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-04T23:21:26.064Z,1683242486.064 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-04T23:21:26.065Z,1683242486.065 [CBIT](DEBUG): Powering off loads.
2023-05-04T23:21:26.076Z,1683242486.076 [CBIT](DEBUG): Disabling WDT.
2023-05-04T23:21:26.088Z,1683242486.088 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-04T23:21:26.089Z,1683242486.089 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.131Z,1683242486.131 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.140Z,1683242486.140 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.142Z,1683242486.142 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.148Z,1683242486.148 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.191Z,1683242486.191 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.236Z,1683242486.236 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.241Z,1683242486.241 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.274Z,1683242486.274 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T23:21:26.332Z,1683242486.332 [logger ThreadHandler](INFO): Thread cancelled.