2023-05-12T19:17:23.465Z,1683919043.465 [Supervisor](DEBUG): Initializing supervisor.
2023-05-12T19:17:23.470Z,1683919043.470 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-12T19:17:23.470Z,1683919043.470 [SyncHandler](INFO): Protected caller Thread ID is 7404
2023-05-12T19:17:23.471Z,1683919043.471 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-12T19:17:23.472Z,1683919043.472 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-12T19:17:23.473Z,1683919043.473 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7405
2023-05-12T19:17:23.477Z,1683919043.477 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-12T19:17:23.494Z,1683919043.494 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-12T19:17:23.495Z,1683919043.495 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-12T19:17:23.495Z,1683919043.495 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7406
2023-05-12T19:17:23.499Z,1683919043.499 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-12T19:17:23.500Z,1683919043.500 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-12T19:17:23.501Z,1683919043.501 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7407
2023-05-12T19:17:23.503Z,1683919043.503 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-12T19:17:23.504Z,1683919043.504 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-12T19:17:23.504Z,1683919043.504 [logger ThreadHandler](INFO): Protected caller Thread ID is 7408
2023-05-12T19:17:23.507Z,1683919043.507 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-12T19:17:23.508Z,1683919043.508 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-12T19:17:23.509Z,1683919043.509 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-12T19:17:23.606Z,1683919043.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-12T19:17:23.606Z,1683919043.606 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-12T19:17:24.255Z,1683919044.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-12T19:17:24.256Z,1683919044.256 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-12T19:17:24.475Z,1683919044.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-12T19:17:24.475Z,1683919044.475 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-12T19:17:24.555Z,1683919044.555 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-12T19:17:24.685Z,1683919044.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-12T19:17:24.685Z,1683919044.685 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-12T19:17:24.767Z,1683919044.767 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-12T19:17:24.874Z,1683919044.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-12T19:17:24.875Z,1683919044.875 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-12T19:17:25.239Z,1683919045.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-12T19:17:25.240Z,1683919045.240 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-12T19:17:25.704Z,1683919045.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-12T19:17:25.704Z,1683919045.704 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-12T19:17:25.918Z,1683919045.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-12T19:17:25.919Z,1683919045.919 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-12T19:17:26.097Z,1683919046.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-12T19:17:26.097Z,1683919046.097 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-12T19:17:26.582Z,1683919046.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-12T19:17:26.582Z,1683919046.582 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-12T19:17:26.868Z,1683919046.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-12T19:17:26.868Z,1683919046.868 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-12T19:17:27.075Z,1683919047.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-12T19:17:27.076Z,1683919047.076 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-12T19:17:27.427Z,1683919047.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-12T19:17:27.428Z,1683919047.428 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-12T19:17:27.759Z,1683919047.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-12T19:17:27.761Z,1683919047.761 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-12T19:17:27.762Z,1683919047.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-12T19:17:27.905Z,1683919047.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-12T19:17:28.241Z,1683919048.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-12T19:17:28.392Z,1683919048.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-12T19:17:28.718Z,1683919048.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-12T19:17:28.891Z,1683919048.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-12T19:17:29.066Z,1683919049.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-12T19:17:29.324Z,1683919049.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-12T19:17:29.325Z,1683919049.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-12T19:17:29.418Z,1683919049.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-12T19:17:29.516Z,1683919049.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-12T19:17:29.652Z,1683919049.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-12T19:17:29.752Z,1683919049.752 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-05-12T19:17:29.752Z,1683919049.752 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-12T19:17:29.760Z,1683919049.760 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-12T19:17:29.854Z,1683919049.854 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-12T19:17:29.854Z,1683919049.854 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-12T19:17:29.904Z,1683919049.904 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-12T19:17:29.962Z,1683919049.962 [VerticalControl] Loaded
2023-05-12T19:17:29.962Z,1683919049.962 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-12T19:17:29.965Z,1683919049.965 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-12T19:17:30.005Z,1683919050.005 [HorizontalControl] Loaded
2023-05-12T19:17:30.005Z,1683919050.005 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-12T19:17:30.007Z,1683919050.007 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-12T19:17:30.010Z,1683919050.010 [SpeedControl] Loaded
2023-05-12T19:17:30.010Z,1683919050.010 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-12T19:17:30.013Z,1683919050.013 [LoopControl](DEBUG): Construct LoopControl.
2023-05-12T19:17:30.014Z,1683919050.014 [LoopControl] Loaded
2023-05-12T19:17:30.014Z,1683919050.014 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-12T19:17:30.014Z,1683919050.014 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-12T19:17:30.015Z,1683919050.015 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-12T19:17:30.062Z,1683919050.062 [DepthRateCalculator] Loaded
2023-05-12T19:17:30.062Z,1683919050.062 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-12T19:17:30.067Z,1683919050.067 [PitchRateCalculator] Loaded
2023-05-12T19:17:30.067Z,1683919050.067 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-12T19:17:30.077Z,1683919050.077 [SpeedCalculator] Loaded
2023-05-12T19:17:30.077Z,1683919050.077 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-12T19:17:30.081Z,1683919050.081 [YawRateCalculator] Loaded
2023-05-12T19:17:30.081Z,1683919050.081 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-12T19:17:30.099Z,1683919050.099 [ElevatorOffsetCalculator] Loaded
2023-05-12T19:17:30.099Z,1683919050.099 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-12T19:17:30.100Z,1683919050.100 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-12T19:17:30.100Z,1683919050.100 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-12T19:17:30.126Z,1683919050.126 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-12T19:17:30.127Z,1683919050.127 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-12T19:17:30.196Z,1683919050.196 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-12T19:17:30.197Z,1683919050.197 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-12T19:17:30.491Z,1683919050.491 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-12T19:17:30.492Z,1683919050.492 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-12T19:17:30.632Z,1683919050.632 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-12T19:17:30.632Z,1683919050.632 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-12T19:17:31.100Z,1683919051.100 [AHRS_M2] Loaded
2023-05-12T19:17:31.100Z,1683919051.100 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-12T19:17:31.134Z,1683919051.134 [BackseatComponent] Loaded
2023-05-12T19:17:31.134Z,1683919051.134 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-12T19:17:31.136Z,1683919051.136 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2023-05-12T19:17:31.136Z,1683919051.136 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7490
2023-05-12T19:17:31.139Z,1683919051.139 [LcmUniversalReporter] Loaded
2023-05-12T19:17:31.139Z,1683919051.139 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-12T19:17:31.927Z,1683919051.927 [BPC1] Loaded
2023-05-12T19:17:31.927Z,1683919051.927 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-12T19:17:32.009Z,1683919052.009 [DATMMP] Loaded
2023-05-12T19:17:32.009Z,1683919052.009 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-12T19:17:32.010Z,1683919052.010 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2023-05-12T19:17:32.010Z,1683919052.010 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 7491
2023-05-12T19:17:32.082Z,1683919052.082 [DataOverHttps] Loaded
2023-05-12T19:17:32.082Z,1683919052.082 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-12T19:17:32.083Z,1683919052.083 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2023-05-12T19:17:32.084Z,1683919052.084 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7492
2023-05-12T19:17:32.104Z,1683919052.104 [Depth_Keller] Loaded
2023-05-12T19:17:32.104Z,1683919052.104 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-12T19:17:32.166Z,1683919052.166 [NAL9602] Loaded
2023-05-12T19:17:32.166Z,1683919052.166 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-12T19:17:32.194Z,1683919052.194 [Onboard] Loaded
2023-05-12T19:17:32.195Z,1683919052.195 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-12T19:17:32.196Z,1683919052.196 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-05-12T19:17:32.196Z,1683919052.196 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7493
2023-05-12T19:17:32.208Z,1683919052.208 [Power24vConverter] Loaded
2023-05-12T19:17:32.208Z,1683919052.208 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-12T19:17:32.221Z,1683919052.221 [Radio_Surface] Loaded
2023-05-12T19:17:32.221Z,1683919052.221 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-12T19:17:32.222Z,1683919052.222 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-05-12T19:17:32.222Z,1683919052.222 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7494
2023-05-12T19:17:32.266Z,1683919052.266 [RDI_Pathfinder] Loaded
2023-05-12T19:17:32.266Z,1683919052.266 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-12T19:17:32.267Z,1683919052.267 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-12T19:17:32.268Z,1683919052.268 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-12T19:17:32.374Z,1683919052.374 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-12T19:17:32.374Z,1683919052.374 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-12T19:17:32.387Z,1683919052.387 [NavChart] Loaded
2023-05-12T19:17:32.388Z,1683919052.388 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-12T19:17:32.393Z,1683919052.393 [UniversalFixResidualReporter] Loaded
2023-05-12T19:17:32.394Z,1683919052.394 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-12T19:17:32.394Z,1683919052.394 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-12T19:17:32.394Z,1683919052.394 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-12T19:17:32.530Z,1683919052.530 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-12T19:17:32.538Z,1683919052.538 [SBIT] Loaded
2023-05-12T19:17:32.539Z,1683919052.539 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-12T19:17:32.542Z,1683919052.542 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-12T19:17:32.554Z,1683919052.554 [IBIT] Loaded
2023-05-12T19:17:32.554Z,1683919052.554 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-12T19:17:32.560Z,1683919052.560 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-12T19:17:32.660Z,1683919052.660 [CBIT] Loaded
2023-05-12T19:17:32.660Z,1683919052.660 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-12T19:17:32.661Z,1683919052.661 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-12T19:17:32.661Z,1683919052.661 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-12T19:17:32.789Z,1683919052.789 [BuoyancyServo] Loaded
2023-05-12T19:17:32.789Z,1683919052.789 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-12T19:17:32.809Z,1683919052.809 [ElevatorServo] Loaded
2023-05-12T19:17:32.809Z,1683919052.809 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-12T19:17:32.908Z,1683919052.908 [MassServo] Loaded
2023-05-12T19:17:32.909Z,1683919052.909 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-12T19:17:32.947Z,1683919052.947 [RudderServo] Loaded
2023-05-12T19:17:32.947Z,1683919052.947 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-12T19:17:32.964Z,1683919052.964 [ThrusterServo] Loaded
2023-05-12T19:17:32.964Z,1683919052.964 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-12T19:17:32.965Z,1683919052.965 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-12T19:17:32.965Z,1683919052.965 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-12T19:17:32.975Z,1683919052.975 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-12T19:17:32.976Z,1683919052.976 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-12T19:17:33.290Z,1683919053.290 [CTD_Seabird] Loaded
2023-05-12T19:17:33.290Z,1683919053.290 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-12T19:17:33.291Z,1683919053.291 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0
2023-05-12T19:17:33.291Z,1683919053.291 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7495
2023-05-12T19:17:33.330Z,1683919053.330 [PAR_Licor] Loaded
2023-05-12T19:17:33.331Z,1683919053.331 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-12T19:17:33.441Z,1683919053.441 [WetLabsBB2FL] Loaded
2023-05-12T19:17:33.442Z,1683919053.442 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-12T19:17:33.443Z,1683919053.443 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0
2023-05-12T19:17:33.443Z,1683919053.443 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7496
2023-05-12T19:17:33.480Z,1683919053.480 [WetLabsUBAT] Loaded
2023-05-12T19:17:33.481Z,1683919053.481 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-12T19:17:33.482Z,1683919053.482 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0
2023-05-12T19:17:33.482Z,1683919053.482 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7497
2023-05-12T19:17:33.483Z,1683919053.483 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-12T19:17:33.489Z,1683919053.489 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-12T19:17:33.491Z,1683919053.491 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-12T19:17:33.502Z,1683919053.502 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-12T19:17:33.503Z,1683919053.503 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0
2023-05-12T19:17:33.504Z,1683919053.504 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7498
2023-05-12T19:17:33.508Z,1683919053.508 [Supervisor](INFO): Main Thread ID is 6926
2023-05-12T19:17:33.508Z,1683919053.508 [Supervisor](DEBUG): Running supervisor.
2023-05-12T19:17:33.509Z,1683919053.509 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7499
2023-05-12T19:17:33.509Z,1683919053.509 [CommandExec](INFO): Initializing the command executive.
2023-05-12T19:17:33.511Z,1683919053.511 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7500
2023-05-12T19:17:33.513Z,1683919053.513 [controlThread ThreadHandler](INFO): Handler Thread ID is 7501
2023-05-12T19:17:33.513Z,1683919053.513 [controlThread](DEBUG): Initializing ControlThread
2023-05-12T19:17:33.514Z,1683919053.514 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-12T19:17:33.516Z,1683919053.516 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-12T19:17:33.517Z,1683919053.517 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-12T19:17:33.517Z,1683919053.517 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-12T19:17:33.518Z,1683919053.518 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-12T19:17:33.518Z,1683919053.518 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-12T19:17:33.518Z,1683919053.518 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-12T19:17:33.519Z,1683919053.519 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-12T19:17:33.519Z,1683919053.519 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-12T19:17:33.524Z,1683919053.524 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-12T19:17:33.525Z,1683919053.525 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-12T19:17:33.525Z,1683919053.525 [SBIT](INFO): Initialize SBIT Component.
2023-05-12T19:17:33.525Z,1683919053.525 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-12T19:17:33.526Z,1683919053.526 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-12T19:17:33.526Z,1683919053.526 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-12T19:17:33.527Z,1683919053.527 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-05-12T19:17:33.528Z,1683919053.528 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-12T19:17:33.529Z,1683919053.529 [IBIT](INFO): Initialize IBIT Component.
2023-05-12T19:17:33.530Z,1683919053.530 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-12T19:17:33.531Z,1683919053.531 [logger ThreadHandler](INFO): Handler Thread ID is 7502
2023-05-12T19:17:33.543Z,1683919053.543 [CBIT](DEBUG): Initialized mux pins.
2023-05-12T19:17:33.543Z,1683919053.543 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-12T19:17:33.552Z,1683919053.552 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7503
2023-05-12T19:17:33.563Z,1683919053.563 [DATMMP ThreadHandler](INFO): Handler Thread ID is 7504
2023-05-12T19:17:33.564Z,1683919053.564 [DATMMP](INFO): Start
2023-05-12T19:17:33.564Z,1683919053.564 [DATMMP](INFO): Powering up
2023-05-12T19:17:33.565Z,1683919053.565 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-12T19:17:33.567Z,1683919053.567 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-12T19:17:33.568Z,1683919053.568 [CBIT](DEBUG): Initializing heartbeat.
2023-05-12T19:17:33.569Z,1683919053.569 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7506
2023-05-12T19:17:33.570Z,1683919053.570 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-12T19:17:33.580Z,1683919053.580 [Onboard ThreadHandler](INFO): Handler Thread ID is 7507
2023-05-12T19:17:33.597Z,1683919053.597 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7508
2023-05-12T19:17:33.616Z,1683919053.616 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7509
2023-05-12T19:17:33.616Z,1683919053.616 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-12T19:17:33.620Z,1683919053.620 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7511
2023-05-12T19:17:33.624Z,1683919053.624 [WetLabsBB2FL](INFO): Powering up
2023-05-12T19:17:33.625Z,1683919053.625 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7513
2023-05-12T19:17:33.628Z,1683919053.628 [WetLabsUBAT](INFO): Powering up
2023-05-12T19:17:33.629Z,1683919053.629 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7515
2023-05-12T19:17:33.632Z,1683919053.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-12T19:17:33.632Z,1683919053.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-12T19:17:33.633Z,1683919053.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-12T19:17:33.633Z,1683919053.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-12T19:17:33.633Z,1683919053.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-12T19:17:33.633Z,1683919053.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-12T19:17:33.633Z,1683919053.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-12T19:17:33.633Z,1683919053.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-12T19:17:33.639Z,1683919053.639 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-12T19:17:33.639Z,1683919053.639 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-12T19:17:33.679Z,1683919053.679 [CBIT](DEBUG): Backplane powered.
2023-05-12T19:17:33.684Z,1683919053.684 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-12T19:17:33.713Z,1683919053.713 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T19:17:33.744Z,1683919053.744 [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-12T19:17:33.745Z,1683919053.745 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-12T19:17:33.815Z,1683919053.815 [DATMMP](INFO): Starting
2023-05-12T19:17:33.831Z,1683919053.832 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-12T19:17:33.833Z,1683919053.833 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-12T19:17:33.850Z,1683919053.850 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T19:17:33.897Z,1683919053.897 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-12T19:17:33.916Z,1683919053.916 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-12T19:17:33.922Z,1683919053.922 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-12T19:17:33.941Z,1683919053.941 [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-12T19:17:33.945Z,1683919053.945 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-12T19:17:33.970Z,1683919053.970 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-12T19:17:34.003Z,1683919054.003 [Radio_Surface](INFO): Powering up
2023-05-12T19:17:34.045Z,1683919054.045 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-12T19:17:34.045Z,1683919054.045 [Power24vConverter](INFO): Powering up.
2023-05-12T19:17:34.046Z,1683919054.046 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-12T19:17:34.051Z,1683919054.051 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-12T19:17:34.067Z,1683919054.067 [DATMMP](INFO): Starting
2023-05-12T19:17:34.077Z,1683919054.077 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-12T19:17:34.087Z,1683919054.087 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-12T19:17:34.089Z,1683919054.089 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-12T19:17:34.095Z,1683919054.095 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-12T19:17:34.097Z,1683919054.096 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-12T19:17:34.116Z,1683919054.116 [MassServo](DEBUG): Initializing MassServo.
2023-05-12T19:17:34.116Z,1683919054.116 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-12T19:17:34.124Z,1683919054.124 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-12T19:17:34.125Z,1683919054.125 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-12T19:17:34.132Z,1683919054.132 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-12T19:17:34.319Z,1683919054.319 [DATMMP](INFO): Starting
2023-05-12T19:17:34.572Z,1683919054.572 [DATMMP](INFO): Starting
2023-05-12T19:17:34.823Z,1683919054.823 [DATMMP](INFO): Starting
2023-05-12T19:17:35.075Z,1683919055.075 [DATMMP](INFO): Starting
2023-05-12T19:17:35.328Z,1683919055.328 [DATMMP](INFO): Starting
2023-05-12T19:17:35.579Z,1683919055.579 [DATMMP](INFO): Starting
2023-05-12T19:17:35.831Z,1683919055.831 [DATMMP](INFO): Starting
2023-05-12T19:17:36.083Z,1683919056.083 [DATMMP](INFO): Starting
2023-05-12T19:17:36.235Z,1683919056.235 [WetLabsBB2FL](INFO): Powering down
2023-05-12T19:17:36.335Z,1683919056.335 [DATMMP](INFO): Starting
2023-05-12T19:17:36.587Z,1683919056.587 [DATMMP](INFO): Starting
2023-05-12T19:17:36.839Z,1683919056.839 [DATMMP](INFO): Starting
2023-05-12T19:17:37.091Z,1683919057.091 [DATMMP](INFO): Starting
2023-05-12T19:17:37.343Z,1683919057.343 [DATMMP](INFO): Starting
2023-05-12T19:17:37.597Z,1683919057.597 [DATMMP](INFO): Starting
2023-05-12T19:17:37.847Z,1683919057.847 [DATMMP](INFO): Starting
2023-05-12T19:17:38.099Z,1683919058.099 [DATMMP](INFO): Starting
2023-05-12T19:17:38.351Z,1683919058.351 [DATMMP](INFO): Starting
2023-05-12T19:17:38.604Z,1683919058.604 [DATMMP](INFO): Starting
2023-05-12T19:17:38.855Z,1683919058.855 [DATMMP](INFO): Starting
2023-05-12T19:17:39.107Z,1683919059.107 [DATMMP](INFO): Starting
2023-05-12T19:17:39.359Z,1683919059.359 [DATMMP](INFO): Starting
2023-05-12T19:17:39.611Z,1683919059.611 [DATMMP](INFO): Starting
2023-05-12T19:17:39.863Z,1683919059.863 [DATMMP](INFO): Starting
2023-05-12T19:17:40.115Z,1683919060.115 [DATMMP](INFO): Starting
2023-05-12T19:17:40.367Z,1683919060.367 [DATMMP](INFO): Starting
2023-05-12T19:17:40.619Z,1683919060.619 [DATMMP](INFO): Starting
2023-05-12T19:17:40.652Z,1683919060.652 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-12T19:17:40.871Z,1683919060.871 [DATMMP](INFO): Starting
2023-05-12T19:17:41.123Z,1683919061.123 [DATMMP](INFO): Starting
2023-05-12T19:17:41.375Z,1683919061.375 [DATMMP](INFO): Starting
2023-05-12T19:17:41.627Z,1683919061.627 [DATMMP](INFO): Starting
2023-05-12T19:17:41.880Z,1683919061.880 [DATMMP](INFO): Starting
2023-05-12T19:17:42.131Z,1683919062.131 [DATMMP](INFO): Starting
2023-05-12T19:17:42.383Z,1683919062.383 [DATMMP](INFO): Starting
2023-05-12T19:17:42.635Z,1683919062.635 [DATMMP](INFO): Starting
2023-05-12T19:17:42.887Z,1683919062.887 [DATMMP](INFO): Starting
2023-05-12T19:17:42.888Z,1683919062.888 [WetLabsUBAT](INFO): Powering down
2023-05-12T19:17:43.139Z,1683919063.139 [DATMMP](INFO): Starting
2023-05-12T19:17:43.391Z,1683919063.391 [DATMMP](INFO): Starting
2023-05-12T19:17:43.643Z,1683919063.643 [DATMMP](INFO): Starting
2023-05-12T19:17:43.895Z,1683919063.895 [DATMMP](INFO): Starting
2023-05-12T19:17:44.147Z,1683919064.147 [DATMMP](INFO): Starting
2023-05-12T19:17:44.399Z,1683919064.399 [DATMMP](INFO): Starting
2023-05-12T19:17:44.651Z,1683919064.651 [DATMMP](INFO): Starting
2023-05-12T19:17:44.904Z,1683919064.904 [DATMMP](INFO): Starting
2023-05-12T19:17:45.155Z,1683919065.155 [DATMMP](INFO): Starting
2023-05-12T19:17:45.407Z,1683919065.407 [DATMMP](INFO): Starting
2023-05-12T19:17:45.659Z,1683919065.659 [DATMMP](INFO): Starting
2023-05-12T19:17:45.660Z,1683919065.660 [DATMMP](INFO): DAT read: 

2023-05-12T19:17:45.661Z,1683919065.661 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-12T19:17:45.911Z,1683919065.911 [DATMMP](INFO): Starting
2023-05-12T19:17:46.163Z,1683919066.163 [DATMMP](INFO): Starting
2023-05-12T19:17:46.415Z,1683919066.415 [DATMMP](INFO): Starting
2023-05-12T19:17:46.667Z,1683919066.667 [DATMMP](INFO): Starting
2023-05-12T19:17:46.919Z,1683919066.919 [DATMMP](INFO): Starting
2023-05-12T19:17:47.171Z,1683919067.171 [DATMMP](INFO): Starting
2023-05-12T19:17:47.424Z,1683919067.424 [DATMMP](INFO): Starting
2023-05-12T19:17:47.424Z,1683919067.424 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-12T19:17:47.426Z,1683919067.426 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-12T19:17:47.426Z,1683919067.426 [DATMMP](INFO): DAT read: Apr 12 2000 19:17:41 

2023-05-12T19:17:47.675Z,1683919067.675 [DATMMP](INFO): Starting
2023-05-12T19:17:47.927Z,1683919067.927 [DATMMP](INFO): Starting
2023-05-12T19:17:48.179Z,1683919068.179 [DATMMP](INFO): Starting
2023-05-12T19:17:48.431Z,1683919068.431 [DATMMP](INFO): Starting
2023-05-12T19:17:48.432Z,1683919068.432 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-12T19:17:48.434Z,1683919068.434 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-12T19:17:48.434Z,1683919068.434 [DATMMP](INFO): commRate: 800
2023-05-12T19:17:48.683Z,1683919068.683 [DATMMP](INFO): Starting
2023-05-12T19:17:48.935Z,1683919068.935 [DATMMP](INFO): Starting
2023-05-12T19:17:49.187Z,1683919069.187 [DATMMP](INFO): Starting
2023-05-12T19:17:49.439Z,1683919069.439 [DATMMP](INFO): Starting
2023-05-12T19:17:49.691Z,1683919069.691 [DATMMP](INFO): Starting
2023-05-12T19:17:49.944Z,1683919069.944 [DATMMP](INFO): Starting
2023-05-12T19:17:50.195Z,1683919070.195 [DATMMP](INFO): Starting
2023-05-12T19:17:50.447Z,1683919070.447 [DATMMP](INFO): Starting
2023-05-12T19:17:50.499Z,1683919070.499 [DATMMP](INFO): entering command mode
2023-05-12T19:17:50.699Z,1683919070.699 [DATMMP](INFO): Starting
2023-05-12T19:17:50.700Z,1683919070.700 [DATMMP](INFO): DAT read: 

2023-05-12T19:17:50.700Z,1683919070.700 [DATMMP](INFO): DAT read: user:1>

2023-05-12T19:17:50.700Z,1683919070.700 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-12T19:17:50.701Z,1683919070.701 [DATMMP](INFO): entering MMP mode
2023-05-12T19:17:50.952Z,1683919070.952 [DATMMP](INFO): Starting
2023-05-12T19:17:50.953Z,1683919070.953 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:17:50.955Z,1683919070.955 [DATMMP](INFO): status rx: 	 x1
2023-05-12T19:17:50.955Z,1683919070.955 [DATMMP](INFO): MMP status: 1: started
2023-05-12T19:17:50.956Z,1683919070.956 [DATMMP](INFO): Received message type: status
2023-05-12T19:17:50.956Z,1683919070.956 [DATMMP](INFO): Handled
2023-05-12T19:17:51.203Z,1683919071.203 [DATMMP](INFO): Starting
2023-05-12T19:17:51.205Z,1683919071.205 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-12T19:17:51.205Z,1683919071.205 [DATMMP](INFO): Setting local address: 10
2023-05-12T19:17:51.209Z,1683919071.209 [DATMMP](INFO): Setting time to: 19:17:51 And date to:5/12/2023
2023-05-12T19:17:51.455Z,1683919071.455 [DATMMP](INFO): Starting
2023-05-12T19:17:51.456Z,1683919071.456 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:17:51.457Z,1683919071.457 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-12T19:17:51.457Z,1683919071.457 [DATMMP](INFO): Received message type: mdmpower
2023-05-12T19:17:51.457Z,1683919071.457 [DATMMP](INFO): Handled
2023-05-12T19:17:51.707Z,1683919071.707 [DATMMP](INFO): Starting
2023-05-12T19:17:51.708Z,1683919071.708 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-12T19:17:51.708Z,1683919071.708 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-12T19:17:51.708Z,1683919071.708 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-12T19:17:51.709Z,1683919071.709 [DATMMP](INFO): Match for : sreg_txpower
2023-05-12T19:17:51.709Z,1683919071.709 [DATMMP](INFO): ACK
2023-05-12T19:17:51.959Z,1683919071.959 [DATMMP](INFO): Starting
2023-05-12T19:17:51.960Z,1683919071.960 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:17:51.961Z,1683919071.961 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-12T19:17:51.961Z,1683919071.961 [DATMMP](INFO): Received message type: mdmlocal
2023-05-12T19:17:51.961Z,1683919071.961 [DATMMP](INFO): Handled
2023-05-12T19:17:52.211Z,1683919072.211 [DATMMP](INFO): Starting
2023-05-12T19:17:52.212Z,1683919072.212 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-12T19:17:52.212Z,1683919072.212 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-12T19:17:52.213Z,1683919072.213 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-12T19:17:52.213Z,1683919072.213 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-12T19:17:52.213Z,1683919072.213 [DATMMP](INFO): ACK
2023-05-12T19:17:52.463Z,1683919072.463 [DATMMP](INFO): Starting
2023-05-12T19:17:52.464Z,1683919072.464 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-12T19:17:52.465Z,1683919072.465 [DATMMP](INFO): timedate rx: 	 x0	 x13	 x11	 x33	 x4	 xC	 x7B	 x0
2023-05-12T19:17:52.465Z,1683919072.465 [DATMMP](INFO): Received message type: timedate
2023-05-12T19:17:52.465Z,1683919072.465 [DATMMP](INFO): Match for : timedate
2023-05-12T19:17:52.466Z,1683919072.466 [DATMMP](INFO): ACK
2023-05-12T19:17:52.715Z,1683919072.715 [DATMMP](INFO): Starting
2023-05-12T19:17:52.716Z,1683919072.716 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:17:52.717Z,1683919072.717 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-12T19:17:52.717Z,1683919072.717 [DATMMP](INFO): Received message type: sys_verb
2023-05-12T19:17:52.717Z,1683919072.717 [DATMMP](INFO): Handled
2023-05-12T19:17:52.967Z,1683919072.967 [DATMMP](INFO): Starting
2023-05-12T19:17:52.968Z,1683919072.968 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-12T19:17:52.968Z,1683919072.968 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-12T19:17:52.968Z,1683919072.968 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-12T19:17:52.969Z,1683919072.969 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-12T19:17:52.969Z,1683919072.969 [DATMMP](INFO): ACK
2023-05-12T19:17:53.219Z,1683919073.219 [DATMMP](INFO): Starting
2023-05-12T19:17:53.220Z,1683919073.220 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-12T19:17:53.220Z,1683919073.220 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-12T19:17:53.221Z,1683919073.221 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-12T19:17:53.221Z,1683919073.221 [DATMMP](INFO): Received message type: version
2023-05-12T19:17:53.221Z,1683919073.221 [DATMMP](INFO): Match for : version
2023-05-12T19:17:53.221Z,1683919073.221 [DATMMP](INFO): ACK
2023-05-12T19:17:53.221Z,1683919073.221 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-12T19:18:02.236Z,1683919082.236 [NAL9602](INFO): Powering up NAL9602
2023-05-12T19:18:06.576Z,1683919086.576 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:18:06.577Z,1683919086.577 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T19:18:06.577Z,1683919086.577 [DATMMP](INFO): Received message type: timesync
2023-05-12T19:18:06.577Z,1683919086.577 [DATMMP](INFO): Handled
2023-05-12T19:18:13.145Z,1683919093.145 [NAL9602](INFO): NAL9602 initialized
2023-05-12T19:18:13.518Z,1683919093.518 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-12T19:18:13.602Z,1683919093.602 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-12T19:18:13.603Z,1683919093.603 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-12T19:18:13.650Z,1683919093.650 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-12T19:18:13.650Z,1683919093.650 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-12T19:18:13.676Z,1683919093.676 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-12T19:18:13.676Z,1683919093.676 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-12T19:18:13.677Z,1683919093.677 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-12T19:18:13.760Z,1683919093.760 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,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,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-12T19:18:16.404Z,1683919096.404 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:18:16.405Z,1683919096.405 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T19:18:16.405Z,1683919096.405 [DATMMP](INFO): Received message type: timesync
2023-05-12T19:18:16.405Z,1683919096.405 [DATMMP](INFO): Handled
2023-05-12T19:18:25.305Z,1683919105.305 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-12T19:18:25.314Z,1683919105.314 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-12T19:18:30.559Z,1683919110.559 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683919096 second
2023-05-12T19:18:36.232Z,1683919116.232 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001431
CHAN A1 (24V): -0.000238
CHAN A2 (12V): -0.007438
CHAN A3 (5V): -0.003541
CHAN B0 (3.3V): -0.000181
CHAN B1 (3.15aV): 0.000017
CHAN B2 (3.15bV): 0.000236
CHAN B3 (GND): 0.002018
OPEN: 0.004933
Full Scale: +/- 1 mA
2023-05-12T19:19:19.024Z,1683919159.024 [SBIT](IMPORTANT): SBIT PASSED
2023-05-12T19:19:19.024Z,1683919159.024 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-12T19:19:19.025Z,1683919159.025 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-12T19:19:19.025Z,1683919159.025 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-12T19:19:19.025Z,1683919159.025 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-12T19:19:19.026Z,1683919159.026 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-12T19:19:19.026Z,1683919159.026 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-12T19:19:19.026Z,1683919159.026 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-12T19:19:19.026Z,1683919159.026 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-12T19:19:19.425Z,1683919159.425 [MissionManager](IMPORTANT): Started mission Startup
2023-05-12T19:19:19.425Z,1683919159.425 [Startup] Running Loop=1
2023-05-12T19:19:19.425Z,1683919159.425 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-12T19:19:19.425Z,1683919159.425 [Startup:A.GoToSurface] Running Loop=1
2023-05-12T19:19:19.425Z,1683919159.425 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T19:19:19.426Z,1683919159.426 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T19:19:19.426Z,1683919159.426 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T19:19:19.426Z,1683919159.427 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T19:19:19.427Z,1683919159.427 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T19:19:19.428Z,1683919159.428 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T19:19:19.429Z,1683919159.429 [Startup:StartupSatComms] Running Loop=1
2023-05-12T19:19:19.429Z,1683919159.429 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-12T19:19:19.429Z,1683919159.429 [Startup:StartupSatComms:A] Running Loop=1
2023-05-12T19:19:19.845Z,1683919159.845 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-12T19:19:19.845Z,1683919159.845 [Startup:StartupSatComms:A] Stopped
2023-05-12T19:19:19.845Z,1683919159.845 [Startup:StartupSatComms:B] Running Loop=1
2023-05-12T19:19:20.305Z,1683919160.305 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T19:19:53.242Z,1683919193.242 [CommandExec](IMPORTANT): got command get depth
2023-05-12T19:19:53.243Z,1683919193.243 [CommandExec](IMPORTANT): depth 0.800000 m
2023-05-12T19:20:01.757Z,1683919201.757 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004831
2023-05-12T19:20:07.924Z,1683919207.924 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:08.110Z,1683919208.110 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:20:08.111Z,1683919208.111 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:20:08.111Z,1683919208.111 [DATMMP](INFO): Handled
2023-05-12T19:20:08.176Z,1683919208.176 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:08.177Z,1683919208.177 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x14	 x6	 x8AE
2023-05-12T19:20:08.178Z,1683919208.178 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919227.733184
2023-05-12T19:20:08.178Z,1683919208.178 [DATMMP](INFO): Rx ping set to:1683919208.176920
2023-05-12T19:20:08.178Z,1683919208.178 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:08.178Z,1683919208.178 [DATMMP](INFO): Handled
2023-05-12T19:20:08.428Z,1683919208.428 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:08.429Z,1683919208.429 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:20:08.429Z,1683919208.429 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:20:08.429Z,1683919208.429 [DATMMP](INFO): Handled
2023-05-12T19:20:08.680Z,1683919208.680 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:08.681Z,1683919208.681 [DATMMP](INFO): directional_debug rx: 	 x18A	 xFC	 x21	 x1E8	 x2AE0	 x42C1	 x6B52	 x7FD3
2023-05-12T19:20:08.683Z,1683919208.683 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:20:08.683Z,1683919208.683 [DATMMP](INFO): Handled
2023-05-12T19:20:08.932Z,1683919208.932 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:08.934Z,1683919208.934 [DATMMP](INFO): directional rx: 	 x1AC	 xEC	 x452	 xFF04	 xB06	 xFF04	 x0	 x6B5	 x1	 x0	 x27B5966	 x-43569E0
2023-05-12T19:20:08.936Z,1683919208.936 [DATMMP](INFO): Received message type: directional
2023-05-12T19:20:08.936Z,1683919208.936 [DATMMP](INFO): Handled
2023-05-12T19:20:09.184Z,1683919209.184 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:09.185Z,1683919209.185 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:20:09.186Z,1683919209.186 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:20:09.186Z,1683919209.186 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:20:09.186Z,1683919209.186 [DATMMP](INFO): Handled
2023-05-12T19:20:09.436Z,1683919209.436 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:09.438Z,1683919209.438 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x78	 xAC	 xAB	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T19:20:09.438Z,1683919209.438 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:20:09.438Z,1683919209.438 [DATMMP](INFO): Handled
2023-05-12T19:20:09.688Z,1683919209.688 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:09.689Z,1683919209.689 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x14	 x7	 x203A
2023-05-12T19:20:09.689Z,1683919209.689 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:09.689Z,1683919209.689 [DATMMP](INFO): Handled
2023-05-12T19:20:09.811Z,1683919209.811 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T184208/Courier0007.lzma
2023-05-12T19:20:10.813Z,1683919210.813 [DataOverHttps](INFO): Moved sent file to Logs/20230512T184208/Courier0007.lzma.bak
2023-05-12T19:20:10.813Z,1683919210.813 [DataOverHttps](INFO): SBD MOMSN=18300497
2023-05-12T19:20:20.041Z,1683919220.041 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T19:19:19.8Z
2023-05-12T19:20:20.041Z,1683919220.041 [Startup:StartupSatComms:B] Stopped
2023-05-12T19:20:20.041Z,1683919220.041 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-12T19:20:20.041Z,1683919220.041 [Startup:StartupSatComms] Stopped
2023-05-12T19:20:20.041Z,1683919220.041 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-12T19:20:20.042Z,1683919220.042 [Startup](INFO): Completed Startup
2023-05-12T19:20:20.042Z,1683919220.042 [MissionManager](INFO): Startup is completed.
2023-05-12T19:20:20.042Z,1683919220.042 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-12T19:20:20.042Z,1683919220.042 [Startup] Stopped
2023-05-12T19:20:20.042Z,1683919220.042 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-12T19:20:20.042Z,1683919220.042 [Startup:A.GoToSurface] Stopped
2023-05-12T19:20:20.043Z,1683919220.043 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T19:20:20.433Z,1683919220.433 [MissionManager](IMPORTANT): Started mission Default
2023-05-12T19:20:20.433Z,1683919220.433 [Default] Running Loop=1
2023-05-12T19:20:20.433Z,1683919220.433 [Default](DEBUG): Aggregate::initialize Default
2023-05-12T19:20:20.433Z,1683919220.433 [Default:B.GoToSurface] Running Loop=1
2023-05-12T19:20:20.433Z,1683919220.433 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T19:20:20.434Z,1683919220.434 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T19:20:20.434Z,1683919220.434 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T19:20:20.434Z,1683919220.434 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T19:20:20.435Z,1683919220.435 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T19:20:20.435Z,1683919220.435 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T19:20:20.435Z,1683919220.435 [Default:A.Wait] Running Loop=1
2023-05-12T19:20:20.435Z,1683919220.435 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-12T19:20:23.296Z,1683919223.296 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:23.296Z,1683919223.296 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:20:23.297Z,1683919223.297 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:20:23.297Z,1683919223.297 [DATMMP](INFO): Handled
2023-05-12T19:20:23.549Z,1683919223.549 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:23.553Z,1683919223.553 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x14	 x15	 x1659
2023-05-12T19:20:23.554Z,1683919223.554 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919276.911360
2023-05-12T19:20:23.556Z,1683919223.556 [DATMMP](INFO): Rx ping set to:1683919223.552707
2023-05-12T19:20:23.556Z,1683919223.556 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:23.556Z,1683919223.556 [DATMMP](INFO): Handled
2023-05-12T19:20:23.800Z,1683919223.800 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:23.801Z,1683919223.801 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:20:23.801Z,1683919223.801 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:20:23.801Z,1683919223.801 [DATMMP](INFO): Handled
2023-05-12T19:20:24.052Z,1683919224.052 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:24.053Z,1683919224.053 [DATMMP](INFO): directional_debug rx: 	 x198	 x106	 x23	 x1E9	 x21F0	 x2621	 x2DE2	 x4423
2023-05-12T19:20:24.054Z,1683919224.054 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:20:24.054Z,1683919224.054 [DATMMP](INFO): Handled
2023-05-12T19:20:24.304Z,1683919224.304 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:24.306Z,1683919224.306 [DATMMP](INFO): directional rx: 	 x1BB	 xF4	 x444	 xFEFA	 xAF7	 xFEF9	 x0	 x6B5	 x3	 x0	 x27B5966	 x-43569E0
2023-05-12T19:20:24.308Z,1683919224.308 [DATMMP](INFO): Received message type: directional
2023-05-12T19:20:24.308Z,1683919224.308 [DATMMP](INFO): Handled
2023-05-12T19:20:24.556Z,1683919224.556 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:24.557Z,1683919224.557 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:20:24.557Z,1683919224.557 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:20:24.558Z,1683919224.558 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:20:24.558Z,1683919224.558 [DATMMP](INFO): Handled
2023-05-12T19:20:24.808Z,1683919224.808 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:24.810Z,1683919224.810 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x8A	 xAF	 xA9	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T19:20:24.810Z,1683919224.810 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:20:24.810Z,1683919224.810 [DATMMP](INFO): Handled
2023-05-12T19:20:25.060Z,1683919225.060 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:25.061Z,1683919225.061 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x14	 x17	 x6D6
2023-05-12T19:20:25.061Z,1683919225.061 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:25.062Z,1683919225.062 [DATMMP](INFO): Handled
2023-05-12T19:20:27.565Z,1683919227.565 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230512T191723/Courier0000.lzma
2023-05-12T19:20:28.562Z,1683919228.562 [DataOverHttps](INFO): Moved sent file to Logs/20230512T191723/Courier0000.lzma.bak
2023-05-12T19:20:28.562Z,1683919228.562 [DataOverHttps](INFO): SBD MOMSN=18300499
2023-05-12T19:20:33.807Z,1683919233.807 [Default:A.Wait](INFO): Done Waiting.
2023-05-12T19:20:33.807Z,1683919233.807 [Default:A.Wait] Stopped
2023-05-12T19:20:33.807Z,1683919233.807 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T19:20:34.175Z,1683919234.175 [Default:CheckIn] Running Loop=1
2023-05-12T19:20:34.175Z,1683919234.175 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T19:20:34.175Z,1683919234.175 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T19:20:34.596Z,1683919234.596 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-12T19:20:38.696Z,1683919238.696 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:38.696Z,1683919238.696 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:20:38.697Z,1683919238.697 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:20:38.697Z,1683919238.697 [DATMMP](INFO): Handled
2023-05-12T19:20:38.948Z,1683919238.948 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:38.949Z,1683919238.949 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x14	 x24	 x25F8
2023-05-12T19:20:38.949Z,1683919238.949 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919266.966656
2023-05-12T19:20:38.950Z,1683919238.950 [DATMMP](INFO): Rx ping set to:1683919238.948841
2023-05-12T19:20:38.950Z,1683919238.950 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:38.950Z,1683919238.950 [DATMMP](INFO): Handled
2023-05-12T19:20:39.200Z,1683919239.200 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:39.201Z,1683919239.201 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:20:39.201Z,1683919239.201 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:20:39.201Z,1683919239.201 [DATMMP](INFO): Handled
2023-05-12T19:20:39.452Z,1683919239.452 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:39.453Z,1683919239.453 [DATMMP](INFO): directional_debug rx: 	 x193	 x101	 x20	 x1E6	 x2A70	 x4141	 x6E52	 x7FF3
2023-05-12T19:20:39.454Z,1683919239.454 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:20:39.454Z,1683919239.454 [DATMMP](INFO): Handled
2023-05-12T19:20:39.704Z,1683919239.704 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:39.706Z,1683919239.706 [DATMMP](INFO): directional rx: 	 x1B6	 xF0	 x449	 xFEFF	 xAFB	 xFEFD	 x0	 x6B4	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T19:20:39.708Z,1683919239.708 [DATMMP](INFO): Received message type: directional
2023-05-12T19:20:39.708Z,1683919239.708 [DATMMP](INFO): Handled
2023-05-12T19:20:39.956Z,1683919239.956 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:39.957Z,1683919239.957 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:20:39.958Z,1683919239.958 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:20:39.958Z,1683919239.958 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:20:39.958Z,1683919239.958 [DATMMP](INFO): Handled
2023-05-12T19:20:40.212Z,1683919240.212 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:40.214Z,1683919240.214 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x1C8	 x22	 x7A	 xAF	 xAC	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T19:20:40.214Z,1683919240.214 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:20:40.214Z,1683919240.214 [DATMMP](INFO): Handled
2023-05-12T19:20:40.464Z,1683919240.464 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:40.465Z,1683919240.465 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x14	 x26	 x1675
2023-05-12T19:20:40.465Z,1683919240.465 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:40.465Z,1683919240.465 [DATMMP](INFO): Handled
2023-05-12T19:20:53.848Z,1683919253.848 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:53.848Z,1683919253.848 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:20:53.848Z,1683919253.848 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:20:53.849Z,1683919253.849 [DATMMP](INFO): Handled
2023-05-12T19:20:54.100Z,1683919254.100 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:54.101Z,1683919254.101 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x14	 x34	 x4B8
2023-05-12T19:20:54.101Z,1683919254.101 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919263.835584
2023-05-12T19:20:54.102Z,1683919254.102 [DATMMP](INFO): Rx ping set to:1683919254.100870
2023-05-12T19:20:54.102Z,1683919254.102 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:54.102Z,1683919254.102 [DATMMP](INFO): Handled
2023-05-12T19:20:54.352Z,1683919254.352 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:54.353Z,1683919254.353 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x22	 x0	 xA	 xC	 x0
2023-05-12T19:20:54.353Z,1683919254.353 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:20:54.354Z,1683919254.354 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:20:54.354Z,1683919254.354 [DATMMP](INFO): Handled
2023-05-12T19:20:54.856Z,1683919254.856 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:54.857Z,1683919254.857 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:20:54.857Z,1683919254.857 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:20:54.857Z,1683919254.857 [DATMMP](INFO): Handled
2023-05-12T19:20:55.108Z,1683919255.108 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:55.109Z,1683919255.109 [DATMMP](INFO): directional_debug rx: 	 x18D	 xF5	 x20	 x1C7	 x2A70	 x4031	 x6C72	 x7FF3
2023-05-12T19:20:55.110Z,1683919255.110 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:20:55.110Z,1683919255.110 [DATMMP](INFO): Handled
2023-05-12T19:20:55.360Z,1683919255.360 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:55.362Z,1683919255.362 [DATMMP](INFO): directional rx: 	 x1B0	 xE6	 x44F	 xFF0B	 xB02	 xFF0C	 x0	 x6B4	 x3	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T19:20:55.363Z,1683919255.363 [DATMMP](INFO): Received message type: directional
2023-05-12T19:20:55.363Z,1683919255.363 [DATMMP](INFO): Handled
2023-05-12T19:20:55.612Z,1683919255.612 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:55.614Z,1683919255.614 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x23	 x7B	 xB7	 xBD	 xD	 xFFFF	 x9C	 x1D	 xB	 x0
2023-05-12T19:20:55.614Z,1683919255.614 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:20:55.614Z,1683919255.614 [DATMMP](INFO): Handled
2023-05-12T19:20:55.864Z,1683919255.864 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:55.865Z,1683919255.865 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x22
2023-05-12T19:20:55.865Z,1683919255.865 [DATMMP](INFO): Check CRC
2023-05-12T19:20:55.865Z,1683919255.865 [DATMMP](INFO): data rx parseDataResponse 34
2023-05-12T19:20:55.865Z,1683919255.865 [DATMMP](INFO): Got DATA: len 34
2023-05-12T19:20:55.866Z,1683919255.866 [DATMMP](INFO): Received command: set _.pressure 0.742117 atmosphere
2023-05-12T19:20:55.866Z,1683919255.866 [DATMMP](INFO): #Outgoing data=1
2023-05-12T19:20:55.866Z,1683919255.866 [DATMMP](INFO): Sending ack
2023-05-12T19:20:55.866Z,1683919255.866 [DATMMP](INFO): Received message type: data_rx
2023-05-12T19:20:55.866Z,1683919255.866 [DATMMP](INFO): Handled
2023-05-12T19:20:55.867Z,1683919255.867 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T19:20:55.867Z,1683919255.867 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T19:20:56.116Z,1683919256.116 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:56.116Z,1683919256.116 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:20:56.117Z,1683919256.117 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:20:56.117Z,1683919256.117 [DATMMP](INFO): Handled
2023-05-12T19:20:56.117Z,1683919256.117 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T19:20:56.117Z,1683919256.117 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T19:20:56.368Z,1683919256.368 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:56.369Z,1683919256.369 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x14	 x35	 x1F8F
2023-05-12T19:20:56.369Z,1683919256.369 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919267.940032
2023-05-12T19:20:56.370Z,1683919256.370 [DATMMP](INFO): Rx ping set to:1683919256.368820
2023-05-12T19:20:56.370Z,1683919256.370 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:56.370Z,1683919256.370 [DATMMP](INFO): Handled
2023-05-12T19:20:56.370Z,1683919256.370 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T19:20:56.371Z,1683919256.371 [DATMMP](INFO): Tx to 12 len 4 xid 15
2023-05-12T19:20:56.371Z,1683919256.371 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T19:20:56.372Z,1683919256.372 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:20:56.620Z,1683919256.620 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:56.621Z,1683919256.621 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:20:56.621Z,1683919256.621 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:20:56.621Z,1683919256.621 [DATMMP](INFO): Handled
2023-05-12T19:20:56.621Z,1683919256.621 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:20:56.872Z,1683919256.872 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:56.873Z,1683919256.873 [DATMMP](INFO): directional_debug rx: 	 x184	 x101	 x23	 x1EE	 x3170	 x3AF1	 x6372	 x6113
2023-05-12T19:20:56.874Z,1683919256.874 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:20:56.874Z,1683919256.874 [DATMMP](INFO): Handled
2023-05-12T19:20:56.875Z,1683919256.875 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:20:57.124Z,1683919257.124 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:57.126Z,1683919257.126 [DATMMP](INFO): directional rx: 	 x1A5	 xF0	 x458	 xFEFF	 xB0A	 xFEFF	 x0	 x6B4	 x3	 xFFFE	 x27B5966	 x-43569E0
2023-05-12T19:20:57.128Z,1683919257.128 [DATMMP](INFO): Received message type: directional
2023-05-12T19:20:57.128Z,1683919257.128 [DATMMP](INFO): Handled
2023-05-12T19:20:57.128Z,1683919257.128 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:20:57.264Z,1683919257.264 [CommandExec](IMPORTANT): got command set _.pressure 0.742117 atmosphere
2023-05-12T19:20:57.376Z,1683919257.376 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:57.377Z,1683919257.377 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:20:57.377Z,1683919257.377 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:20:57.378Z,1683919257.378 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:20:57.378Z,1683919257.378 [DATMMP](INFO): Handled
2023-05-12T19:20:57.378Z,1683919257.378 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:20:57.628Z,1683919257.628 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:57.630Z,1683919257.630 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x81	 xAF	 xAA	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T19:20:57.630Z,1683919257.630 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:20:57.630Z,1683919257.630 [DATMMP](INFO): Handled
2023-05-12T19:20:57.883Z,1683919257.883 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:57.884Z,1683919257.884 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x14	 x37	 x100B
2023-05-12T19:20:57.884Z,1683919257.884 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:57.885Z,1683919257.885 [DATMMP](INFO): Handled
2023-05-12T19:20:57.885Z,1683919257.885 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:20:58.136Z,1683919258.136 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-12T19:20:58.137Z,1683919258.137 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T19:20:58.137Z,1683919258.137 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T19:20:58.137Z,1683919258.137 [DATMMP](DEBUG): Command complete send
2023-05-12T19:20:58.138Z,1683919258.138 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T19:20:58.138Z,1683919258.138 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T19:20:58.138Z,1683919258.138 [DATMMP](INFO): Handled
2023-05-12T19:20:58.138Z,1683919258.138 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T19:20:58.138Z,1683919258.138 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T19:20:58.388Z,1683919258.388 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:20:58.389Z,1683919258.389 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x14	 x37	 xEBA
2023-05-12T19:20:58.389Z,1683919258.389 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:20:58.389Z,1683919258.389 [DATMMP](INFO): Handled
2023-05-12T19:21:03.182Z,1683919263.182 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:03.183Z,1683919263.183 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:21:03.183Z,1683919263.183 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:21:03.183Z,1683919263.183 [DATMMP](INFO): Handled
2023-05-12T19:21:03.436Z,1683919263.436 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:03.437Z,1683919263.437 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x15	 x1	 x1263
2023-05-12T19:21:03.437Z,1683919263.437 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919307.013760
2023-05-12T19:21:03.438Z,1683919263.438 [DATMMP](INFO): Rx ping set to:1683919263.436812
2023-05-12T19:21:03.438Z,1683919263.438 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:03.438Z,1683919263.438 [DATMMP](INFO): Handled
2023-05-12T19:21:03.688Z,1683919263.688 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:03.689Z,1683919263.689 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x23	 x0	 xA	 xC	 x0
2023-05-12T19:21:03.690Z,1683919263.690 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:21:03.690Z,1683919263.690 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:21:03.690Z,1683919263.690 [DATMMP](INFO): Handled
2023-05-12T19:21:04.192Z,1683919264.192 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:04.193Z,1683919264.193 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:21:04.193Z,1683919264.193 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:21:04.193Z,1683919264.193 [DATMMP](INFO): Handled
2023-05-12T19:21:04.452Z,1683919264.452 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:04.453Z,1683919264.453 [DATMMP](INFO): directional_debug rx: 	 x192	 x109	 x22	 x1F6	 x3190	 x4331	 x6F72	 x7FF3
2023-05-12T19:21:04.454Z,1683919264.454 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:21:04.454Z,1683919264.454 [DATMMP](INFO): Handled
2023-05-12T19:21:04.704Z,1683919264.704 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:04.706Z,1683919264.706 [DATMMP](INFO): directional rx: 	 x1B4	 xF6	 x44A	 xFEF7	 xAFE	 xFEF8	 x0	 x6B4	 x1	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T19:21:04.708Z,1683919264.708 [DATMMP](INFO): Received message type: directional
2023-05-12T19:21:04.708Z,1683919264.708 [DATMMP](INFO): Handled
2023-05-12T19:21:04.956Z,1683919264.956 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:04.958Z,1683919264.958 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x23	 x7D	 xA9	 xBD	 xD	 xFFFF	 xA5	 x22	 xB	 x0
2023-05-12T19:21:04.958Z,1683919264.958 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:21:04.958Z,1683919264.958 [DATMMP](INFO): Handled
2023-05-12T19:21:05.208Z,1683919265.208 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:05.209Z,1683919265.209 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x23
2023-05-12T19:21:05.209Z,1683919265.209 [DATMMP](INFO): Check CRC
2023-05-12T19:21:05.210Z,1683919265.210 [DATMMP](INFO): data rx parseDataResponse 35
2023-05-12T19:21:05.210Z,1683919265.210 [DATMMP](INFO): Got DATA: len 35
2023-05-12T19:21:05.210Z,1683919265.210 [DATMMP](INFO): Received command: set _.temperature 24.219263 celsius
2023-05-12T19:21:05.210Z,1683919265.210 [DATMMP](INFO): #Outgoing data=1
2023-05-12T19:21:05.211Z,1683919265.211 [DATMMP](INFO): Sending ack
2023-05-12T19:21:05.211Z,1683919265.211 [DATMMP](INFO): Received message type: data_rx
2023-05-12T19:21:05.211Z,1683919265.211 [DATMMP](INFO): Handled
2023-05-12T19:21:05.211Z,1683919265.211 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T19:21:05.211Z,1683919265.211 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T19:21:05.459Z,1683919265.459 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T19:21:05.460Z,1683919265.460 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T19:21:05.964Z,1683919265.964 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T19:21:05.964Z,1683919265.964 [DATMMP](INFO): Tx to 12 len 4 xid 16
2023-05-12T19:21:05.965Z,1683919265.965 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T19:21:05.965Z,1683919265.965 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:21:06.216Z,1683919266.216 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-12T19:21:06.217Z,1683919266.217 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T19:21:06.217Z,1683919266.217 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T19:21:06.217Z,1683919266.217 [DATMMP](DEBUG): Command complete send
2023-05-12T19:21:06.217Z,1683919266.217 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T19:21:06.217Z,1683919266.217 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T19:21:06.218Z,1683919266.218 [DATMMP](INFO): Handled
2023-05-12T19:21:06.218Z,1683919266.218 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T19:21:06.218Z,1683919266.218 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T19:21:06.468Z,1683919266.468 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:06.469Z,1683919266.469 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x15	 x4	 x262C
2023-05-12T19:21:06.469Z,1683919266.469 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:06.469Z,1683919266.469 [DATMMP](INFO): Handled
2023-05-12T19:21:07.875Z,1683919267.875 [CommandExec](IMPORTANT): got command set _.temperature 24.219263 celsius
2023-05-12T19:21:09.252Z,1683919269.252 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:09.253Z,1683919269.253 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:21:09.253Z,1683919269.253 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:21:09.253Z,1683919269.253 [DATMMP](INFO): Handled
2023-05-12T19:21:09.504Z,1683919269.504 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:09.505Z,1683919269.505 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x15	 x7	 x1C32
2023-05-12T19:21:09.506Z,1683919269.506 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919273.512000
2023-05-12T19:21:09.506Z,1683919269.506 [DATMMP](INFO): Rx ping set to:1683919269.505010
2023-05-12T19:21:09.507Z,1683919269.507 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:09.507Z,1683919269.507 [DATMMP](INFO): Handled
2023-05-12T19:21:10.008Z,1683919270.008 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:10.009Z,1683919270.009 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:21:10.009Z,1683919270.009 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:21:10.009Z,1683919270.009 [DATMMP](INFO): Handled
2023-05-12T19:21:10.260Z,1683919270.260 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:10.261Z,1683919270.261 [DATMMP](INFO): directional_debug rx: 	 x1BB	 xD0	 x20	 x174	 x36E0	 x4191	 x6FF2	 x7FF3
2023-05-12T19:21:10.262Z,1683919270.262 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:21:10.262Z,1683919270.262 [DATMMP](INFO): Handled
2023-05-12T19:21:10.517Z,1683919270.517 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:10.519Z,1683919270.519 [DATMMP](INFO): directional rx: 	 x1E8	 xC8	 x421	 xFF30	 xAD5	 xFF30	 x0	 x6B5	 x1	 x0	 x27B5966	 x-43569E0
2023-05-12T19:21:10.521Z,1683919270.521 [DATMMP](INFO): Received message type: directional
2023-05-12T19:21:10.521Z,1683919270.521 [DATMMP](INFO): Handled
2023-05-12T19:21:10.768Z,1683919270.768 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:10.769Z,1683919270.769 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:21:10.769Z,1683919270.769 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:21:10.770Z,1683919270.770 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:21:10.770Z,1683919270.770 [DATMMP](INFO): Handled
2023-05-12T19:21:11.020Z,1683919271.020 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:11.022Z,1683919271.022 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7B	 xAE	 xAA	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T19:21:11.022Z,1683919271.022 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:21:11.022Z,1683919271.022 [DATMMP](INFO): Handled
2023-05-12T19:21:11.272Z,1683919271.272 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:11.273Z,1683919271.273 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x15	 x9	 xCAF
2023-05-12T19:21:11.273Z,1683919271.273 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:11.273Z,1683919271.273 [DATMMP](INFO): Handled
2023-05-12T19:21:21.624Z,1683919281.624 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:21.625Z,1683919281.625 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T19:21:21.625Z,1683919281.625 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:21:21.625Z,1683919281.625 [DATMMP](INFO): Handled
2023-05-12T19:21:21.876Z,1683919281.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:21.877Z,1683919281.877 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x15	 x14	 x4B6
2023-05-12T19:21:21.877Z,1683919281.877 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919291.815104
2023-05-12T19:21:21.878Z,1683919281.878 [DATMMP](INFO): Rx ping set to:1683919281.876731
2023-05-12T19:21:21.878Z,1683919281.878 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:21.878Z,1683919281.878 [DATMMP](INFO): Handled
2023-05-12T19:21:22.380Z,1683919282.380 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:22.381Z,1683919282.381 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x20	 x0	 xA	 xC	 x0
2023-05-12T19:21:22.381Z,1683919282.381 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:21:22.382Z,1683919282.382 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:21:22.382Z,1683919282.382 [DATMMP](INFO): Handled
2023-05-12T19:21:22.884Z,1683919282.884 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:22.885Z,1683919282.885 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:21:22.885Z,1683919282.885 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:21:22.885Z,1683919282.885 [DATMMP](INFO): Handled
2023-05-12T19:21:23.136Z,1683919283.136 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:23.137Z,1683919283.137 [DATMMP](INFO): directional_debug rx: 	 x1BE	 xD8	 x20	 x16C	 x36A0	 x41E1	 x7032	 x7FF3
2023-05-12T19:21:23.138Z,1683919283.138 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:21:23.138Z,1683919283.138 [DATMMP](INFO): Handled
2023-05-12T19:21:23.388Z,1683919283.388 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:23.390Z,1683919283.390 [DATMMP](INFO): directional rx: 	 x1EA	 xCF	 x41E	 xFF28	 xAD3	 xFF26	 x0	 x6B6	 x0	 x2	 x27B5966	 x-43569E0
2023-05-12T19:21:23.392Z,1683919283.392 [DATMMP](INFO): Received message type: directional
2023-05-12T19:21:23.392Z,1683919283.392 [DATMMP](INFO): Handled
2023-05-12T19:21:23.640Z,1683919283.640 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:23.642Z,1683919283.642 [DATMMP](INFO): acstats rx: 	 xC	 x5	 xFFFF	 x2D0	 x22	 x7A	 xB9	 xC3	 xD	 xFFFF	 xA9	 x24	 xB	 x0
2023-05-12T19:21:23.642Z,1683919283.642 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:21:23.642Z,1683919283.642 [DATMMP](INFO): Handled
2023-05-12T19:21:23.892Z,1683919283.892 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:23.893Z,1683919283.893 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x20
2023-05-12T19:21:23.893Z,1683919283.893 [DATMMP](INFO): Check CRC
2023-05-12T19:21:23.893Z,1683919283.893 [DATMMP](INFO): data rx parseDataResponse 32
2023-05-12T19:21:23.893Z,1683919283.893 [DATMMP](INFO): Got DATA: len 32
2023-05-12T19:21:23.893Z,1683919283.893 [DATMMP](INFO): Received command: set _.humidity 12.867782 percent
2023-05-12T19:21:23.894Z,1683919283.894 [DATMMP](INFO): #Outgoing data=1
2023-05-12T19:21:23.894Z,1683919283.894 [DATMMP](INFO): Sending ack
2023-05-12T19:21:23.894Z,1683919283.894 [DATMMP](INFO): Received message type: data_rx
2023-05-12T19:21:23.894Z,1683919283.894 [DATMMP](INFO): Handled
2023-05-12T19:21:24.144Z,1683919284.144 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T19:21:24.144Z,1683919284.144 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T19:21:24.396Z,1683919284.396 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T19:21:24.396Z,1683919284.396 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T19:21:24.648Z,1683919284.648 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:24.648Z,1683919284.648 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T19:21:24.649Z,1683919284.649 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:21:24.649Z,1683919284.649 [DATMMP](INFO): Handled
2023-05-12T19:21:24.649Z,1683919284.649 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T19:21:24.649Z,1683919284.649 [DATMMP](INFO): Tx to 12 len 4 xid 17
2023-05-12T19:21:24.650Z,1683919284.650 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T19:21:24.650Z,1683919284.650 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:21:24.900Z,1683919284.900 [DATMMP](INFO): GSXN notify for xid: 17
2023-05-12T19:21:24.901Z,1683919284.901 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T19:21:24.901Z,1683919284.901 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T19:21:24.901Z,1683919284.901 [DATMMP](DEBUG): Command complete send
2023-05-12T19:21:24.901Z,1683919284.901 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T19:21:24.901Z,1683919284.901 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T19:21:24.902Z,1683919284.902 [DATMMP](INFO): Handled
2023-05-12T19:21:24.902Z,1683919284.902 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T19:21:24.902Z,1683919284.902 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T19:21:25.090Z,1683919285.090 [CommandExec](IMPORTANT): got command set _.humidity 12.867782 percent
2023-05-12T19:21:25.152Z,1683919285.152 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:25.153Z,1683919285.153 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x15	 x17	 x2CD
2023-05-12T19:21:25.153Z,1683919285.153 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919290.002048
2023-05-12T19:21:25.154Z,1683919285.154 [DATMMP](INFO): Rx ping set to:1683919285.152799
2023-05-12T19:21:25.154Z,1683919285.154 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:25.154Z,1683919285.154 [DATMMP](INFO): Handled
2023-05-12T19:21:25.404Z,1683919285.404 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:25.404Z,1683919285.404 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:21:25.405Z,1683919285.405 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:21:25.405Z,1683919285.405 [DATMMP](INFO): Handled
2023-05-12T19:21:25.656Z,1683919285.656 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:25.657Z,1683919285.657 [DATMMP](INFO): directional_debug rx: 	 x19F	 xC9	 x20	 x1BE	 x3660	 x4161	 x6FD2	 x7FF3
2023-05-12T19:21:25.658Z,1683919285.658 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:21:25.658Z,1683919285.658 [DATMMP](INFO): Handled
2023-05-12T19:21:25.916Z,1683919285.916 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:25.918Z,1683919285.918 [DATMMP](INFO): directional rx: 	 x1C8	 xC5	 x43D	 xFF37	 xAF1	 xFF34	 x0	 x6B4	 x1	 x2	 x27B5966	 x-43569E0
2023-05-12T19:21:25.920Z,1683919285.920 [DATMMP](INFO): Received message type: directional
2023-05-12T19:21:25.920Z,1683919285.920 [DATMMP](INFO): Handled
2023-05-12T19:21:26.168Z,1683919286.168 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:26.169Z,1683919286.169 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:21:26.169Z,1683919286.169 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:21:26.170Z,1683919286.170 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:21:26.170Z,1683919286.170 [DATMMP](INFO): Handled
2023-05-12T19:21:26.420Z,1683919286.420 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:26.422Z,1683919286.422 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x22	 x7A	 xAF	 xAD	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T19:21:26.422Z,1683919286.422 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:21:26.422Z,1683919286.422 [DATMMP](INFO): Handled
2023-05-12T19:21:26.672Z,1683919286.672 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:26.673Z,1683919286.673 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x15	 x18	 x4FD
2023-05-12T19:21:26.673Z,1683919286.673 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:26.673Z,1683919286.673 [DATMMP](INFO): Handled
2023-05-12T19:21:26.924Z,1683919286.924 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:26.925Z,1683919286.925 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x15	 x18	 x1A5A
2023-05-12T19:21:26.925Z,1683919286.925 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:26.925Z,1683919286.925 [DATMMP](INFO): Handled
2023-05-12T19:21:40.044Z,1683919300.044 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:40.045Z,1683919300.045 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:21:40.045Z,1683919300.045 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:21:40.045Z,1683919300.045 [DATMMP](INFO): Handled
2023-05-12T19:21:40.296Z,1683919300.296 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:40.297Z,1683919300.297 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x15	 x26	 x126B
2023-05-12T19:21:40.297Z,1683919300.297 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919344.047104
2023-05-12T19:21:40.298Z,1683919300.298 [DATMMP](INFO): Rx ping set to:1683919300.296807
2023-05-12T19:21:40.298Z,1683919300.298 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:40.298Z,1683919300.298 [DATMMP](INFO): Handled
2023-05-12T19:21:40.800Z,1683919300.800 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:40.801Z,1683919300.801 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:21:40.801Z,1683919300.801 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:21:40.801Z,1683919300.801 [DATMMP](INFO): Handled
2023-05-12T19:21:41.052Z,1683919301.052 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:41.053Z,1683919301.053 [DATMMP](INFO): directional_debug rx: 	 x183	 x101	 x24	 x1EC	 x2590	 x2111	 x3282	 x4D73
2023-05-12T19:21:41.054Z,1683919301.054 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:21:41.054Z,1683919301.054 [DATMMP](INFO): Handled
2023-05-12T19:21:41.304Z,1683919301.304 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:41.305Z,1683919301.305 [DATMMP](INFO): directional rx: 	 x1A4	 xEF	 x459	 xFEFF	 xB0D	 xFEFE	 x0	 x6B5	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T19:21:41.312Z,1683919301.312 [DATMMP](INFO): Received message type: directional
2023-05-12T19:21:41.312Z,1683919301.312 [DATMMP](INFO): Handled
2023-05-12T19:21:41.557Z,1683919301.557 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:41.561Z,1683919301.561 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:21:41.562Z,1683919301.562 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:21:41.562Z,1683919301.562 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:21:41.562Z,1683919301.562 [DATMMP](INFO): Handled
2023-05-12T19:21:41.808Z,1683919301.808 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:41.810Z,1683919301.810 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x89	 xB0	 xB0	 xD	 xFFFF	 x0	 x24	 x0	 x0
2023-05-12T19:21:41.810Z,1683919301.810 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:21:41.810Z,1683919301.810 [DATMMP](INFO): Handled
2023-05-12T19:21:42.060Z,1683919302.060 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:42.061Z,1683919302.061 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x15	 x28	 x2E8
2023-05-12T19:21:42.061Z,1683919302.061 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:42.061Z,1683919302.061 [DATMMP](INFO): Handled
2023-05-12T19:21:55.180Z,1683919315.180 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:55.180Z,1683919315.180 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:21:55.181Z,1683919315.181 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:21:55.181Z,1683919315.181 [DATMMP](INFO): Handled
2023-05-12T19:21:55.432Z,1683919315.432 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:55.433Z,1683919315.433 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x15	 x35	 x1846
2023-05-12T19:21:55.433Z,1683919315.433 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919373.716800
2023-05-12T19:21:55.434Z,1683919315.434 [DATMMP](INFO): Rx ping set to:1683919315.432924
2023-05-12T19:21:55.434Z,1683919315.434 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:55.434Z,1683919315.434 [DATMMP](INFO): Handled
2023-05-12T19:21:55.936Z,1683919315.936 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:55.937Z,1683919315.937 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:21:55.937Z,1683919315.937 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:21:55.937Z,1683919315.937 [DATMMP](INFO): Handled
2023-05-12T19:21:56.192Z,1683919316.192 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:56.193Z,1683919316.193 [DATMMP](INFO): directional_debug rx: 	 x188	 x106	 x22	 x1CC	 x31B0	 x3F51	 x6822	 x75B3
2023-05-12T19:21:56.194Z,1683919316.194 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:21:56.194Z,1683919316.194 [DATMMP](INFO): Handled
2023-05-12T19:21:56.444Z,1683919316.444 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:56.445Z,1683919316.445 [DATMMP](INFO): directional rx: 	 x1A9	 xF3	 x454	 xFEFA	 xB05	 xFEFB	 x0	 x6B3	 x3	 xFFFE	 x27B5966	 x-43569E0
2023-05-12T19:21:56.447Z,1683919316.447 [DATMMP](INFO): Received message type: directional
2023-05-12T19:21:56.447Z,1683919316.447 [DATMMP](INFO): Handled
2023-05-12T19:21:56.696Z,1683919316.696 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:56.697Z,1683919316.697 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T19:21:56.698Z,1683919316.698 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:21:56.698Z,1683919316.698 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:21:56.698Z,1683919316.698 [DATMMP](INFO): Handled
2023-05-12T19:21:56.948Z,1683919316.948 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:56.950Z,1683919316.950 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7D	 xAF	 xA9	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T19:21:56.950Z,1683919316.950 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:21:56.950Z,1683919316.950 [DATMMP](INFO): Handled
2023-05-12T19:21:57.200Z,1683919317.200 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:21:57.201Z,1683919317.201 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x15	 x37	 x8C3
2023-05-12T19:21:57.201Z,1683919317.201 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:21:57.201Z,1683919317.201 [DATMMP](INFO): Handled
2023-05-12T19:22:06.300Z,1683919326.300 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:06.300Z,1683919326.300 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:22:06.301Z,1683919326.301 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:22:06.301Z,1683919326.301 [DATMMP](INFO): Handled
2023-05-12T19:22:06.552Z,1683919326.552 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:06.553Z,1683919326.553 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x16	 x4	 x1E17
2023-05-12T19:22:06.553Z,1683919326.553 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919335.235520
2023-05-12T19:22:06.554Z,1683919326.554 [DATMMP](INFO): Rx ping set to:1683919326.552827
2023-05-12T19:22:06.554Z,1683919326.554 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:22:06.554Z,1683919326.554 [DATMMP](INFO): Handled
2023-05-12T19:22:07.056Z,1683919327.056 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:07.057Z,1683919327.057 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x22	 x0	 xA	 xC	 x0
2023-05-12T19:22:07.057Z,1683919327.057 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:22:07.058Z,1683919327.058 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:22:07.058Z,1683919327.058 [DATMMP](INFO): Handled
2023-05-12T19:22:07.561Z,1683919327.561 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:07.572Z,1683919327.572 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:22:07.572Z,1683919327.572 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:22:07.573Z,1683919327.573 [DATMMP](INFO): Handled
2023-05-12T19:22:07.812Z,1683919327.812 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:07.813Z,1683919327.813 [DATMMP](INFO): directional_debug rx: 	 x193	 x10A	 x21	 x1E4	 x31D0	 x42B1	 x6C32	 x7FF3
2023-05-12T19:22:07.814Z,1683919327.814 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:22:07.814Z,1683919327.814 [DATMMP](INFO): Handled
2023-05-12T19:22:08.064Z,1683919328.064 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:08.065Z,1683919328.065 [DATMMP](INFO): directional rx: 	 x1B5	 xF7	 x449	 xFEF6	 xAFF	 xFEF6	 x0	 x6B6	 x1	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T19:22:08.067Z,1683919328.067 [DATMMP](INFO): Received message type: directional
2023-05-12T19:22:08.067Z,1683919328.067 [DATMMP](INFO): Handled
2023-05-12T19:22:08.316Z,1683919328.316 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:08.318Z,1683919328.318 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x23F	 x23	 x7C	 xAE	 xAF	 xD	 xFFFF	 xA7	 x22	 xB	 x0
2023-05-12T19:22:08.318Z,1683919328.318 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:22:08.318Z,1683919328.318 [DATMMP](INFO): Handled
2023-05-12T19:22:08.568Z,1683919328.568 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:08.569Z,1683919328.569 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x22
2023-05-12T19:22:08.569Z,1683919328.569 [DATMMP](INFO): Check CRC
2023-05-12T19:22:08.569Z,1683919328.569 [DATMMP](INFO): data rx parseDataResponse 34
2023-05-12T19:22:08.569Z,1683919328.569 [DATMMP](INFO): Got DATA: len 34
2023-05-12T19:22:08.569Z,1683919328.569 [DATMMP](INFO): Received command: set _.pressure 0.743841 atmosphere
2023-05-12T19:22:08.570Z,1683919328.570 [DATMMP](INFO): #Outgoing data=1
2023-05-12T19:22:08.570Z,1683919328.570 [DATMMP](INFO): Sending ack
2023-05-12T19:22:08.570Z,1683919328.570 [DATMMP](INFO): Received message type: data_rx
2023-05-12T19:22:08.570Z,1683919328.570 [DATMMP](INFO): Handled
2023-05-12T19:22:08.570Z,1683919328.570 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T19:22:08.570Z,1683919328.570 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T19:22:08.820Z,1683919328.820 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T19:22:08.820Z,1683919328.820 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T19:22:09.072Z,1683919329.072 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T19:22:09.072Z,1683919329.072 [DATMMP](INFO): Tx to 12 len 4 xid 18
2023-05-12T19:22:09.073Z,1683919329.073 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T19:22:09.073Z,1683919329.073 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:22:09.324Z,1683919329.324 [DATMMP](INFO): GSXN notify for xid: 18
2023-05-12T19:22:09.325Z,1683919329.325 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T19:22:09.325Z,1683919329.325 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T19:22:09.325Z,1683919329.325 [DATMMP](DEBUG): Command complete send
2023-05-12T19:22:09.325Z,1683919329.325 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T19:22:09.325Z,1683919329.325 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T19:22:09.326Z,1683919329.326 [DATMMP](INFO): Handled
2023-05-12T19:22:09.326Z,1683919329.326 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T19:22:09.326Z,1683919329.326 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T19:22:09.577Z,1683919329.577 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:09.580Z,1683919329.580 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x16	 x8	 x301
2023-05-12T19:22:09.581Z,1683919329.581 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:22:09.581Z,1683919329.581 [DATMMP](INFO): Handled
2023-05-12T19:22:09.943Z,1683919329.943 [CommandExec](IMPORTANT): got command set _.pressure 0.743841 atmosphere
2023-05-12T19:22:13.860Z,1683919333.860 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:13.860Z,1683919333.860 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:22:13.861Z,1683919333.861 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:22:13.861Z,1683919333.861 [DATMMP](INFO): Handled
2023-05-12T19:22:14.112Z,1683919334.112 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:14.113Z,1683919334.113 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x16	 xC	 xC82
2023-05-12T19:22:14.113Z,1683919334.113 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919363.282624
2023-05-12T19:22:14.114Z,1683919334.114 [DATMMP](INFO): Rx ping set to:1683919334.112812
2023-05-12T19:22:14.114Z,1683919334.114 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:22:14.114Z,1683919334.114 [DATMMP](INFO): Handled
2023-05-12T19:22:14.616Z,1683919334.616 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:14.617Z,1683919334.617 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x23	 x0	 xA	 xC	 x0
2023-05-12T19:22:14.617Z,1683919334.617 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:22:14.618Z,1683919334.618 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:22:14.618Z,1683919334.618 [DATMMP](INFO): Handled
2023-05-12T19:22:15.120Z,1683919335.120 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:15.121Z,1683919335.121 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:22:15.121Z,1683919335.121 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:22:15.121Z,1683919335.121 [DATMMP](INFO): Handled
2023-05-12T19:22:15.372Z,1683919335.372 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:15.374Z,1683919335.374 [DATMMP](INFO): directional_debug rx: 	 x1C3	 xDA	 x21	 x16A	 x3680	 x4191	 x7032	 x56F3
2023-05-12T19:22:15.375Z,1683919335.375 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:22:15.375Z,1683919335.375 [DATMMP](INFO): Handled
2023-05-12T19:22:15.624Z,1683919335.624 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:15.626Z,1683919335.626 [DATMMP](INFO): directional rx: 	 x1EF	 xD0	 x419	 xFF26	 xACD	 xFF26	 x0	 x6B4	 x1	 x0	 x27B5966	 x-43569E0
2023-05-12T19:22:15.627Z,1683919335.627 [DATMMP](INFO): Received message type: directional
2023-05-12T19:22:15.628Z,1683919335.628 [DATMMP](INFO): Handled
2023-05-12T19:22:15.876Z,1683919335.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:15.878Z,1683919335.878 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x22	 x7B	 xB1	 xB9	 xD	 xFFFF	 xA4	 x22	 xB	 x0
2023-05-12T19:22:15.878Z,1683919335.878 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:22:15.878Z,1683919335.878 [DATMMP](INFO): Handled
2023-05-12T19:22:16.128Z,1683919336.128 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:16.129Z,1683919336.129 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x23
2023-05-12T19:22:16.129Z,1683919336.129 [DATMMP](INFO): Check CRC
2023-05-12T19:22:16.129Z,1683919336.129 [DATMMP](INFO): data rx parseDataResponse 35
2023-05-12T19:22:16.129Z,1683919336.129 [DATMMP](INFO): Got DATA: len 35
2023-05-12T19:22:16.129Z,1683919336.129 [DATMMP](INFO): Received command: set _.temperature 24.219263 celsius
2023-05-12T19:22:16.130Z,1683919336.130 [DATMMP](INFO): #Outgoing data=1
2023-05-12T19:22:16.130Z,1683919336.130 [DATMMP](INFO): Sending ack
2023-05-12T19:22:16.130Z,1683919336.130 [DATMMP](INFO): Received message type: data_rx
2023-05-12T19:22:16.130Z,1683919336.130 [DATMMP](INFO): Handled
2023-05-12T19:22:16.130Z,1683919336.130 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T19:22:16.130Z,1683919336.130 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T19:22:16.380Z,1683919336.380 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T19:22:16.380Z,1683919336.380 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T19:22:16.632Z,1683919336.632 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T19:22:16.632Z,1683919336.632 [DATMMP](INFO): Tx to 12 len 4 xid 19
2023-05-12T19:22:16.633Z,1683919336.633 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T19:22:16.633Z,1683919336.633 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:22:16.884Z,1683919336.884 [DATMMP](INFO): GSXN notify for xid: 19
2023-05-12T19:22:16.885Z,1683919336.885 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T19:22:16.885Z,1683919336.885 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T19:22:16.885Z,1683919336.885 [DATMMP](DEBUG): Command complete send
2023-05-12T19:22:16.885Z,1683919336.885 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T19:22:16.885Z,1683919336.885 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T19:22:16.886Z,1683919336.886 [DATMMP](INFO): Handled
2023-05-12T19:22:16.886Z,1683919336.886 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T19:22:16.886Z,1683919336.886 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T19:22:17.136Z,1683919337.136 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:17.137Z,1683919337.137 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x16	 xF	 x1A70
2023-05-12T19:22:17.137Z,1683919337.137 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:22:17.137Z,1683919337.137 [DATMMP](INFO): Handled
2023-05-12T19:22:17.433Z,1683919337.433 [CommandExec](IMPORTANT): got command set _.temperature 24.219263 celsius
2023-05-12T19:22:21.172Z,1683919341.172 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:21.173Z,1683919341.173 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T19:22:21.173Z,1683919341.173 [DATMMP](INFO): Received message type: doppler
2023-05-12T19:22:21.173Z,1683919341.173 [DATMMP](INFO): Handled
2023-05-12T19:22:21.424Z,1683919341.424 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:21.425Z,1683919341.425 [DATMMP](INFO): timestamp rx: 	 x1	 x13	 x16	 x13	 x183A
2023-05-12T19:22:21.426Z,1683919341.426 [DATMMP](INFO): Rx dataTimestamp_ set to:1683919399.593920
2023-05-12T19:22:21.426Z,1683919341.426 [DATMMP](INFO): Rx ping set to:1683919341.425328
2023-05-12T19:22:21.426Z,1683919341.426 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:22:21.426Z,1683919341.426 [DATMMP](INFO): Handled
2023-05-12T19:22:21.928Z,1683919341.928 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:21.929Z,1683919341.929 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x20	 x0	 xA	 xC	 x0
2023-05-12T19:22:21.929Z,1683919341.929 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T19:22:21.930Z,1683919341.930 [DATMMP](INFO): Received message type: remote_header
2023-05-12T19:22:21.930Z,1683919341.930 [DATMMP](INFO): Handled
2023-05-12T19:22:22.432Z,1683919342.432 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:22.433Z,1683919342.433 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T19:22:22.433Z,1683919342.433 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T19:22:22.433Z,1683919342.433 [DATMMP](INFO): Handled
2023-05-12T19:22:22.684Z,1683919342.684 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:22.685Z,1683919342.685 [DATMMP](INFO): directional_debug rx: 	 x1BD	 xCE	 x20	 x165	 x3650	 x4181	 x6FF2	 x7FF3
2023-05-12T19:22:22.686Z,1683919342.686 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T19:22:22.686Z,1683919342.686 [DATMMP](INFO): Handled
2023-05-12T19:22:22.936Z,1683919342.936 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:22.938Z,1683919342.938 [DATMMP](INFO): directional rx: 	 x1EA	 xC7	 x41F	 xFF32	 xAD0	 xFF31	 x0	 x6B2	 x2	 x0	 x27B5966	 x-43569E0
2023-05-12T19:22:22.940Z,1683919342.940 [DATMMP](INFO): Received message type: directional
2023-05-12T19:22:22.940Z,1683919342.940 [DATMMP](INFO): Handled
2023-05-12T19:22:23.188Z,1683919343.188 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:23.190Z,1683919343.190 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x22	 x7A	 xC5	 xC1	 xD	 xFFFF	 xAA	 x25	 xB	 x0
2023-05-12T19:22:23.190Z,1683919343.190 [DATMMP](INFO): Received message type: acstats
2023-05-12T19:22:23.190Z,1683919343.190 [DATMMP](INFO): Handled
2023-05-12T19:22:23.440Z,1683919343.440 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:23.441Z,1683919343.441 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x20
2023-05-12T19:22:23.441Z,1683919343.441 [DATMMP](INFO): Check CRC
2023-05-12T19:22:23.441Z,1683919343.441 [DATMMP](INFO): data rx parseDataResponse 32
2023-05-12T19:22:23.441Z,1683919343.441 [DATMMP](INFO): Got DATA: len 32
2023-05-12T19:22:23.441Z,1683919343.441 [DATMMP](INFO): Received command: set _.humidity 12.892199 percent
2023-05-12T19:22:23.442Z,1683919343.442 [DATMMP](INFO): #Outgoing data=1
2023-05-12T19:22:23.442Z,1683919343.442 [DATMMP](INFO): Sending ack
2023-05-12T19:22:23.442Z,1683919343.442 [DATMMP](INFO): Received message type: data_rx
2023-05-12T19:22:23.442Z,1683919343.442 [DATMMP](INFO): Handled
2023-05-12T19:22:23.442Z,1683919343.442 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T19:22:23.443Z,1683919343.443 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T19:22:23.691Z,1683919343.691 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T19:22:23.691Z,1683919343.691 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T19:22:23.944Z,1683919343.944 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T19:22:23.944Z,1683919343.944 [DATMMP](INFO): Tx to 12 len 4 xid 20
2023-05-12T19:22:23.945Z,1683919343.945 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T19:22:23.945Z,1683919343.945 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T19:22:24.196Z,1683919344.196 [DATMMP](INFO): GSXN notify for xid: 20
2023-05-12T19:22:24.197Z,1683919344.197 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T19:22:24.197Z,1683919344.197 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T19:22:24.197Z,1683919344.197 [DATMMP](DEBUG): Command complete send
2023-05-12T19:22:24.197Z,1683919344.197 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T19:22:24.197Z,1683919344.197 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T19:22:24.198Z,1683919344.198 [DATMMP](INFO): Handled
2023-05-12T19:22:24.198Z,1683919344.198 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T19:22:24.198Z,1683919344.198 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T19:22:24.448Z,1683919344.448 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T19:22:24.449Z,1683919344.449 [DATMMP](INFO): timestamp rx: 	 x0	 x13	 x16	 x16	 x2628
2023-05-12T19:22:24.449Z,1683919344.449 [DATMMP](INFO): Received message type: timestamp
2023-05-12T19:22:24.449Z,1683919344.449 [DATMMP](INFO): Handled
2023-05-12T19:22:24.642Z,1683919344.642 [CommandExec](IMPORTANT): got command set _.humidity 12.892199 percent
2023-05-12T19:22:58.700Z,1683919378.700 [CommandExec](IMPORTANT): got command failComponent
2023-05-12T19:22:58.700Z,1683919378.700 [CommandExec](IMPORTANT): Failed components:
2023-05-12T19:22:58.700Z,1683919378.700 [CommandExec](IMPORTANT): No failed Components.
2023-05-12T19:23:16.624Z,1683919396.624 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T19:23:51.784Z,1683919431.784 [CommandExec](IMPORTANT): got command show stack
2023-05-12T19:23:51.785Z,1683919431.785 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-12T19:23:51.785Z,1683919431.785 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2023-05-12T19:23:51.785Z,1683919431.785 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2023-05-12T19:24:03.850Z,1683919443.850 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683919431 second
2023-05-12T19:24:03.964Z,1683919443.964 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T19:24:03.964Z,1683919443.964 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T19:24:04.307Z,1683919444.307 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T19:24:11.822Z,1683919451.822 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20230512T191723/Courier0004.lzma
2023-05-12T19:24:12.825Z,1683919452.825 [DataOverHttps](INFO): Moved sent file to Logs/20230512T191723/Courier0004.lzma.bak
2023-05-12T19:24:12.825Z,1683919452.825 [DataOverHttps](INFO): SBD MOMSN=18300510