2023-05-12T18:42:08.373Z,1683916928.373 [Supervisor](DEBUG): Initializing supervisor.
2023-05-12T18:42:08.378Z,1683916928.378 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-12T18:42:08.378Z,1683916928.378 [SyncHandler](INFO): Protected caller Thread ID is 7238
2023-05-12T18:42:08.379Z,1683916928.379 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-12T18:42:08.380Z,1683916928.380 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-12T18:42:08.380Z,1683916928.380 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7239
2023-05-12T18:42:08.385Z,1683916928.385 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-12T18:42:08.402Z,1683916928.402 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-12T18:42:08.402Z,1683916928.402 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-12T18:42:08.403Z,1683916928.403 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7240
2023-05-12T18:42:08.407Z,1683916928.407 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-12T18:42:08.408Z,1683916928.408 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-12T18:42:08.408Z,1683916928.408 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7241
2023-05-12T18:42:08.410Z,1683916928.410 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-12T18:42:08.411Z,1683916928.411 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-12T18:42:08.412Z,1683916928.412 [logger ThreadHandler](INFO): Protected caller Thread ID is 7242
2023-05-12T18:42:08.415Z,1683916928.415 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-12T18:42:08.415Z,1683916928.415 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-12T18:42:08.417Z,1683916928.417 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-12T18:42:08.515Z,1683916928.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-12T18:42:08.516Z,1683916928.516 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-12T18:42:09.190Z,1683916929.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-12T18:42:09.190Z,1683916929.190 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-12T18:42:09.413Z,1683916929.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-12T18:42:09.413Z,1683916929.413 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-12T18:42:09.494Z,1683916929.494 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-12T18:42:09.625Z,1683916929.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-12T18:42:09.625Z,1683916929.625 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-12T18:42:09.706Z,1683916929.706 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-12T18:42:09.814Z,1683916929.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-12T18:42:09.815Z,1683916929.815 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-12T18:42:10.386Z,1683916930.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-12T18:42:10.387Z,1683916930.387 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-12T18:42:11.183Z,1683916931.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-12T18:42:11.184Z,1683916931.184 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-12T18:42:11.401Z,1683916931.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-12T18:42:11.402Z,1683916931.402 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-12T18:42:11.585Z,1683916931.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-12T18:42:11.586Z,1683916931.586 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-12T18:42:12.077Z,1683916932.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-12T18:42:12.078Z,1683916932.078 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-12T18:42:12.368Z,1683916932.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-12T18:42:12.368Z,1683916932.368 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-12T18:42:12.580Z,1683916932.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-12T18:42:12.580Z,1683916932.580 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-12T18:42:12.937Z,1683916932.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-12T18:42:12.937Z,1683916932.937 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-12T18:42:13.265Z,1683916933.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-12T18:42:13.267Z,1683916933.267 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-12T18:42:13.268Z,1683916933.268 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-12T18:42:13.352Z,1683916933.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-12T18:42:13.508Z,1683916933.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-12T18:42:13.609Z,1683916933.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-12T18:42:13.696Z,1683916933.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-12T18:42:13.790Z,1683916933.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-12T18:42:13.968Z,1683916933.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-12T18:42:14.228Z,1683916934.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-12T18:42:14.228Z,1683916934.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-12T18:42:14.322Z,1683916934.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-12T18:42:14.421Z,1683916934.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-12T18:42:14.560Z,1683916934.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-12T18:42:14.660Z,1683916934.660 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-05-12T18:42:14.660Z,1683916934.660 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-12T18:42:14.668Z,1683916934.668 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-12T18:42:14.761Z,1683916934.761 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-12T18:42:14.762Z,1683916934.762 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-12T18:42:14.810Z,1683916934.810 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-12T18:42:14.870Z,1683916934.870 [VerticalControl] Loaded
2023-05-12T18:42:14.870Z,1683916934.870 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-12T18:42:14.873Z,1683916934.873 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-12T18:42:14.913Z,1683916934.913 [HorizontalControl] Loaded
2023-05-12T18:42:14.913Z,1683916934.913 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-12T18:42:14.916Z,1683916934.916 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-12T18:42:14.919Z,1683916934.919 [SpeedControl] Loaded
2023-05-12T18:42:14.919Z,1683916934.919 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-12T18:42:14.921Z,1683916934.921 [LoopControl](DEBUG): Construct LoopControl.
2023-05-12T18:42:14.922Z,1683916934.922 [LoopControl] Loaded
2023-05-12T18:42:14.922Z,1683916934.922 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-12T18:42:14.923Z,1683916934.923 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-12T18:42:14.923Z,1683916934.923 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-12T18:42:14.970Z,1683916934.970 [DepthRateCalculator] Loaded
2023-05-12T18:42:14.970Z,1683916934.970 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-12T18:42:14.975Z,1683916934.975 [PitchRateCalculator] Loaded
2023-05-12T18:42:14.975Z,1683916934.975 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-12T18:42:14.985Z,1683916934.985 [SpeedCalculator] Loaded
2023-05-12T18:42:14.985Z,1683916934.985 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-12T18:42:14.989Z,1683916934.989 [YawRateCalculator] Loaded
2023-05-12T18:42:14.989Z,1683916934.989 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-12T18:42:15.007Z,1683916935.007 [ElevatorOffsetCalculator] Loaded
2023-05-12T18:42:15.007Z,1683916935.007 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-12T18:42:15.008Z,1683916935.008 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-12T18:42:15.008Z,1683916935.008 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-12T18:42:15.034Z,1683916935.034 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-12T18:42:15.034Z,1683916935.034 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-12T18:42:15.104Z,1683916935.104 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-12T18:42:15.104Z,1683916935.104 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-12T18:42:15.785Z,1683916935.785 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-12T18:42:15.786Z,1683916935.786 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-12T18:42:16.069Z,1683916936.069 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-12T18:42:16.070Z,1683916936.070 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-12T18:42:16.712Z,1683916936.712 [AHRS_M2] Loaded
2023-05-12T18:42:16.713Z,1683916936.713 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-12T18:42:16.746Z,1683916936.746 [BackseatComponent] Loaded
2023-05-12T18:42:16.747Z,1683916936.747 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-12T18:42:16.748Z,1683916936.748 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2023-05-12T18:42:16.748Z,1683916936.748 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7324
2023-05-12T18:42:16.751Z,1683916936.751 [LcmUniversalReporter] Loaded
2023-05-12T18:42:16.751Z,1683916936.751 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-12T18:42:17.540Z,1683916937.540 [BPC1] Loaded
2023-05-12T18:42:17.540Z,1683916937.540 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-12T18:42:17.622Z,1683916937.622 [DATMMP] Loaded
2023-05-12T18:42:17.622Z,1683916937.622 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-12T18:42:17.623Z,1683916937.623 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2023-05-12T18:42:17.624Z,1683916937.624 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 7325
2023-05-12T18:42:17.696Z,1683916937.696 [DataOverHttps] Loaded
2023-05-12T18:42:17.696Z,1683916937.696 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-12T18:42:17.697Z,1683916937.697 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2023-05-12T18:42:17.697Z,1683916937.697 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7326
2023-05-12T18:42:17.717Z,1683916937.717 [Depth_Keller] Loaded
2023-05-12T18:42:17.718Z,1683916937.718 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-12T18:42:17.779Z,1683916937.779 [NAL9602] Loaded
2023-05-12T18:42:17.780Z,1683916937.780 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-12T18:42:17.808Z,1683916937.808 [Onboard] Loaded
2023-05-12T18:42:17.808Z,1683916937.808 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-12T18:42:17.809Z,1683916937.809 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-05-12T18:42:17.810Z,1683916937.810 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7327
2023-05-12T18:42:17.822Z,1683916937.822 [Power24vConverter] Loaded
2023-05-12T18:42:17.822Z,1683916937.822 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-12T18:42:17.834Z,1683916937.834 [Radio_Surface] Loaded
2023-05-12T18:42:17.835Z,1683916937.835 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-12T18:42:17.836Z,1683916937.836 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-05-12T18:42:17.836Z,1683916937.836 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7328
2023-05-12T18:42:17.880Z,1683916937.880 [RDI_Pathfinder] Loaded
2023-05-12T18:42:17.880Z,1683916937.880 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-12T18:42:17.881Z,1683916937.881 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-12T18:42:17.881Z,1683916937.881 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-12T18:42:17.987Z,1683916937.987 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-12T18:42:17.988Z,1683916937.988 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-12T18:42:18.001Z,1683916938.001 [NavChart] Loaded
2023-05-12T18:42:18.001Z,1683916938.001 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-12T18:42:18.007Z,1683916938.007 [UniversalFixResidualReporter] Loaded
2023-05-12T18:42:18.007Z,1683916938.007 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-12T18:42:18.007Z,1683916938.007 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-12T18:42:18.008Z,1683916938.008 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-12T18:42:18.141Z,1683916938.141 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-12T18:42:18.150Z,1683916938.150 [SBIT] Loaded
2023-05-12T18:42:18.150Z,1683916938.150 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-12T18:42:18.153Z,1683916938.153 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-12T18:42:18.165Z,1683916938.165 [IBIT] Loaded
2023-05-12T18:42:18.166Z,1683916938.166 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-12T18:42:18.171Z,1683916938.171 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-12T18:42:18.273Z,1683916938.273 [CBIT] Loaded
2023-05-12T18:42:18.273Z,1683916938.273 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-12T18:42:18.273Z,1683916938.273 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-12T18:42:18.274Z,1683916938.274 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-12T18:42:18.400Z,1683916938.400 [BuoyancyServo] Loaded
2023-05-12T18:42:18.401Z,1683916938.401 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-12T18:42:18.421Z,1683916938.421 [ElevatorServo] Loaded
2023-05-12T18:42:18.421Z,1683916938.421 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-12T18:42:18.441Z,1683916938.441 [MassServo] Loaded
2023-05-12T18:42:18.442Z,1683916938.442 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-12T18:42:18.460Z,1683916938.460 [RudderServo] Loaded
2023-05-12T18:42:18.460Z,1683916938.460 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-12T18:42:18.476Z,1683916938.476 [ThrusterServo] Loaded
2023-05-12T18:42:18.477Z,1683916938.477 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-12T18:42:18.477Z,1683916938.477 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-12T18:42:18.478Z,1683916938.478 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-12T18:42:18.487Z,1683916938.487 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-12T18:42:18.488Z,1683916938.488 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-12T18:42:18.652Z,1683916938.652 [CTD_Seabird] Loaded
2023-05-12T18:42:18.652Z,1683916938.652 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-12T18:42:18.653Z,1683916938.653 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0
2023-05-12T18:42:18.653Z,1683916938.653 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7329
2023-05-12T18:42:18.674Z,1683916938.674 [PAR_Licor] Loaded
2023-05-12T18:42:18.674Z,1683916938.674 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-12T18:42:18.704Z,1683916938.704 [WetLabsBB2FL] Loaded
2023-05-12T18:42:18.705Z,1683916938.705 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-12T18:42:18.706Z,1683916938.706 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0
2023-05-12T18:42:18.706Z,1683916938.706 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7330
2023-05-12T18:42:18.725Z,1683916938.725 [WetLabsUBAT] Loaded
2023-05-12T18:42:18.726Z,1683916938.726 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-12T18:42:18.727Z,1683916938.727 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0
2023-05-12T18:42:18.727Z,1683916938.727 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7331
2023-05-12T18:42:18.728Z,1683916938.728 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-12T18:42:18.734Z,1683916938.734 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-12T18:42:18.737Z,1683916938.737 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-12T18:42:18.748Z,1683916938.748 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-12T18:42:18.749Z,1683916938.749 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0
2023-05-12T18:42:18.749Z,1683916938.749 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7332
2023-05-12T18:42:18.753Z,1683916938.753 [Supervisor](INFO): Main Thread ID is 6926
2023-05-12T18:42:18.753Z,1683916938.753 [Supervisor](DEBUG): Running supervisor.
2023-05-12T18:42:18.754Z,1683916938.754 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7333
2023-05-12T18:42:18.755Z,1683916938.755 [CommandExec](INFO): Initializing the command executive.
2023-05-12T18:42:18.756Z,1683916938.756 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7334
2023-05-12T18:42:18.758Z,1683916938.758 [controlThread ThreadHandler](INFO): Handler Thread ID is 7335
2023-05-12T18:42:18.759Z,1683916938.759 [controlThread](DEBUG): Initializing ControlThread
2023-05-12T18:42:18.760Z,1683916938.760 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-12T18:42:18.761Z,1683916938.761 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-12T18:42:18.762Z,1683916938.762 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-12T18:42:18.762Z,1683916938.762 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-12T18:42:18.763Z,1683916938.763 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-12T18:42:18.763Z,1683916938.763 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-12T18:42:18.763Z,1683916938.763 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-12T18:42:18.764Z,1683916938.764 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-12T18:42:18.764Z,1683916938.764 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-12T18:42:18.768Z,1683916938.768 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-12T18:42:18.769Z,1683916938.769 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-12T18:42:18.769Z,1683916938.769 [SBIT](INFO): Initialize SBIT Component.
2023-05-12T18:42:18.769Z,1683916938.769 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-12T18:42:18.770Z,1683916938.770 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-12T18:42:18.770Z,1683916938.770 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-12T18:42:18.772Z,1683916938.772 [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-12T18:42:18.773Z,1683916938.773 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-12T18:42:18.773Z,1683916938.773 [IBIT](INFO): Initialize IBIT Component.
2023-05-12T18:42:18.774Z,1683916938.774 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-12T18:42:18.775Z,1683916938.775 [logger ThreadHandler](INFO): Handler Thread ID is 7336
2023-05-12T18:42:18.787Z,1683916938.787 [CBIT](DEBUG): Initialized mux pins.
2023-05-12T18:42:18.787Z,1683916938.787 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-12T18:42:18.796Z,1683916938.796 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7337
2023-05-12T18:42:18.807Z,1683916938.807 [DATMMP ThreadHandler](INFO): Handler Thread ID is 7338
2023-05-12T18:42:18.808Z,1683916938.808 [DATMMP](INFO): Start
2023-05-12T18:42:18.808Z,1683916938.808 [DATMMP](INFO): Powering up
2023-05-12T18:42:18.809Z,1683916938.809 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-12T18:42:18.811Z,1683916938.811 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-12T18:42:18.812Z,1683916938.812 [CBIT](DEBUG): Initializing heartbeat.
2023-05-12T18:42:18.813Z,1683916938.813 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7340
2023-05-12T18:42:18.814Z,1683916938.814 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-12T18:42:18.824Z,1683916938.824 [Onboard ThreadHandler](INFO): Handler Thread ID is 7341
2023-05-12T18:42:18.841Z,1683916938.841 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7342
2023-05-12T18:42:18.859Z,1683916938.859 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7343
2023-05-12T18:42:18.860Z,1683916938.860 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-12T18:42:18.864Z,1683916938.864 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7345
2023-05-12T18:42:18.868Z,1683916938.868 [WetLabsBB2FL](INFO): Powering up
2023-05-12T18:42:18.869Z,1683916938.869 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7347
2023-05-12T18:42:18.872Z,1683916938.872 [WetLabsUBAT](INFO): Powering up
2023-05-12T18:42:18.873Z,1683916938.873 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7349
2023-05-12T18:42:18.876Z,1683916938.876 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-12T18:42:18.876Z,1683916938.876 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-12T18:42:18.876Z,1683916938.876 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-12T18:42:18.877Z,1683916938.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-12T18:42:18.877Z,1683916938.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-12T18:42:18.877Z,1683916938.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-12T18:42:18.877Z,1683916938.877 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-12T18:42:18.877Z,1683916938.877 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-12T18:42:18.883Z,1683916938.883 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-12T18:42:18.884Z,1683916938.884 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-12T18:42:18.923Z,1683916938.923 [CBIT](DEBUG): Backplane powered.
2023-05-12T18:42:18.928Z,1683916938.928 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-12T18:42:18.964Z,1683916938.964 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T18:42:18.995Z,1683916938.995 [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-12T18:42:18.996Z,1683916938.996 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-12T18:42:19.059Z,1683916939.059 [DATMMP](INFO): Starting
2023-05-12T18:42:19.071Z,1683916939.071 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-12T18:42:19.072Z,1683916939.072 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-12T18:42:19.075Z,1683916939.075 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T18:42:19.122Z,1683916939.122 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-12T18:42:19.125Z,1683916939.125 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-12T18:42:19.143Z,1683916939.143 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-12T18:42:19.146Z,1683916939.146 [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-12T18:42:19.163Z,1683916939.163 [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-12T18:42:19.171Z,1683916939.171 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-12T18:42:19.247Z,1683916939.247 [Radio_Surface](INFO): Powering up
2023-05-12T18:42:19.257Z,1683916939.257 [Power24vConverter](INFO): Powering up.
2023-05-12T18:42:19.258Z,1683916939.258 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-12T18:42:19.311Z,1683916939.311 [DATMMP](INFO): Starting
2023-05-12T18:42:19.353Z,1683916939.353 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:42:19.360Z,1683916939.360 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-12T18:42:19.361Z,1683916939.361 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:42:19.368Z,1683916939.368 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-12T18:42:19.369Z,1683916939.369 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:42:19.375Z,1683916939.375 [MassServo](DEBUG): Initializing MassServo.
2023-05-12T18:42:19.376Z,1683916939.376 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:42:19.383Z,1683916939.383 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-12T18:42:19.384Z,1683916939.384 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:42:19.399Z,1683916939.399 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-12T18:42:19.563Z,1683916939.563 [DATMMP](INFO): Starting
2023-05-12T18:42:19.816Z,1683916939.816 [DATMMP](INFO): Starting
2023-05-12T18:42:20.067Z,1683916940.067 [DATMMP](INFO): Starting
2023-05-12T18:42:20.319Z,1683916940.319 [DATMMP](INFO): Starting
2023-05-12T18:42:20.571Z,1683916940.571 [DATMMP](INFO): Starting
2023-05-12T18:42:20.823Z,1683916940.823 [DATMMP](INFO): Starting
2023-05-12T18:42:21.075Z,1683916941.075 [DATMMP](INFO): Starting
2023-05-12T18:42:21.327Z,1683916941.327 [DATMMP](INFO): Starting
2023-05-12T18:42:21.481Z,1683916941.481 [WetLabsBB2FL](INFO): Powering down
2023-05-12T18:42:21.579Z,1683916941.579 [DATMMP](INFO): Starting
2023-05-12T18:42:21.831Z,1683916941.831 [DATMMP](INFO): Starting
2023-05-12T18:42:22.084Z,1683916942.084 [DATMMP](INFO): Starting
2023-05-12T18:42:22.335Z,1683916942.335 [DATMMP](INFO): Starting
2023-05-12T18:42:22.587Z,1683916942.587 [DATMMP](INFO): Starting
2023-05-12T18:42:22.839Z,1683916942.839 [DATMMP](INFO): Starting
2023-05-12T18:42:23.091Z,1683916943.091 [DATMMP](INFO): Starting
2023-05-12T18:42:23.343Z,1683916943.343 [DATMMP](INFO): Starting
2023-05-12T18:42:23.595Z,1683916943.595 [DATMMP](INFO): Starting
2023-05-12T18:42:23.848Z,1683916943.848 [DATMMP](INFO): Starting
2023-05-12T18:42:24.099Z,1683916944.099 [DATMMP](INFO): Starting
2023-05-12T18:42:24.351Z,1683916944.351 [DATMMP](INFO): Starting
2023-05-12T18:42:24.603Z,1683916944.603 [DATMMP](INFO): Starting
2023-05-12T18:42:24.855Z,1683916944.855 [DATMMP](INFO): Starting
2023-05-12T18:42:25.107Z,1683916945.107 [DATMMP](INFO): Starting
2023-05-12T18:42:25.359Z,1683916945.359 [DATMMP](INFO): Starting
2023-05-12T18:42:25.611Z,1683916945.611 [DATMMP](INFO): Starting
2023-05-12T18:42:25.863Z,1683916945.863 [DATMMP](INFO): Starting
2023-05-12T18:42:26.115Z,1683916946.115 [DATMMP](INFO): Starting
2023-05-12T18:42:26.367Z,1683916946.367 [DATMMP](INFO): Starting
2023-05-12T18:42:26.619Z,1683916946.619 [DATMMP](INFO): Starting
2023-05-12T18:42:26.872Z,1683916946.872 [DATMMP](INFO): Starting
2023-05-12T18:42:27.123Z,1683916947.123 [DATMMP](INFO): Starting
2023-05-12T18:42:27.375Z,1683916947.375 [DATMMP](INFO): Starting
2023-05-12T18:42:27.627Z,1683916947.627 [DATMMP](INFO): Starting
2023-05-12T18:42:27.879Z,1683916947.879 [DATMMP](INFO): Starting
2023-05-12T18:42:28.131Z,1683916948.131 [WetLabsUBAT](INFO): Powering down
2023-05-12T18:42:28.133Z,1683916948.133 [DATMMP](INFO): Starting
2023-05-12T18:42:28.387Z,1683916948.387 [DATMMP](INFO): Starting
2023-05-12T18:42:28.639Z,1683916948.639 [DATMMP](INFO): Starting
2023-05-12T18:42:28.891Z,1683916948.891 [DATMMP](INFO): Starting
2023-05-12T18:42:29.143Z,1683916949.143 [DATMMP](INFO): Starting
2023-05-12T18:42:29.395Z,1683916949.395 [DATMMP](INFO): Starting
2023-05-12T18:42:29.647Z,1683916949.647 [DATMMP](INFO): Starting
2023-05-12T18:42:29.899Z,1683916949.899 [DATMMP](INFO): Starting
2023-05-12T18:42:30.151Z,1683916950.151 [DATMMP](INFO): Starting
2023-05-12T18:42:30.403Z,1683916950.403 [DATMMP](INFO): Starting
2023-05-12T18:42:30.655Z,1683916950.655 [DATMMP](INFO): Starting
2023-05-12T18:42:30.907Z,1683916950.907 [DATMMP](INFO): Starting
2023-05-12T18:42:30.908Z,1683916950.908 [DATMMP](INFO): DAT read: 

2023-05-12T18:42:30.909Z,1683916950.909 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-12T18:42:31.159Z,1683916951.159 [DATMMP](INFO): Starting
2023-05-12T18:42:31.411Z,1683916951.411 [DATMMP](INFO): Starting
2023-05-12T18:42:31.663Z,1683916951.663 [DATMMP](INFO): Starting
2023-05-12T18:42:31.915Z,1683916951.915 [DATMMP](INFO): Starting
2023-05-12T18:42:32.167Z,1683916952.167 [DATMMP](INFO): Starting
2023-05-12T18:42:32.419Z,1683916952.419 [DATMMP](INFO): Starting
2023-05-12T18:42:32.671Z,1683916952.671 [DATMMP](INFO): Starting
2023-05-12T18:42:32.672Z,1683916952.672 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-12T18:42:32.673Z,1683916952.673 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-12T18:42:32.674Z,1683916952.674 [DATMMP](INFO): DAT read: Apr 12 2000 18:42:27 

2023-05-12T18:42:32.923Z,1683916952.923 [DATMMP](INFO): Starting
2023-05-12T18:42:33.175Z,1683916953.175 [DATMMP](INFO): Starting
2023-05-12T18:42:33.428Z,1683916953.428 [DATMMP](INFO): Starting
2023-05-12T18:42:33.679Z,1683916953.679 [DATMMP](INFO): Starting
2023-05-12T18:42:33.680Z,1683916953.680 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-12T18:42:33.681Z,1683916953.681 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-12T18:42:33.682Z,1683916953.682 [DATMMP](INFO): commRate: 800
2023-05-12T18:42:33.931Z,1683916953.931 [DATMMP](INFO): Starting
2023-05-12T18:42:34.183Z,1683916954.183 [DATMMP](INFO): Starting
2023-05-12T18:42:34.435Z,1683916954.435 [DATMMP](INFO): Starting
2023-05-12T18:42:34.687Z,1683916954.687 [DATMMP](INFO): Starting
2023-05-12T18:42:34.939Z,1683916954.939 [DATMMP](INFO): Starting
2023-05-12T18:42:35.191Z,1683916955.191 [DATMMP](INFO): Starting
2023-05-12T18:42:35.443Z,1683916955.443 [DATMMP](INFO): Starting
2023-05-12T18:42:35.695Z,1683916955.695 [DATMMP](INFO): Starting
2023-05-12T18:42:35.747Z,1683916955.747 [DATMMP](INFO): entering command mode
2023-05-12T18:42:35.947Z,1683916955.947 [DATMMP](INFO): Starting
2023-05-12T18:42:35.948Z,1683916955.948 [DATMMP](INFO): DAT read: 

2023-05-12T18:42:35.948Z,1683916955.948 [DATMMP](INFO): DAT read: user:1>

2023-05-12T18:42:35.948Z,1683916955.948 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-12T18:42:35.949Z,1683916955.949 [DATMMP](INFO): entering MMP mode
2023-05-12T18:42:36.200Z,1683916956.200 [DATMMP](INFO): Starting
2023-05-12T18:42:36.201Z,1683916956.201 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:42:36.202Z,1683916956.202 [DATMMP](INFO): status rx: 	 x1
2023-05-12T18:42:36.203Z,1683916956.203 [DATMMP](INFO): MMP status: 1: started
2023-05-12T18:42:36.203Z,1683916956.203 [DATMMP](INFO): Received message type: status
2023-05-12T18:42:36.203Z,1683916956.203 [DATMMP](INFO): Handled
2023-05-12T18:42:36.451Z,1683916956.451 [DATMMP](INFO): Starting
2023-05-12T18:42:36.453Z,1683916956.453 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-12T18:42:36.453Z,1683916956.453 [DATMMP](INFO): Setting local address: 10
2023-05-12T18:42:36.456Z,1683916956.456 [DATMMP](INFO): Setting time to: 18:42:36 And date to:5/12/2023
2023-05-12T18:42:36.703Z,1683916956.703 [DATMMP](INFO): Starting
2023-05-12T18:42:36.704Z,1683916956.704 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:42:36.705Z,1683916956.705 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-12T18:42:36.705Z,1683916956.705 [DATMMP](INFO): Received message type: mdmpower
2023-05-12T18:42:36.705Z,1683916956.705 [DATMMP](INFO): Handled
2023-05-12T18:42:36.955Z,1683916956.955 [DATMMP](INFO): Starting
2023-05-12T18:42:36.956Z,1683916956.956 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-12T18:42:36.956Z,1683916956.956 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-12T18:42:36.956Z,1683916956.956 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-12T18:42:36.957Z,1683916956.957 [DATMMP](INFO): Match for : sreg_txpower
2023-05-12T18:42:36.957Z,1683916956.957 [DATMMP](INFO): ACK
2023-05-12T18:42:37.207Z,1683916957.207 [DATMMP](INFO): Starting
2023-05-12T18:42:37.208Z,1683916957.208 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:42:37.208Z,1683916957.208 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-12T18:42:37.209Z,1683916957.209 [DATMMP](INFO): Received message type: mdmlocal
2023-05-12T18:42:37.209Z,1683916957.209 [DATMMP](INFO): Handled
2023-05-12T18:42:37.459Z,1683916957.459 [DATMMP](INFO): Starting
2023-05-12T18:42:37.460Z,1683916957.460 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-12T18:42:37.460Z,1683916957.460 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-12T18:42:37.461Z,1683916957.461 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-12T18:42:37.461Z,1683916957.461 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-12T18:42:37.461Z,1683916957.461 [DATMMP](INFO): ACK
2023-05-12T18:42:37.711Z,1683916957.711 [DATMMP](INFO): Starting
2023-05-12T18:42:37.712Z,1683916957.712 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-12T18:42:37.713Z,1683916957.713 [DATMMP](INFO): timedate rx: 	 x0	 x12	 x2A	 x24	 x4	 xC	 x7B	 x0
2023-05-12T18:42:37.713Z,1683916957.713 [DATMMP](INFO): Received message type: timedate
2023-05-12T18:42:37.713Z,1683916957.713 [DATMMP](INFO): Match for : timedate
2023-05-12T18:42:37.713Z,1683916957.713 [DATMMP](INFO): ACK
2023-05-12T18:42:37.963Z,1683916957.963 [DATMMP](INFO): Starting
2023-05-12T18:42:37.964Z,1683916957.964 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:42:37.965Z,1683916957.965 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-12T18:42:37.965Z,1683916957.965 [DATMMP](INFO): Received message type: sys_verb
2023-05-12T18:42:37.965Z,1683916957.965 [DATMMP](INFO): Handled
2023-05-12T18:42:38.215Z,1683916958.215 [DATMMP](INFO): Starting
2023-05-12T18:42:38.216Z,1683916958.216 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-12T18:42:38.216Z,1683916958.216 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-12T18:42:38.216Z,1683916958.216 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-12T18:42:38.217Z,1683916958.217 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-12T18:42:38.217Z,1683916958.217 [DATMMP](INFO): ACK
2023-05-12T18:42:38.467Z,1683916958.467 [DATMMP](INFO): Starting
2023-05-12T18:42:38.468Z,1683916958.468 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-12T18:42:38.468Z,1683916958.468 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-12T18:42:38.469Z,1683916958.469 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-12T18:42:38.469Z,1683916958.469 [DATMMP](INFO): Received message type: version
2023-05-12T18:42:38.469Z,1683916958.469 [DATMMP](INFO): Match for : version
2023-05-12T18:42:38.469Z,1683916958.469 [DATMMP](INFO): ACK
2023-05-12T18:42:38.469Z,1683916958.469 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-12T18:42:47.860Z,1683916967.860 [NAL9602](INFO): Powering up NAL9602
2023-05-12T18:42:52.832Z,1683916972.832 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:42:52.833Z,1683916972.833 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T18:42:52.833Z,1683916972.833 [DATMMP](INFO): Received message type: timesync
2023-05-12T18:42:52.833Z,1683916972.833 [DATMMP](INFO): Handled
2023-05-12T18:42:58.816Z,1683916978.816 [NAL9602](INFO): NAL9602 initialized
2023-05-12T18:43:02.672Z,1683916982.672 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:43:02.673Z,1683916982.673 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T18:43:02.673Z,1683916982.673 [DATMMP](INFO): Received message type: timesync
2023-05-12T18:43:02.673Z,1683916982.673 [DATMMP](INFO): Handled
2023-05-12T18:43:05.171Z,1683916985.171 [CommandExec](IMPORTANT): got command get depth
2023-05-12T18:43:05.172Z,1683916985.172 [CommandExec](IMPORTANT): depth -0.273559 m
2023-05-12T18:43:10.515Z,1683916990.515 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-12T18:43:10.543Z,1683916990.543 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-12T18:43:19.349Z,1683916999.349 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-12T18:43:19.353Z,1683916999.353 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-12T18:43:19.353Z,1683916999.353 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-12T18:43:19.357Z,1683916999.357 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-12T18:43:19.357Z,1683916999.357 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-12T18:43:19.413Z,1683916999.413 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-12T18:43:19.413Z,1683916999.413 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-12T18:43:19.414Z,1683916999.414 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-12T18:43:19.486Z,1683916999.486 [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-12T18:43:21.460Z,1683917001.460 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001234
CHAN A1 (24V): 0.000268
CHAN A2 (12V): -0.007642
CHAN A3 (5V): -0.001984
CHAN B0 (3.3V): 0.000326
CHAN B1 (3.15aV): -0.000292
CHAN B2 (3.15bV): 0.000175
CHAN B3 (GND): 0.001389
OPEN: 0.005250
Full Scale: +/- 1 mA
2023-05-12T18:43:24.377Z,1683917004.377 [CommandExec](IMPORTANT): got command get depth
2023-05-12T18:43:24.377Z,1683917004.377 [CommandExec](IMPORTANT): depth 0.800000 m
2023-05-12T18:43:33.671Z,1683917013.671 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683917004 second
2023-05-12T18:44:04.266Z,1683917044.266 [SBIT](IMPORTANT): SBIT PASSED
2023-05-12T18:44:04.266Z,1683917044.266 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-12T18:44:04.267Z,1683917044.267 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-12T18:44:04.267Z,1683917044.267 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-12T18:44:04.267Z,1683917044.267 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-12T18:44:04.268Z,1683917044.268 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-12T18:44:04.268Z,1683917044.268 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-12T18:44:04.268Z,1683917044.268 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-12T18:44:04.268Z,1683917044.268 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-12T18:44:04.672Z,1683917044.672 [MissionManager](IMPORTANT): Started mission Startup
2023-05-12T18:44:04.673Z,1683917044.673 [Startup] Running Loop=1
2023-05-12T18:44:04.673Z,1683917044.673 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-12T18:44:04.673Z,1683917044.673 [Startup:A.GoToSurface] Running Loop=1
2023-05-12T18:44:04.673Z,1683917044.673 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T18:44:04.674Z,1683917044.674 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T18:44:04.674Z,1683917044.674 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T18:44:04.674Z,1683917044.674 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T18:44:04.675Z,1683917044.675 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T18:44:04.675Z,1683917044.675 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T18:44:04.677Z,1683917044.677 [Startup:StartupSatComms] Running Loop=1
2023-05-12T18:44:04.677Z,1683917044.677 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-12T18:44:04.677Z,1683917044.677 [Startup:StartupSatComms:A] Running Loop=1
2023-05-12T18:44:05.082Z,1683917045.082 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-12T18:44:05.082Z,1683917045.082 [Startup:StartupSatComms:A] Stopped
2023-05-12T18:44:05.082Z,1683917045.082 [Startup:StartupSatComms:B] Running Loop=1
2023-05-12T18:44:05.464Z,1683917045.464 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T18:44:46.965Z,1683917086.965 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004463
2023-05-12T18:44:54.491Z,1683917094.491 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T182948/Courier0007.lzma
2023-05-12T18:44:55.493Z,1683917095.493 [DataOverHttps](INFO): Moved sent file to Logs/20230512T182948/Courier0007.lzma.bak
2023-05-12T18:44:55.493Z,1683917095.493 [DataOverHttps](INFO): SBD MOMSN=18298104
2023-05-12T18:45:02.244Z,1683917102.244 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:02.244Z,1683917102.244 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T18:45:02.245Z,1683917102.245 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:45:02.245Z,1683917102.245 [DATMMP](INFO): Handled
2023-05-12T18:45:02.496Z,1683917102.496 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:02.497Z,1683917102.497 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2D	 x0	 x1190
2023-05-12T18:45:02.498Z,1683917102.498 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917143.950272
2023-05-12T18:45:02.498Z,1683917102.498 [DATMMP](INFO): Rx ping set to:1683917102.496785
2023-05-12T18:45:02.498Z,1683917102.498 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:02.498Z,1683917102.498 [DATMMP](INFO): Handled
2023-05-12T18:45:02.000Z,1683917103.000 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:03.000Z,1683917103.000 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:45:03.001Z,1683917103.001 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:45:03.001Z,1683917103.001 [DATMMP](INFO): Handled
2023-05-12T18:45:03.252Z,1683917103.252 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:03.253Z,1683917103.253 [DATMMP](INFO): directional_debug rx: 	 x181	 xFF	 x21	 x1CF	 x2A70	 x4031	 x66B2	 x5753
2023-05-12T18:45:03.254Z,1683917103.254 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:45:03.255Z,1683917103.255 [DATMMP](INFO): Handled
2023-05-12T18:45:03.504Z,1683917103.504 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:03.505Z,1683917103.505 [DATMMP](INFO): directional rx: 	 x1A2	 xEE	 x45B	 xFF01	 xB0F	 xFF00	 x0	 x6B5	 x3	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:45:03.507Z,1683917103.507 [DATMMP](INFO): Received message type: directional
2023-05-12T18:45:03.507Z,1683917103.507 [DATMMP](INFO): Handled
2023-05-12T18:45:03.756Z,1683917103.756 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:03.757Z,1683917103.757 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:45:03.757Z,1683917103.757 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:45:03.758Z,1683917103.758 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:45:03.758Z,1683917103.758 [DATMMP](INFO): Handled
2023-05-12T18:45:04.008Z,1683917104.008 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:04.010Z,1683917104.010 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x23	 x7B	 xAE	 xAE	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:45:04.010Z,1683917104.010 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:45:04.010Z,1683917104.010 [DATMMP](INFO): Handled
2023-05-12T18:45:04.260Z,1683917104.260 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:04.261Z,1683917104.261 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2D	 x2	 x20C
2023-05-12T18:45:04.261Z,1683917104.261 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:04.261Z,1683917104.261 [DATMMP](INFO): Handled
2023-05-12T18:45:05.247Z,1683917105.247 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T18:44:05.1Z
2023-05-12T18:45:05.247Z,1683917105.247 [Startup:StartupSatComms:B] Stopped
2023-05-12T18:45:05.247Z,1683917105.247 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-12T18:45:05.248Z,1683917105.248 [Startup:StartupSatComms] Stopped
2023-05-12T18:45:05.248Z,1683917105.248 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-12T18:45:05.248Z,1683917105.248 [Startup](INFO): Completed Startup
2023-05-12T18:45:05.249Z,1683917105.249 [MissionManager](INFO): Startup is completed.
2023-05-12T18:45:05.249Z,1683917105.249 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-12T18:45:05.249Z,1683917105.249 [Startup] Stopped
2023-05-12T18:45:05.249Z,1683917105.249 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-12T18:45:05.249Z,1683917105.249 [Startup:A.GoToSurface] Stopped
2023-05-12T18:45:05.249Z,1683917105.249 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T18:45:05.644Z,1683917105.644 [MissionManager](IMPORTANT): Started mission Default
2023-05-12T18:45:05.644Z,1683917105.644 [Default] Running Loop=1
2023-05-12T18:45:05.644Z,1683917105.644 [Default](DEBUG): Aggregate::initialize Default
2023-05-12T18:45:05.644Z,1683917105.644 [Default:B.GoToSurface] Running Loop=1
2023-05-12T18:45:05.644Z,1683917105.644 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T18:45:05.645Z,1683917105.645 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T18:45:05.645Z,1683917105.645 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T18:45:05.645Z,1683917105.645 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T18:45:05.646Z,1683917105.646 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T18:45:05.646Z,1683917105.646 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T18:45:05.646Z,1683917105.646 [Default:A.Wait] Running Loop=1
2023-05-12T18:45:05.646Z,1683917105.646 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-12T18:45:11.963Z,1683917111.963 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230512T184208/Courier0000.lzma
2023-05-12T18:45:12.965Z,1683917112.965 [DataOverHttps](INFO): Moved sent file to Logs/20230512T184208/Courier0000.lzma.bak
2023-05-12T18:45:12.965Z,1683917112.965 [DataOverHttps](INFO): SBD MOMSN=18298107
2023-05-12T18:45:17.624Z,1683917117.624 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:17.624Z,1683917117.624 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:45:17.625Z,1683917117.625 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:45:17.625Z,1683917117.625 [DATMMP](INFO): Handled
2023-05-12T18:45:17.876Z,1683917117.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:17.877Z,1683917117.877 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2D	 xF	 x212F
2023-05-12T18:45:17.877Z,1683917117.877 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917134.005568
2023-05-12T18:45:17.878Z,1683917117.878 [DATMMP](INFO): Rx ping set to:1683917117.876777
2023-05-12T18:45:17.878Z,1683917117.878 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:17.878Z,1683917117.878 [DATMMP](INFO): Handled
2023-05-12T18:45:18.380Z,1683917118.380 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:18.381Z,1683917118.381 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:45:18.381Z,1683917118.381 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:45:18.381Z,1683917118.381 [DATMMP](INFO): Handled
2023-05-12T18:45:18.632Z,1683917118.632 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:18.634Z,1683917118.634 [DATMMP](INFO): directional_debug rx: 	 x18A	 x107	 x23	 x1D1	 x3220	 x3B01	 x5E42	 x61F3
2023-05-12T18:45:18.635Z,1683917118.635 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:45:18.635Z,1683917118.635 [DATMMP](INFO): Handled
2023-05-12T18:45:18.884Z,1683917118.884 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:18.885Z,1683917118.885 [DATMMP](INFO): directional rx: 	 x1AC	 xF5	 x452	 xFEF9	 xB04	 xFEF7	 x0	 x6B3	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:45:18.887Z,1683917118.887 [DATMMP](INFO): Received message type: directional
2023-05-12T18:45:18.887Z,1683917118.887 [DATMMP](INFO): Handled
2023-05-12T18:45:19.046Z,1683917119.046 [Default:A.Wait](INFO): Done Waiting.
2023-05-12T18:45:19.047Z,1683917119.047 [Default:A.Wait] Stopped
2023-05-12T18:45:19.047Z,1683917119.047 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T18:45:19.136Z,1683917119.136 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:19.137Z,1683917119.137 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:45:19.138Z,1683917119.138 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:45:19.138Z,1683917119.138 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:45:19.138Z,1683917119.138 [DATMMP](INFO): Handled
2023-05-12T18:45:19.388Z,1683917119.388 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:19.389Z,1683917119.389 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x1A0	 x23	 x83	 xAF	 xAD	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:45:19.390Z,1683917119.390 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:45:19.390Z,1683917119.390 [DATMMP](INFO): Handled
2023-05-12T18:45:19.399Z,1683917119.399 [Default:CheckIn] Running Loop=1
2023-05-12T18:45:19.400Z,1683917119.400 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T18:45:19.400Z,1683917119.400 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T18:45:19.640Z,1683917119.640 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:19.641Z,1683917119.641 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2D	 x11	 x11AB
2023-05-12T18:45:19.641Z,1683917119.641 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:19.641Z,1683917119.641 [DATMMP](INFO): Handled
2023-05-12T18:45:19.817Z,1683917119.817 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-12T18:45:32.996Z,1683917132.996 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:32.996Z,1683917132.996 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:45:32.997Z,1683917132.997 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:45:32.997Z,1683917132.997 [DATMMP](INFO): Handled
2023-05-12T18:45:33.248Z,1683917133.248 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:33.249Z,1683917133.249 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2D	 x1F	 x7CA
2023-05-12T18:45:33.249Z,1683917133.249 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917150.495616
2023-05-12T18:45:33.250Z,1683917133.250 [DATMMP](INFO): Rx ping set to:1683917133.248790
2023-05-12T18:45:33.250Z,1683917133.250 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:33.250Z,1683917133.250 [DATMMP](INFO): Handled
2023-05-12T18:45:33.752Z,1683917133.752 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:33.753Z,1683917133.753 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:45:33.753Z,1683917133.753 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:45:33.753Z,1683917133.753 [DATMMP](INFO): Handled
2023-05-12T18:45:34.005Z,1683917134.005 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:34.006Z,1683917134.006 [DATMMP](INFO): directional_debug rx: 	 x18E	 x108	 x23	 x1ED	 x3220	 x3A61	 x5ED2	 x61A3
2023-05-12T18:45:34.008Z,1683917134.008 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:45:34.008Z,1683917134.008 [DATMMP](INFO): Handled
2023-05-12T18:45:34.256Z,1683917134.256 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:34.258Z,1683917134.258 [DATMMP](INFO): directional rx: 	 x1AF	 xF5	 x44E	 xFEF8	 xAFE	 xFEF8	 x0	 x6B1	 x3	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:45:34.260Z,1683917134.260 [DATMMP](INFO): Received message type: directional
2023-05-12T18:45:34.260Z,1683917134.260 [DATMMP](INFO): Handled
2023-05-12T18:45:34.508Z,1683917134.508 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:34.509Z,1683917134.509 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:45:34.509Z,1683917134.509 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:45:34.509Z,1683917134.509 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:45:34.509Z,1683917134.509 [DATMMP](INFO): Handled
2023-05-12T18:45:34.761Z,1683917134.761 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:34.766Z,1683917134.766 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x22	 x82	 xAF	 xAD	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:45:34.766Z,1683917134.766 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:45:34.767Z,1683917134.767 [DATMMP](INFO): Handled
2023-05-12T18:45:35.012Z,1683917135.012 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:35.013Z,1683917135.013 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2D	 x20	 x1F56
2023-05-12T18:45:35.013Z,1683917135.013 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:35.013Z,1683917135.013 [DATMMP](INFO): Handled
2023-05-12T18:45:48.116Z,1683917148.116 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:48.116Z,1683917148.116 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:45:48.117Z,1683917148.117 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:45:48.117Z,1683917148.117 [DATMMP](INFO): Handled
2023-05-12T18:45:48.368Z,1683917148.368 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:48.369Z,1683917148.369 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2D	 x2E	 x5C8
2023-05-12T18:45:48.369Z,1683917148.369 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917160.475136
2023-05-12T18:45:48.370Z,1683917148.370 [DATMMP](INFO): Rx ping set to:1683917148.368755
2023-05-12T18:45:48.370Z,1683917148.370 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:48.370Z,1683917148.370 [DATMMP](INFO): Handled
2023-05-12T18:45:48.620Z,1683917148.620 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:48.621Z,1683917148.621 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x22	 x0	 xA	 xC	 x0
2023-05-12T18:45:48.621Z,1683917148.621 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:45:48.621Z,1683917148.621 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:45:48.622Z,1683917148.622 [DATMMP](INFO): Handled
2023-05-12T18:45:49.124Z,1683917149.124 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:49.125Z,1683917149.125 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:45:49.125Z,1683917149.125 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:45:49.125Z,1683917149.125 [DATMMP](INFO): Handled
2023-05-12T18:45:49.376Z,1683917149.376 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:49.377Z,1683917149.377 [DATMMP](INFO): directional_debug rx: 	 x186	 x102	 x23	 x1D9	 x3220	 x3AF1	 x5572	 x61F3
2023-05-12T18:45:49.378Z,1683917149.378 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:45:49.378Z,1683917149.378 [DATMMP](INFO): Handled
2023-05-12T18:45:49.628Z,1683917149.628 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:49.629Z,1683917149.629 [DATMMP](INFO): directional rx: 	 x1A7	 xF0	 x456	 xFEFE	 xB07	 xFEFD	 x0	 x6B2	 x2	 x1	 x27B5966	 x-43569E0
2023-05-12T18:45:49.632Z,1683917149.632 [DATMMP](INFO): Received message type: directional
2023-05-12T18:45:49.632Z,1683917149.632 [DATMMP](INFO): Handled
2023-05-12T18:45:49.880Z,1683917149.880 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:49.881Z,1683917149.881 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x23	 x82	 xB7	 xBE	 xD	 xFFFF	 xA8	 x1E	 xB	 x0
2023-05-12T18:45:49.882Z,1683917149.882 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:45:49.882Z,1683917149.882 [DATMMP](INFO): Handled
2023-05-12T18:45:50.132Z,1683917150.132 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:50.133Z,1683917150.133 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x22
2023-05-12T18:45:50.133Z,1683917150.133 [DATMMP](INFO): Check CRC
2023-05-12T18:45:50.133Z,1683917150.133 [DATMMP](INFO): data rx parseDataResponse 34
2023-05-12T18:45:50.133Z,1683917150.133 [DATMMP](INFO): Got DATA: len 34
2023-05-12T18:45:50.134Z,1683917150.134 [DATMMP](INFO): Received command: set _.pressure 0.745180 atmosphere
2023-05-12T18:45:50.134Z,1683917150.134 [DATMMP](INFO): #Outgoing data=1
2023-05-12T18:45:50.134Z,1683917150.134 [DATMMP](INFO): Sending ack
2023-05-12T18:45:50.134Z,1683917150.134 [DATMMP](INFO): Received message type: data_rx
2023-05-12T18:45:50.134Z,1683917150.134 [DATMMP](INFO): Handled
2023-05-12T18:45:50.135Z,1683917150.135 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T18:45:50.135Z,1683917150.135 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T18:45:50.383Z,1683917150.383 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T18:45:50.384Z,1683917150.384 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T18:45:50.636Z,1683917150.636 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T18:45:50.636Z,1683917150.636 [DATMMP](INFO): Tx to 12 len 4 xid 15
2023-05-12T18:45:50.638Z,1683917150.638 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T18:45:50.888Z,1683917150.888 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-12T18:45:50.889Z,1683917150.889 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T18:45:50.889Z,1683917150.889 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T18:45:50.889Z,1683917150.889 [DATMMP](DEBUG): Command complete send
2023-05-12T18:45:50.890Z,1683917150.890 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T18:45:50.890Z,1683917150.890 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T18:45:50.890Z,1683917150.890 [DATMMP](INFO): Handled
2023-05-12T18:45:51.140Z,1683917151.140 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:51.141Z,1683917151.141 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2D	 x31	 x1064
2023-05-12T18:45:51.141Z,1683917151.141 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:51.141Z,1683917151.141 [DATMMP](INFO): Handled
2023-05-12T18:45:51.141Z,1683917151.141 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T18:45:51.141Z,1683917151.141 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T18:45:51.246Z,1683917151.246 [CommandExec](IMPORTANT): got command set _.pressure 0.745180 atmosphere
2023-05-12T18:45:55.436Z,1683917155.436 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:55.437Z,1683917155.437 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T18:45:55.437Z,1683917155.437 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:45:55.437Z,1683917155.437 [DATMMP](INFO): Handled
2023-05-12T18:45:55.688Z,1683917155.688 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:55.689Z,1683917155.689 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2D	 x35	 x1180
2023-05-12T18:45:55.689Z,1683917155.689 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917196.786432
2023-05-12T18:45:55.690Z,1683917155.690 [DATMMP](INFO): Rx ping set to:1683917155.688792
2023-05-12T18:45:55.690Z,1683917155.690 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:55.690Z,1683917155.690 [DATMMP](INFO): Handled
2023-05-12T18:45:55.940Z,1683917155.940 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:55.941Z,1683917155.941 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x23	 x0	 xA	 xC	 x0
2023-05-12T18:45:55.941Z,1683917155.941 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:45:55.942Z,1683917155.942 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:45:55.942Z,1683917155.942 [DATMMP](INFO): Handled
2023-05-12T18:45:56.444Z,1683917156.444 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:56.445Z,1683917156.445 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:45:56.445Z,1683917156.445 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:45:56.445Z,1683917156.445 [DATMMP](INFO): Handled
2023-05-12T18:45:56.696Z,1683917156.696 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:56.697Z,1683917156.697 [DATMMP](INFO): directional_debug rx: 	 x177	 x105	 x22	 x1FA	 x3230	 x4071	 x6B32	 x7FF3
2023-05-12T18:45:56.698Z,1683917156.698 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:45:56.698Z,1683917156.698 [DATMMP](INFO): Handled
2023-05-12T18:45:56.948Z,1683917156.948 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:56.949Z,1683917156.949 [DATMMP](INFO): directional rx: 	 x196	 xF2	 x465	 xFEFB	 xB14	 xFEFA	 x0	 x6B0	 x1	 x1	 x27B5966	 x-43569E0
2023-05-12T18:45:56.951Z,1683917156.951 [DATMMP](INFO): Received message type: directional
2023-05-12T18:45:56.951Z,1683917156.951 [DATMMP](INFO): Handled
2023-05-12T18:45:57.200Z,1683917157.200 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:57.202Z,1683917157.202 [DATMMP](INFO): acstats rx: 	 xC	 x5	 xFFFF	 x2D0	 x23	 x7C	 xAA	 xB8	 xD	 xFFFF	 xA4	 x22	 xB	 x0
2023-05-12T18:45:57.202Z,1683917157.202 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:45:57.202Z,1683917157.202 [DATMMP](INFO): Handled
2023-05-12T18:45:57.452Z,1683917157.452 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:57.453Z,1683917157.453 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x23
2023-05-12T18:45:57.453Z,1683917157.453 [DATMMP](INFO): Check CRC
2023-05-12T18:45:57.453Z,1683917157.453 [DATMMP](INFO): data rx parseDataResponse 35
2023-05-12T18:45:57.453Z,1683917157.453 [DATMMP](INFO): Got DATA: len 35
2023-05-12T18:45:57.453Z,1683917157.453 [DATMMP](INFO): Received command: set _.temperature 24.219263 celsius
2023-05-12T18:45:57.454Z,1683917157.454 [DATMMP](INFO): #Outgoing data=1
2023-05-12T18:45:57.454Z,1683917157.454 [DATMMP](INFO): Sending ack
2023-05-12T18:45:57.454Z,1683917157.454 [DATMMP](INFO): Received message type: data_rx
2023-05-12T18:45:57.454Z,1683917157.454 [DATMMP](INFO): Handled
2023-05-12T18:45:57.454Z,1683917157.454 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T18:45:57.455Z,1683917157.455 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T18:45:57.704Z,1683917157.704 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T18:45:57.704Z,1683917157.704 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T18:45:57.956Z,1683917157.956 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T18:45:57.956Z,1683917157.956 [DATMMP](INFO): Tx to 12 len 4 xid 16
2023-05-12T18:45:57.957Z,1683917157.957 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T18:45:58.208Z,1683917158.208 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-12T18:45:58.209Z,1683917158.209 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T18:45:58.209Z,1683917158.209 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T18:45:58.209Z,1683917158.209 [DATMMP](DEBUG): Command complete send
2023-05-12T18:45:58.209Z,1683917158.209 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T18:45:58.209Z,1683917158.209 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T18:45:58.209Z,1683917158.209 [DATMMP](INFO): Handled
2023-05-12T18:45:58.210Z,1683917158.210 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T18:45:58.210Z,1683917158.210 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T18:45:58.460Z,1683917158.460 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:45:58.461Z,1683917158.461 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2D	 x38	 x1C1C
2023-05-12T18:45:58.461Z,1683917158.461 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:45:58.461Z,1683917158.461 [DATMMP](INFO): Handled
2023-05-12T18:45:58.712Z,1683917158.712 [CommandExec](IMPORTANT): got command set _.temperature 24.219263 celsius
2023-05-12T18:46:02.996Z,1683917162.996 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:02.996Z,1683917162.996 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:46:02.997Z,1683917162.997 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:46:02.997Z,1683917162.997 [DATMMP](INFO): Handled
2023-05-12T18:46:03.248Z,1683917163.248 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:03.249Z,1683917163.249 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2E	 x1	 x1DF
2023-05-12T18:46:03.250Z,1683917163.250 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917165.710656
2023-05-12T18:46:03.250Z,1683917163.250 [DATMMP](INFO): Rx ping set to:1683917163.249115
2023-05-12T18:46:03.250Z,1683917163.250 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:03.250Z,1683917163.250 [DATMMP](INFO): Handled
2023-05-12T18:46:03.500Z,1683917163.500 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:03.501Z,1683917163.501 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x20	 x0	 xA	 xC	 x0
2023-05-12T18:46:03.501Z,1683917163.501 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:46:03.501Z,1683917163.501 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:46:03.502Z,1683917163.502 [DATMMP](INFO): Handled
2023-05-12T18:46:04.004Z,1683917164.004 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:04.005Z,1683917164.005 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:46:04.005Z,1683917164.005 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:46:04.005Z,1683917164.005 [DATMMP](INFO): Handled
2023-05-12T18:46:04.264Z,1683917164.264 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:04.265Z,1683917164.265 [DATMMP](INFO): directional_debug rx: 	 x1A3	 xBF	 x20	 x17F	 x36A0	 x4161	 x6FE2	 x7FF3
2023-05-12T18:46:04.266Z,1683917164.266 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:46:04.266Z,1683917164.266 [DATMMP](INFO): Handled
2023-05-12T18:46:04.516Z,1683917164.516 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:04.517Z,1683917164.517 [DATMMP](INFO): directional rx: 	 x1CB	 xBD	 x439	 xFF41	 xAE8	 xFF41	 x0	 x6AF	 x1	 x0	 x27B5966	 x-43569E0
2023-05-12T18:46:04.519Z,1683917164.519 [DATMMP](INFO): Received message type: directional
2023-05-12T18:46:04.519Z,1683917164.519 [DATMMP](INFO): Handled
2023-05-12T18:46:04.769Z,1683917164.769 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:04.774Z,1683917164.774 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x23	 x7A	 xB6	 xC1	 xD	 xFFFF	 xA9	 x23	 xB	 x0
2023-05-12T18:46:04.774Z,1683917164.774 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:46:04.775Z,1683917164.775 [DATMMP](INFO): Handled
2023-05-12T18:46:05.020Z,1683917165.020 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:05.021Z,1683917165.021 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x20
2023-05-12T18:46:05.021Z,1683917165.021 [DATMMP](INFO): Check CRC
2023-05-12T18:46:05.021Z,1683917165.021 [DATMMP](INFO): data rx parseDataResponse 32
2023-05-12T18:46:05.021Z,1683917165.021 [DATMMP](INFO): Got DATA: len 32
2023-05-12T18:46:05.021Z,1683917165.021 [DATMMP](INFO): Received command: set _.humidity 12.916615 percent
2023-05-12T18:46:05.022Z,1683917165.022 [DATMMP](INFO): #Outgoing data=1
2023-05-12T18:46:05.022Z,1683917165.022 [DATMMP](INFO): Sending ack
2023-05-12T18:46:05.022Z,1683917165.022 [DATMMP](INFO): Received message type: data_rx
2023-05-12T18:46:05.022Z,1683917165.022 [DATMMP](INFO): Handled
2023-05-12T18:46:05.272Z,1683917165.272 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:05.272Z,1683917165.272 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:46:05.273Z,1683917165.273 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:46:05.273Z,1683917165.273 [DATMMP](INFO): Handled
2023-05-12T18:46:05.273Z,1683917165.273 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T18:46:05.273Z,1683917165.273 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T18:46:05.524Z,1683917165.524 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:05.525Z,1683917165.525 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2E	 x2	 x1CB6
2023-05-12T18:46:05.525Z,1683917165.525 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917169.815104
2023-05-12T18:46:05.526Z,1683917165.526 [DATMMP](INFO): Rx ping set to:1683917165.524747
2023-05-12T18:46:05.526Z,1683917165.526 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:05.526Z,1683917165.526 [DATMMP](INFO): Handled
2023-05-12T18:46:05.526Z,1683917165.526 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T18:46:05.526Z,1683917165.526 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T18:46:05.776Z,1683917165.776 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:05.776Z,1683917165.776 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:46:05.777Z,1683917165.777 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:46:05.777Z,1683917165.777 [DATMMP](INFO): Handled
2023-05-12T18:46:05.777Z,1683917165.777 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T18:46:05.777Z,1683917165.777 [DATMMP](INFO): Tx to 12 len 4 xid 17
2023-05-12T18:46:05.778Z,1683917165.778 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T18:46:06.028Z,1683917166.028 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:06.029Z,1683917166.029 [DATMMP](INFO): directional_debug rx: 	 x19B	 xBE	 x22	 x17D	 x35F0	 x4171	 x6F72	 x5643
2023-05-12T18:46:06.030Z,1683917166.030 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:46:06.030Z,1683917166.030 [DATMMP](INFO): Handled
2023-05-12T18:46:06.030Z,1683917166.030 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:46:06.280Z,1683917166.280 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:06.281Z,1683917166.281 [DATMMP](INFO): directional rx: 	 x1C1	 xBD	 x441	 xFF42	 xAF1	 xFF42	 x0	 x6B0	 x1	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:46:06.283Z,1683917166.283 [DATMMP](INFO): Received message type: directional
2023-05-12T18:46:06.283Z,1683917166.283 [DATMMP](INFO): Handled
2023-05-12T18:46:06.283Z,1683917166.283 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:46:06.397Z,1683917166.397 [CommandExec](IMPORTANT): got command set _.humidity 12.916615 percent
2023-05-12T18:46:06.532Z,1683917166.532 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:06.533Z,1683917166.533 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:46:06.533Z,1683917166.533 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:46:06.534Z,1683917166.534 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:46:06.534Z,1683917166.534 [DATMMP](INFO): Handled
2023-05-12T18:46:06.534Z,1683917166.534 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:46:06.785Z,1683917166.785 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:06.790Z,1683917166.790 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7E	 xB0	 xB1	 xD	 xFFFF	 x0	 x1F	 x0	 x0
2023-05-12T18:46:06.790Z,1683917166.790 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:46:06.790Z,1683917166.790 [DATMMP](INFO): Handled
2023-05-12T18:46:06.791Z,1683917166.791 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:46:07.036Z,1683917167.036 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:07.037Z,1683917167.037 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2E	 x4	 xD32
2023-05-12T18:46:07.037Z,1683917167.037 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:07.037Z,1683917167.037 [DATMMP](INFO): Handled
2023-05-12T18:46:07.037Z,1683917167.037 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:46:07.288Z,1683917167.288 [DATMMP](INFO): GSXN notify for xid: 17
2023-05-12T18:46:07.289Z,1683917167.289 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T18:46:07.289Z,1683917167.289 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T18:46:07.289Z,1683917167.289 [DATMMP](DEBUG): Command complete send
2023-05-12T18:46:07.289Z,1683917167.289 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T18:46:07.290Z,1683917167.290 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T18:46:07.290Z,1683917167.290 [DATMMP](INFO): Handled
2023-05-12T18:46:07.290Z,1683917167.290 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T18:46:07.290Z,1683917167.290 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T18:46:07.540Z,1683917167.540 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:07.541Z,1683917167.541 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2E	 x4	 x1449
2023-05-12T18:46:07.541Z,1683917167.541 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:07.541Z,1683917167.541 [DATMMP](INFO): Handled
2023-05-12T18:46:19.140Z,1683917179.140 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:19.140Z,1683917179.140 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:46:19.140Z,1683917179.140 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:46:19.141Z,1683917179.141 [DATMMP](INFO): Handled
2023-05-12T18:46:19.392Z,1683917179.392 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:19.393Z,1683917179.393 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2E	 x11	 x5D2
2023-05-12T18:46:19.393Z,1683917179.393 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917191.577536
2023-05-12T18:46:19.394Z,1683917179.394 [DATMMP](INFO): Rx ping set to:1683917179.392812
2023-05-12T18:46:19.394Z,1683917179.394 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:19.394Z,1683917179.394 [DATMMP](INFO): Handled
2023-05-12T18:46:19.644Z,1683917179.644 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:19.644Z,1683917179.644 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:46:19.645Z,1683917179.645 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:46:19.645Z,1683917179.645 [DATMMP](INFO): Handled
2023-05-12T18:46:19.896Z,1683917179.896 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:19.897Z,1683917179.897 [DATMMP](INFO): directional_debug rx: 	 x10C	 x216	 x23	 x452	 x1BF0	 x19B1	 x2AA2	 x4123
2023-05-12T18:46:19.898Z,1683917179.898 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:46:19.899Z,1683917179.899 [DATMMP](INFO): Handled
2023-05-12T18:46:20.148Z,1683917180.148 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:20.149Z,1683917180.149 [DATMMP](INFO): directional rx: 	 x11A	 x1F1	 x4D0	 xFDEA	 xB83	 xFDE9	 x0	 x6B3	 x0	 x1	 x27B5966	 x-43569E0
2023-05-12T18:46:20.151Z,1683917180.151 [DATMMP](INFO): Received message type: directional
2023-05-12T18:46:20.151Z,1683917180.151 [DATMMP](INFO): Handled
2023-05-12T18:46:20.400Z,1683917180.400 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:20.401Z,1683917180.401 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:46:20.401Z,1683917180.401 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:46:20.402Z,1683917180.402 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:46:20.402Z,1683917180.402 [DATMMP](INFO): Handled
2023-05-12T18:46:20.652Z,1683917180.652 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:20.653Z,1683917180.653 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x8A	 xAF	 xAA	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:46:20.654Z,1683917180.654 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:46:20.654Z,1683917180.654 [DATMMP](INFO): Handled
2023-05-12T18:46:20.904Z,1683917180.904 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:20.905Z,1683917180.905 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2E	 x12	 x1D5E
2023-05-12T18:46:20.905Z,1683917180.905 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:20.905Z,1683917180.905 [DATMMP](INFO): Handled
2023-05-12T18:46:34.272Z,1683917194.272 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:34.272Z,1683917194.272 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:46:34.273Z,1683917194.273 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:46:34.273Z,1683917194.273 [DATMMP](INFO): Handled
2023-05-12T18:46:34.524Z,1683917194.524 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:34.525Z,1683917194.525 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2E	 x20	 x137D
2023-05-12T18:46:34.525Z,1683917194.525 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917240.755712
2023-05-12T18:46:34.526Z,1683917194.526 [DATMMP](INFO): Rx ping set to:1683917194.524789
2023-05-12T18:46:34.526Z,1683917194.526 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:34.526Z,1683917194.526 [DATMMP](INFO): Handled
2023-05-12T18:46:35.028Z,1683917195.028 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:35.029Z,1683917195.029 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:46:35.029Z,1683917195.029 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:46:35.029Z,1683917195.029 [DATMMP](INFO): Handled
2023-05-12T18:46:35.280Z,1683917195.280 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:35.281Z,1683917195.281 [DATMMP](INFO): directional_debug rx: 	 x185	 x10F	 x23	 x219	 x31E0	 x3ED1	 x5112	 x6773
2023-05-12T18:46:35.282Z,1683917195.282 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:46:35.282Z,1683917195.282 [DATMMP](INFO): Handled
2023-05-12T18:46:35.532Z,1683917195.532 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:35.533Z,1683917195.533 [DATMMP](INFO): directional rx: 	 x1A5	 xFB	 x457	 xFEF1	 xB09	 xFEEF	 x0	 x6B2	 x1	 x2	 x27B5966	 x-43569E0
2023-05-12T18:46:35.542Z,1683917195.542 [DATMMP](INFO): Received message type: directional
2023-05-12T18:46:35.542Z,1683917195.542 [DATMMP](INFO): Handled
2023-05-12T18:46:35.784Z,1683917195.784 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:35.785Z,1683917195.785 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:46:35.785Z,1683917195.785 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:46:35.785Z,1683917195.785 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:46:35.785Z,1683917195.785 [DATMMP](INFO): Handled
2023-05-12T18:46:36.036Z,1683917196.036 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:36.038Z,1683917196.038 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x86	 xAF	 xAD	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:46:36.038Z,1683917196.038 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:46:36.038Z,1683917196.038 [DATMMP](INFO): Handled
2023-05-12T18:46:36.288Z,1683917196.288 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:36.289Z,1683917196.289 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2E	 x22	 x3F9
2023-05-12T18:46:36.289Z,1683917196.289 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:36.289Z,1683917196.289 [DATMMP](INFO): Handled
2023-05-12T18:46:49.896Z,1683917209.896 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:49.896Z,1683917209.896 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:46:49.897Z,1683917209.897 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:46:49.897Z,1683917209.897 [DATMMP](INFO): Handled
2023-05-12T18:46:50.148Z,1683917210.148 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:50.149Z,1683917210.149 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x2E	 x2F	 x231C
2023-05-12T18:46:50.149Z,1683917210.149 [DATMMP](INFO): Rx dataTimestamp_ set to:1683917230.811008
2023-05-12T18:46:50.150Z,1683917210.150 [DATMMP](INFO): Rx ping set to:1683917210.148809
2023-05-12T18:46:50.150Z,1683917210.150 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:50.150Z,1683917210.150 [DATMMP](INFO): Handled
2023-05-12T18:46:50.400Z,1683917210.400 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:50.401Z,1683917210.401 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:46:50.401Z,1683917210.401 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:46:50.401Z,1683917210.401 [DATMMP](INFO): Handled
2023-05-12T18:46:50.652Z,1683917210.652 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:50.653Z,1683917210.653 [DATMMP](INFO): directional_debug rx: 	 x180	 x10E	 x23	 x227	 x31F0	 x3E41	 x5D92	 x67B3
2023-05-12T18:46:50.654Z,1683917210.654 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:46:50.654Z,1683917210.654 [DATMMP](INFO): Handled
2023-05-12T18:46:50.904Z,1683917210.904 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:50.905Z,1683917210.905 [DATMMP](INFO): directional rx: 	 x1A0	 xF9	 x45C	 xFEF2	 xB0F	 xFEF2	 x0	 x6B3	 x0	 x0	 x27B5966	 x-43569E0
2023-05-12T18:46:50.909Z,1683917210.909 [DATMMP](INFO): Received message type: directional
2023-05-12T18:46:50.909Z,1683917210.909 [DATMMP](INFO): Handled
2023-05-12T18:46:51.156Z,1683917211.156 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:51.157Z,1683917211.157 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:46:51.157Z,1683917211.157 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:46:51.158Z,1683917211.158 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:46:51.158Z,1683917211.158 [DATMMP](INFO): Handled
2023-05-12T18:46:51.408Z,1683917211.408 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:51.409Z,1683917211.409 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x1BF	 x23	 x84	 xAF	 xAE	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:46:51.410Z,1683917211.410 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:46:51.410Z,1683917211.410 [DATMMP](INFO): Handled
2023-05-12T18:46:51.660Z,1683917211.660 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:46:51.661Z,1683917211.661 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x2E	 x31	 x1398
2023-05-12T18:46:51.661Z,1683917211.661 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:46:51.661Z,1683917211.661 [DATMMP](INFO): Handled
2023-05-12T18:47:19.072Z,1683917239.072 [CommandExec](IMPORTANT): got command failComponent
2023-05-12T18:47:19.073Z,1683917239.073 [CommandExec](IMPORTANT): Failed components:
2023-05-12T18:47:19.073Z,1683917239.073 [CommandExec](IMPORTANT): No failed Components.
2023-05-12T18:48:02.200Z,1683917282.200 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T18:49:42.024Z,1683917382.024 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2023-05-12T18:49:42.027Z,1683917382.027 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T18:50:19.596Z,1683917419.596 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T18:45:19.4Z
2023-05-12T18:50:19.596Z,1683917419.596 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T18:50:19.597Z,1683917419.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T18:50:19.994Z,1683917419.994 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T18:50:26.947Z,1683917426.947 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230512T184208/Courier0004.lzma
2023-05-12T18:50:27.949Z,1683917427.949 [DataOverHttps](INFO): Moved sent file to Logs/20230512T184208/Courier0004.lzma.bak
2023-05-12T18:50:27.949Z,1683917427.949 [DataOverHttps](INFO): SBD MOMSN=18298110
2023-05-12T18:50:43.839Z,1683917443.839 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20230512T182948/Express0008.lzma
2023-05-12T18:50:44.841Z,1683917444.841 [DataOverHttps](INFO): Moved sent file to Logs/20230512T182948/Express0008.lzma.bak
2023-05-12T18:50:44.841Z,1683917444.841 [DataOverHttps](INFO): SBD MOMSN=18298112
2023-05-12T18:50:52.284Z,1683917452.284 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-12T18:51:00.916Z,1683917460.916 [DataOverHttps](INFO): Sending 1110 bytes from file Logs/20230512T184208/Express0001.lzma
2023-05-12T18:51:01.917Z,1683917461.917 [DataOverHttps](INFO): Moved sent file to Logs/20230512T184208/Express0001.lzma.bak
2023-05-12T18:51:01.917Z,1683917461.917 [DataOverHttps](INFO): SBD MOMSN=18298115
2023-05-12T18:51:30.415Z,1683917490.415 [DataOverHttps](INFO): Sending 453 bytes from file Logs/20230512T184208/Express0005.lzma
2023-05-12T18:51:31.417Z,1683917491.417 [DataOverHttps](INFO): Moved sent file to Logs/20230512T184208/Express0005.lzma.bak
2023-05-12T18:51:31.417Z,1683917491.417 [DataOverHttps](INFO): SBD MOMSN=18298144
2023-05-12T18:59:55.027Z,1683917995.027 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2023-05-12T19:17:14.216Z,1683919034.216 [CommandExec](IMPORTANT): got command restart application
2023-05-12T19:17:15.219Z,1683919035.219 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-12T19:17:15.219Z,1683919035.219 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:15.219Z,1683919035.219 [CommandExec](INFO): Uninitializing the command executive.
2023-05-12T19:17:15.220Z,1683919035.220 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-12T19:17:15.220Z,1683919035.220 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:15.271Z,1683919035.271 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-12T19:17:15.271Z,1683919035.271 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-12T19:17:15.272Z,1683919035.272 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:15.272Z,1683919035.272 [NavChartDb](INFO): Join timeout helper Thread ID is 7391
2023-05-12T19:17:15.543Z,1683919035.543 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:15.543Z,1683919035.543 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:15.551Z,1683919035.551 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-12T19:17:15.551Z,1683919035.551 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:15.552Z,1683919035.552 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7392
2023-05-12T19:17:15.852Z,1683919035.852 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:15.852Z,1683919035.852 [WetLabsUBAT](INFO): Powering down
2023-05-12T19:17:15.853Z,1683919035.853 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:15.859Z,1683919035.859 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-12T19:17:15.859Z,1683919035.859 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:15.860Z,1683919035.860 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7393
2023-05-12T19:17:16.309Z,1683919036.309 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:16.310Z,1683919036.310 [WetLabsBB2FL](INFO): Powering down
2023-05-12T19:17:16.312Z,1683919036.312 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:16.315Z,1683919036.315 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-12T19:17:16.315Z,1683919036.315 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:16.316Z,1683919036.316 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7394
2023-05-12T19:17:17.155Z,1683919037.155 [CTD_Seabird](INFO): Powering down
2023-05-12T19:17:17.167Z,1683919037.167 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:17.167Z,1683919037.167 [CTD_Seabird](INFO): Powering down
2023-05-12T19:17:17.179Z,1683919037.179 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:17.183Z,1683919037.183 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-12T19:17:17.183Z,1683919037.183 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:17.184Z,1683919037.184 [Radio_Surface](INFO): Join timeout helper Thread ID is 7395
2023-05-12T19:17:17.399Z,1683919037.399 [Radio_Surface](INFO): Powering down
2023-05-12T19:17:17.400Z,1683919037.400 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:17.400Z,1683919037.400 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:17.416Z,1683919037.416 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-12T19:17:17.417Z,1683919037.417 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:17.417Z,1683919037.417 [Onboard](INFO): Join timeout helper Thread ID is 7396
2023-05-12T19:17:18.466Z,1683919038.466 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-12T19:17:20.764Z,1683919040.764 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:20.765Z,1683919040.765 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:20.773Z,1683919040.773 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-12T19:17:20.773Z,1683919040.773 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:20.774Z,1683919040.774 [DataOverHttps](INFO): Join timeout helper Thread ID is 7397
2023-05-12T19:17:21.319Z,1683919041.319 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:21.320Z,1683919041.320 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.336Z,1683919041.336 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-12T19:17:21.336Z,1683919041.336 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.336Z,1683919041.336 [DATMMP](INFO): Join timeout helper Thread ID is 7398
2023-05-12T19:17:21.419Z,1683919041.419 [DATMMP](INFO): Stop
2023-05-12T19:17:21.419Z,1683919041.419 [DATMMP](INFO): uninitialize
2023-05-12T19:17:21.419Z,1683919041.419 [DATMMP](INFO): Powering down
2023-05-12T19:17:21.488Z,1683919041.488 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:21.489Z,1683919041.489 [DATMMP](INFO): uninitialize
2023-05-12T19:17:21.489Z,1683919041.489 [DATMMP](INFO): Powering down
2023-05-12T19:17:21.490Z,1683919041.490 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.500Z,1683919041.500 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-12T19:17:21.500Z,1683919041.500 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.500Z,1683919041.500 [BackseatComponent](INFO): Join timeout helper Thread ID is 7399
2023-05-12T19:17:21.699Z,1683919041.699 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:21.700Z,1683919041.700 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.706Z,1683919041.706 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-12T19:17:21.706Z,1683919041.706 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.706Z,1683919041.706 [logger](INFO): Join timeout helper Thread ID is 7400
2023-05-12T19:17:21.747Z,1683919041.747 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:21.748Z,1683919041.748 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.760Z,1683919041.760 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-12T19:17:21.760Z,1683919041.760 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.760Z,1683919041.760 [CommandLine](INFO): Join timeout helper Thread ID is 7401
2023-05-12T19:17:21.815Z,1683919041.815 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:21.816Z,1683919041.816 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.820Z,1683919041.820 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-12T19:17:21.820Z,1683919041.820 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.820Z,1683919041.820 [CommandExec](INFO): Join timeout helper Thread ID is 7402
2023-05-12T19:17:21.821Z,1683919041.821 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-12T19:17:21.822Z,1683919041.822 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:21.822Z,1683919041.822 [controlThread](INFO): Join timeout helper Thread ID is 7403
2023-05-12T19:17:22.103Z,1683919042.103 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T19:17:22.104Z,1683919042.104 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-12T19:17:22.104Z,1683919042.104 [AHRS_M2](INFO): Powering down
2023-05-12T19:17:22.176Z,1683919042.176 [NAL9602](INFO): Powering down
2023-05-12T19:17:22.247Z,1683919042.247 [RDI_Pathfinder](INFO): Powering down
2023-05-12T19:17:22.249Z,1683919042.249 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-12T19:17:22.250Z,1683919042.250 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-12T19:17:22.250Z,1683919042.250 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-12T19:17:22.251Z,1683919042.251 [MissionManager](INFO): Uninitializing Mission Default
2023-05-12T19:17:22.251Z,1683919042.251 [Default] Stopped
2023-05-12T19:17:22.251Z,1683919042.251 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-12T19:17:22.251Z,1683919042.251 [Default:B.GoToSurface] Stopped
2023-05-12T19:17:22.251Z,1683919042.251 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T19:17:22.251Z,1683919042.251 [Default:CheckIn] Stopped
2023-05-12T19:17:22.251Z,1683919042.251 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T19:17:22.251Z,1683919042.251 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T19:17:22.254Z,1683919042.254 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-12T19:17:22.255Z,1683919042.255 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-12T19:17:22.255Z,1683919042.255 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-12T19:17:22.255Z,1683919042.255 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-12T19:17:22.256Z,1683919042.256 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-12T19:17:22.256Z,1683919042.256 [BuoyancyServo](INFO): Powering down
2023-05-12T19:17:22.267Z,1683919042.267 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-12T19:17:22.267Z,1683919042.267 [ElevatorServo](INFO): Powering down
2023-05-12T19:17:22.268Z,1683919042.268 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-12T19:17:22.268Z,1683919042.268 [MassServo](INFO): Powering down
2023-05-12T19:17:22.269Z,1683919042.269 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-12T19:17:22.269Z,1683919042.269 [RudderServo](INFO): Powering down
2023-05-12T19:17:22.270Z,1683919042.270 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-12T19:17:22.270Z,1683919042.270 [ThrusterServo](INFO): Powering down
2023-05-12T19:17:22.271Z,1683919042.271 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-12T19:17:22.271Z,1683919042.271 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-12T19:17:22.272Z,1683919042.272 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-12T19:17:22.272Z,1683919042.272 [CBIT](DEBUG): Powering off loads.
2023-05-12T19:17:22.283Z,1683919042.283 [CBIT](DEBUG): Disabling WDT.
2023-05-12T19:17:22.295Z,1683919042.295 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-12T19:17:22.296Z,1683919042.296 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.356Z,1683919042.356 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.359Z,1683919042.359 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.364Z,1683919042.364 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.370Z,1683919042.370 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.411Z,1683919042.411 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.477Z,1683919042.477 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.479Z,1683919042.479 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.482Z,1683919042.482 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.491Z,1683919042.491 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T19:17:22.552Z,1683919042.552 [logger ThreadHandler](INFO): Thread cancelled.