2023-05-11T15:51:42.971Z,1683820302.971 [Supervisor](DEBUG): Initializing supervisor.
2023-05-11T15:51:42.976Z,1683820302.976 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-11T15:51:42.976Z,1683820302.976 [SyncHandler](INFO): Protected caller Thread ID is 5423
2023-05-11T15:51:42.977Z,1683820302.977 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-11T15:51:42.978Z,1683820302.978 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-11T15:51:42.978Z,1683820302.978 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5424
2023-05-11T15:51:42.982Z,1683820302.982 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-11T15:51:42.999Z,1683820302.999 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-11T15:51:43.000Z,1683820303.000 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-11T15:51:43.000Z,1683820303.000 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5425
2023-05-11T15:51:43.004Z,1683820303.004 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-11T15:51:43.005Z,1683820303.005 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-11T15:51:43.006Z,1683820303.006 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5426
2023-05-11T15:51:43.008Z,1683820303.008 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-11T15:51:43.009Z,1683820303.009 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-11T15:51:43.009Z,1683820303.009 [logger ThreadHandler](INFO): Protected caller Thread ID is 5427
2023-05-11T15:51:43.013Z,1683820303.013 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-11T15:51:43.013Z,1683820303.013 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-11T15:51:43.015Z,1683820303.015 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-11T15:51:43.112Z,1683820303.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-11T15:51:43.113Z,1683820303.113 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-11T15:51:43.776Z,1683820303.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-11T15:51:43.776Z,1683820303.776 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-11T15:51:44.060Z,1683820304.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-11T15:51:44.061Z,1683820304.061 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-11T15:51:44.148Z,1683820304.148 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-11T15:51:44.283Z,1683820304.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-11T15:51:44.284Z,1683820304.284 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-11T15:51:44.364Z,1683820304.364 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-11T15:51:44.472Z,1683820304.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-11T15:51:44.472Z,1683820304.472 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-11T15:51:44.842Z,1683820304.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-11T15:51:44.843Z,1683820304.843 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-11T15:51:45.315Z,1683820305.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-11T15:51:45.315Z,1683820305.315 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-11T15:51:45.531Z,1683820305.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-11T15:51:45.532Z,1683820305.532 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-11T15:51:45.711Z,1683820305.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-11T15:51:45.711Z,1683820305.711 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-11T15:51:46.205Z,1683820306.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-11T15:51:46.206Z,1683820306.206 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-11T15:51:46.493Z,1683820306.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-11T15:51:46.493Z,1683820306.493 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-11T15:51:46.703Z,1683820306.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-11T15:51:46.704Z,1683820306.704 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-11T15:51:47.060Z,1683820307.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-11T15:51:47.061Z,1683820307.061 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-11T15:51:47.385Z,1683820307.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-11T15:51:47.386Z,1683820307.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-11T15:51:47.387Z,1683820307.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-11T15:51:47.471Z,1683820307.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-11T15:51:47.623Z,1683820307.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-11T15:51:47.745Z,1683820307.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-11T15:51:47.912Z,1683820307.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-11T15:51:48.163Z,1683820308.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-11T15:51:48.604Z,1683820308.604 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-11T15:51:48.960Z,1683820308.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-11T15:51:48.960Z,1683820308.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-11T15:51:49.054Z,1683820309.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-11T15:51:49.152Z,1683820309.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-11T15:51:49.288Z,1683820309.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-11T15:51:49.387Z,1683820309.387 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-05-11T15:51:49.388Z,1683820309.388 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-11T15:51:49.396Z,1683820309.396 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-11T15:51:49.502Z,1683820309.502 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-11T15:51:49.503Z,1683820309.503 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-11T15:51:49.557Z,1683820309.557 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-11T15:51:49.615Z,1683820309.615 [VerticalControl] Loaded
2023-05-11T15:51:49.615Z,1683820309.615 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-11T15:51:49.618Z,1683820309.618 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-11T15:51:49.658Z,1683820309.658 [HorizontalControl] Loaded
2023-05-11T15:51:49.659Z,1683820309.659 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-11T15:51:49.661Z,1683820309.661 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-11T15:51:49.664Z,1683820309.664 [SpeedControl] Loaded
2023-05-11T15:51:49.664Z,1683820309.664 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-11T15:51:49.667Z,1683820309.667 [LoopControl](DEBUG): Construct LoopControl.
2023-05-11T15:51:49.667Z,1683820309.667 [LoopControl] Loaded
2023-05-11T15:51:49.668Z,1683820309.668 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-11T15:51:49.668Z,1683820309.668 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-11T15:51:49.668Z,1683820309.668 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-11T15:51:49.720Z,1683820309.720 [DepthRateCalculator] Loaded
2023-05-11T15:51:49.720Z,1683820309.720 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-11T15:51:49.725Z,1683820309.725 [PitchRateCalculator] Loaded
2023-05-11T15:51:49.725Z,1683820309.725 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-11T15:51:49.735Z,1683820309.735 [SpeedCalculator] Loaded
2023-05-11T15:51:49.736Z,1683820309.736 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-11T15:51:49.740Z,1683820309.740 [YawRateCalculator] Loaded
2023-05-11T15:51:49.740Z,1683820309.740 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-11T15:51:49.758Z,1683820309.758 [ElevatorOffsetCalculator] Loaded
2023-05-11T15:51:49.758Z,1683820309.758 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-11T15:51:49.758Z,1683820309.758 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-11T15:51:49.759Z,1683820309.759 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-11T15:51:49.787Z,1683820309.787 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-11T15:51:49.788Z,1683820309.788 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-11T15:51:49.862Z,1683820309.862 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-11T15:51:49.863Z,1683820309.863 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-11T15:51:50.195Z,1683820310.195 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-11T15:51:50.195Z,1683820310.195 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-11T15:51:50.353Z,1683820310.353 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-11T15:51:50.354Z,1683820310.354 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-11T15:51:50.818Z,1683820310.818 [AHRS_M2] Loaded
2023-05-11T15:51:50.818Z,1683820310.818 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-11T15:51:50.854Z,1683820310.854 [BackseatComponent] Loaded
2023-05-11T15:51:50.854Z,1683820310.854 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-11T15:51:50.855Z,1683820310.855 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409984E0
2023-05-11T15:51:50.855Z,1683820310.855 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5510
2023-05-11T15:51:50.858Z,1683820310.858 [LcmUniversalReporter] Loaded
2023-05-11T15:51:50.858Z,1683820310.858 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-11T15:51:51.685Z,1683820311.685 [BPC1] Loaded
2023-05-11T15:51:51.685Z,1683820311.685 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-11T15:51:51.769Z,1683820311.769 [DATMMP] Loaded
2023-05-11T15:51:51.769Z,1683820311.769 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-11T15:51:51.770Z,1683820311.770 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409C94E0
2023-05-11T15:51:51.770Z,1683820311.770 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 5511
2023-05-11T15:51:51.840Z,1683820311.840 [DataOverHttps] Loaded
2023-05-11T15:51:51.840Z,1683820311.840 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-11T15:51:51.841Z,1683820311.841 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F94E0
2023-05-11T15:51:51.842Z,1683820311.842 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5512
2023-05-11T15:51:51.862Z,1683820311.862 [Depth_Keller] Loaded
2023-05-11T15:51:51.863Z,1683820311.863 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-11T15:51:51.926Z,1683820311.926 [NAL9602] Loaded
2023-05-11T15:51:51.927Z,1683820311.927 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-11T15:51:51.956Z,1683820311.956 [Onboard] Loaded
2023-05-11T15:51:51.957Z,1683820311.957 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-11T15:51:51.958Z,1683820311.958 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A294E0
2023-05-11T15:51:51.958Z,1683820311.958 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5513
2023-05-11T15:51:51.971Z,1683820311.971 [Power24vConverter] Loaded
2023-05-11T15:51:51.971Z,1683820311.971 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-11T15:51:51.985Z,1683820311.985 [Radio_Surface] Loaded
2023-05-11T15:51:51.985Z,1683820311.985 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-11T15:51:51.986Z,1683820311.986 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2023-05-11T15:51:51.986Z,1683820311.986 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5514
2023-05-11T15:51:52.032Z,1683820312.032 [RDI_Pathfinder] Loaded
2023-05-11T15:51:52.032Z,1683820312.032 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-11T15:51:52.033Z,1683820312.033 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-11T15:51:52.034Z,1683820312.034 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-11T15:51:52.147Z,1683820312.147 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-11T15:51:52.147Z,1683820312.147 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-11T15:51:52.160Z,1683820312.160 [NavChart] Loaded
2023-05-11T15:51:52.161Z,1683820312.161 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-11T15:51:52.166Z,1683820312.166 [UniversalFixResidualReporter] Loaded
2023-05-11T15:51:52.167Z,1683820312.167 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-11T15:51:52.167Z,1683820312.167 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-11T15:51:52.168Z,1683820312.168 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-11T15:51:52.319Z,1683820312.319 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-11T15:51:52.328Z,1683820312.328 [SBIT] Loaded
2023-05-11T15:51:52.328Z,1683820312.328 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-11T15:51:52.331Z,1683820312.331 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-11T15:51:52.343Z,1683820312.343 [IBIT] Loaded
2023-05-11T15:51:52.343Z,1683820312.343 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-11T15:51:52.349Z,1683820312.349 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-11T15:51:52.451Z,1683820312.451 [CBIT] Loaded
2023-05-11T15:51:52.451Z,1683820312.451 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-11T15:51:52.451Z,1683820312.451 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-11T15:51:52.452Z,1683820312.452 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-11T15:51:52.591Z,1683820312.591 [BuoyancyServo] Loaded
2023-05-11T15:51:52.591Z,1683820312.591 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-11T15:51:52.622Z,1683820312.622 [ElevatorServo] Loaded
2023-05-11T15:51:52.622Z,1683820312.622 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-11T15:51:52.642Z,1683820312.642 [MassServo] Loaded
2023-05-11T15:51:52.642Z,1683820312.642 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-11T15:51:52.660Z,1683820312.660 [RudderServo] Loaded
2023-05-11T15:51:52.661Z,1683820312.661 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-11T15:51:52.678Z,1683820312.678 [ThrusterServo] Loaded
2023-05-11T15:51:52.678Z,1683820312.678 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-11T15:51:52.678Z,1683820312.678 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-11T15:51:52.679Z,1683820312.679 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-11T15:51:52.689Z,1683820312.689 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-11T15:51:52.690Z,1683820312.690 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-11T15:51:52.869Z,1683820312.869 [CTD_Seabird] Loaded
2023-05-11T15:51:52.869Z,1683820312.869 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-11T15:51:52.870Z,1683820312.870 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA14E0
2023-05-11T15:51:52.871Z,1683820312.871 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5515
2023-05-11T15:51:52.891Z,1683820312.891 [PAR_Licor] Loaded
2023-05-11T15:51:52.891Z,1683820312.891 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-11T15:51:52.922Z,1683820312.922 [WetLabsBB2FL] Loaded
2023-05-11T15:51:52.922Z,1683820312.922 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-11T15:51:52.923Z,1683820312.923 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD14E0
2023-05-11T15:51:52.924Z,1683820312.924 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5516
2023-05-11T15:51:52.943Z,1683820312.943 [WetLabsUBAT] Loaded
2023-05-11T15:51:52.944Z,1683820312.944 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-11T15:51:52.944Z,1683820312.944 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C014E0
2023-05-11T15:51:52.945Z,1683820312.945 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 5517
2023-05-11T15:51:52.945Z,1683820312.945 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-11T15:51:52.000Z,1683820313.000 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-11T15:51:53.002Z,1683820313.002 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-11T15:51:53.092Z,1683820313.092 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-11T15:51:53.093Z,1683820313.093 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C314E0
2023-05-11T15:51:53.094Z,1683820313.094 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5518
2023-05-11T15:51:53.099Z,1683820313.099 [Supervisor](INFO): Main Thread ID is 5422
2023-05-11T15:51:53.099Z,1683820313.099 [Supervisor](DEBUG): Running supervisor.
2023-05-11T15:51:53.102Z,1683820313.102 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5519
2023-05-11T15:51:53.102Z,1683820313.102 [CommandExec](INFO): Initializing the command executive.
2023-05-11T15:51:53.144Z,1683820313.144 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5520
2023-05-11T15:51:53.146Z,1683820313.146 [controlThread ThreadHandler](INFO): Handler Thread ID is 5521
2023-05-11T15:51:53.148Z,1683820313.148 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5523
2023-05-11T15:51:53.148Z,1683820313.148 [controlThread](DEBUG): Initializing ControlThread
2023-05-11T15:51:53.149Z,1683820313.149 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-11T15:51:53.151Z,1683820313.151 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-11T15:51:53.152Z,1683820313.152 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-11T15:51:53.152Z,1683820313.152 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-11T15:51:53.152Z,1683820313.152 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-11T15:51:53.153Z,1683820313.153 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-11T15:51:53.153Z,1683820313.153 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-11T15:51:53.153Z,1683820313.153 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-11T15:51:53.153Z,1683820313.153 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-11T15:51:53.157Z,1683820313.157 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-11T15:51:53.157Z,1683820313.157 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-11T15:51:53.157Z,1683820313.157 [SBIT](INFO): Initialize SBIT Component.
2023-05-11T15:51:53.158Z,1683820313.158 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-11T15:51:53.158Z,1683820313.158 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-11T15:51:53.158Z,1683820313.158 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-11T15:51:53.160Z,1683820313.160 [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-11T15:51:53.161Z,1683820313.161 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-11T15:51:53.161Z,1683820313.161 [IBIT](INFO): Initialize IBIT Component.
2023-05-11T15:51:53.162Z,1683820313.162 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-11T15:51:53.147Z,1683820313.147 [logger ThreadHandler](INFO): Handler Thread ID is 5522
2023-05-11T15:51:53.196Z,1683820313.196 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5525
2023-05-11T15:51:53.197Z,1683820313.197 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-11T15:51:53.198Z,1683820313.198 [Onboard ThreadHandler](INFO): Handler Thread ID is 5526
2023-05-11T15:51:53.199Z,1683820313.199 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5527
2023-05-11T15:51:53.200Z,1683820313.200 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5528
2023-05-11T15:51:53.200Z,1683820313.200 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 5530
2023-05-11T15:51:53.202Z,1683820313.202 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-11T15:51:53.206Z,1683820313.206 [CBIT](DEBUG): Initialized mux pins.
2023-05-11T15:51:53.206Z,1683820313.206 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-11T15:51:53.207Z,1683820313.207 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5531
2023-05-11T15:51:53.210Z,1683820313.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-11T15:51:53.210Z,1683820313.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-11T15:51:53.210Z,1683820313.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-11T15:51:53.210Z,1683820313.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-11T15:51:53.210Z,1683820313.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-11T15:51:53.211Z,1683820313.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-11T15:51:53.211Z,1683820313.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-11T15:51:53.211Z,1683820313.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-11T15:51:53.219Z,1683820313.219 [DATMMP ThreadHandler](INFO): Handler Thread ID is 5524
2023-05-11T15:51:53.220Z,1683820313.220 [DATMMP](INFO): Start
2023-05-11T15:51:53.220Z,1683820313.220 [DATMMP](INFO): Powering up
2023-05-11T15:51:53.221Z,1683820313.221 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-11T15:51:53.225Z,1683820313.225 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5529
2023-05-11T15:51:53.232Z,1683820313.232 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-11T15:51:53.232Z,1683820313.232 [CBIT](DEBUG): Initializing heartbeat.
2023-05-11T15:51:53.245Z,1683820313.245 [WetLabsUBAT](INFO): Powering up
2023-05-11T15:51:53.264Z,1683820313.264 [WetLabsBB2FL](INFO): Powering up
2023-05-11T15:51:53.303Z,1683820313.303 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-11T15:51:53.303Z,1683820313.303 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-11T15:51:53.343Z,1683820313.343 [CBIT](DEBUG): Backplane powered.
2023-05-11T15:51:53.348Z,1683820313.348 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-11T15:51:53.361Z,1683820313.361 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-11T15:51:53.399Z,1683820313.399 [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-11T15:51:53.400Z,1683820313.400 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-11T15:51:53.471Z,1683820313.471 [DATMMP](INFO): Starting
2023-05-11T15:51:53.558Z,1683820313.558 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-11T15:51:53.559Z,1683820313.559 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-11T15:51:53.562Z,1683820313.562 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-11T15:51:53.604Z,1683820313.604 [Radio_Surface](INFO): Powering up
2023-05-11T15:51:53.626Z,1683820313.626 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-11T15:51:53.641Z,1683820313.641 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-11T15:51:53.659Z,1683820313.659 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-11T15:51:53.682Z,1683820313.682 [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-11T15:51:53.687Z,1683820313.687 [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-11T15:51:53.693Z,1683820313.693 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-11T15:51:53.723Z,1683820313.723 [DATMMP](INFO): Starting
2023-05-11T15:51:53.777Z,1683820313.777 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-11T15:51:53.778Z,1683820313.778 [Power24vConverter](INFO): Powering up.
2023-05-11T15:51:53.799Z,1683820313.799 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-11T15:51:53.882Z,1683820313.882 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-11T15:51:53.917Z,1683820313.917 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-11T15:51:53.924Z,1683820313.924 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-11T15:51:53.925Z,1683820313.925 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-11T15:51:53.932Z,1683820313.932 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-11T15:51:53.933Z,1683820313.933 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-11T15:51:53.940Z,1683820313.940 [MassServo](DEBUG): Initializing MassServo.
2023-05-11T15:51:53.940Z,1683820313.940 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-11T15:51:53.948Z,1683820313.948 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-11T15:51:53.949Z,1683820313.949 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-11T15:51:53.956Z,1683820313.956 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-11T15:51:53.975Z,1683820313.975 [DATMMP](INFO): Starting
2023-05-11T15:51:54.228Z,1683820314.228 [DATMMP](INFO): Starting
2023-05-11T15:51:54.479Z,1683820314.479 [DATMMP](INFO): Starting
2023-05-11T15:51:54.731Z,1683820314.731 [DATMMP](INFO): Starting
2023-05-11T15:51:54.983Z,1683820314.983 [DATMMP](INFO): Starting
2023-05-11T15:51:55.235Z,1683820315.235 [DATMMP](INFO): Starting
2023-05-11T15:51:55.487Z,1683820315.487 [DATMMP](INFO): Starting
2023-05-11T15:51:55.739Z,1683820315.739 [DATMMP](INFO): Starting
2023-05-11T15:51:55.844Z,1683820315.844 [WetLabsBB2FL](INFO): Powering down
2023-05-11T15:51:55.991Z,1683820315.991 [DATMMP](INFO): Starting
2023-05-11T15:51:56.244Z,1683820316.244 [DATMMP](INFO): Starting
2023-05-11T15:51:56.495Z,1683820316.495 [DATMMP](INFO): Starting
2023-05-11T15:51:56.747Z,1683820316.747 [DATMMP](INFO): Starting
2023-05-11T15:51:56.999Z,1683820316.999 [DATMMP](INFO): Starting
2023-05-11T15:51:57.251Z,1683820317.251 [DATMMP](INFO): Starting
2023-05-11T15:51:57.504Z,1683820317.504 [DATMMP](INFO): Starting
2023-05-11T15:51:57.755Z,1683820317.755 [DATMMP](INFO): Starting
2023-05-11T15:51:58.007Z,1683820318.007 [DATMMP](INFO): Starting
2023-05-11T15:51:58.259Z,1683820318.259 [DATMMP](INFO): Starting
2023-05-11T15:51:58.511Z,1683820318.511 [DATMMP](INFO): Starting
2023-05-11T15:51:58.763Z,1683820318.763 [DATMMP](INFO): Starting
2023-05-11T15:51:59.017Z,1683820319.017 [DATMMP](INFO): Starting
2023-05-11T15:51:59.267Z,1683820319.267 [DATMMP](INFO): Starting
2023-05-11T15:51:59.520Z,1683820319.520 [DATMMP](INFO): Starting
2023-05-11T15:51:59.771Z,1683820319.771 [DATMMP](INFO): Starting
2023-05-11T15:52:00.023Z,1683820320.023 [DATMMP](INFO): Starting
2023-05-11T15:52:00.276Z,1683820320.276 [DATMMP](INFO): Starting
2023-05-11T15:52:00.528Z,1683820320.528 [DATMMP](INFO): Starting
2023-05-11T15:52:00.779Z,1683820320.779 [DATMMP](INFO): Starting
2023-05-11T15:52:01.033Z,1683820321.033 [DATMMP](INFO): Starting
2023-05-11T15:52:01.283Z,1683820321.283 [DATMMP](INFO): Starting
2023-05-11T15:52:01.536Z,1683820321.536 [DATMMP](INFO): Starting
2023-05-11T15:52:01.787Z,1683820321.787 [DATMMP](INFO): Starting
2023-05-11T15:52:02.039Z,1683820322.039 [DATMMP](INFO): Starting
2023-05-11T15:52:02.292Z,1683820322.292 [DATMMP](INFO): Starting
2023-05-11T15:52:02.511Z,1683820322.511 [WetLabsUBAT](INFO): Powering down
2023-05-11T15:52:02.544Z,1683820322.544 [DATMMP](INFO): Starting
2023-05-11T15:52:02.795Z,1683820322.795 [DATMMP](INFO): Starting
2023-05-11T15:52:03.047Z,1683820323.047 [DATMMP](INFO): Starting
2023-05-11T15:52:03.300Z,1683820323.300 [DATMMP](INFO): Starting
2023-05-11T15:52:03.551Z,1683820323.551 [DATMMP](INFO): Starting
2023-05-11T15:52:03.803Z,1683820323.803 [DATMMP](INFO): Starting
2023-05-11T15:52:04.055Z,1683820324.055 [DATMMP](INFO): Starting
2023-05-11T15:52:04.307Z,1683820324.307 [DATMMP](INFO): Starting
2023-05-11T15:52:04.559Z,1683820324.559 [DATMMP](INFO): Starting
2023-05-11T15:52:04.811Z,1683820324.811 [DATMMP](INFO): Starting
2023-05-11T15:52:05.063Z,1683820325.063 [DATMMP](INFO): Starting
2023-05-11T15:52:05.317Z,1683820325.317 [DATMMP](INFO): Starting
2023-05-11T15:52:05.567Z,1683820325.567 [DATMMP](INFO): Starting
2023-05-11T15:52:05.819Z,1683820325.819 [DATMMP](INFO): Starting
2023-05-11T15:52:05.958Z,1683820325.958 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004809
2023-05-11T15:52:06.072Z,1683820326.072 [DATMMP](INFO): Starting
2023-05-11T15:52:06.324Z,1683820326.324 [DATMMP](INFO): Starting
2023-05-11T15:52:06.576Z,1683820326.576 [DATMMP](INFO): Starting
2023-05-11T15:52:06.827Z,1683820326.827 [DATMMP](INFO): Starting
2023-05-11T15:52:07.080Z,1683820327.080 [DATMMP](INFO): Starting
2023-05-11T15:52:07.080Z,1683820327.080 [DATMMP](INFO): DAT read: 

2023-05-11T15:52:07.081Z,1683820327.081 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-11T15:52:07.332Z,1683820327.332 [DATMMP](INFO): Starting
2023-05-11T15:52:07.584Z,1683820327.584 [DATMMP](INFO): Starting
2023-05-11T15:52:07.836Z,1683820327.836 [DATMMP](INFO): Starting
2023-05-11T15:52:08.087Z,1683820328.087 [DATMMP](INFO): Starting
2023-05-11T15:52:08.340Z,1683820328.340 [DATMMP](INFO): Starting
2023-05-11T15:52:08.592Z,1683820328.592 [DATMMP](INFO): Starting
2023-05-11T15:52:08.844Z,1683820328.844 [DATMMP](INFO): Starting
2023-05-11T15:52:08.844Z,1683820328.844 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-11T15:52:08.845Z,1683820328.845 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-11T15:52:08.846Z,1683820328.846 [DATMMP](INFO): DAT read: Apr 10 2000 23:03:05 

2023-05-11T15:52:09.095Z,1683820329.095 [DATMMP](INFO): Starting
2023-05-11T15:52:09.349Z,1683820329.349 [DATMMP](INFO): Starting
2023-05-11T15:52:09.600Z,1683820329.600 [DATMMP](INFO): Starting
2023-05-11T15:52:09.852Z,1683820329.852 [DATMMP](INFO): Starting
2023-05-11T15:52:09.852Z,1683820329.852 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-11T15:52:09.854Z,1683820329.854 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-11T15:52:09.854Z,1683820329.854 [DATMMP](INFO): commRate: 800
2023-05-11T15:52:10.104Z,1683820330.104 [DATMMP](INFO): Starting
2023-05-11T15:52:10.358Z,1683820330.358 [DATMMP](INFO): Starting
2023-05-11T15:52:10.607Z,1683820330.607 [DATMMP](INFO): Starting
2023-05-11T15:52:10.860Z,1683820330.860 [DATMMP](INFO): Starting
2023-05-11T15:52:11.111Z,1683820331.111 [DATMMP](INFO): Starting
2023-05-11T15:52:11.364Z,1683820331.364 [DATMMP](INFO): Starting
2023-05-11T15:52:11.616Z,1683820331.616 [DATMMP](INFO): Starting
2023-05-11T15:52:11.867Z,1683820331.867 [DATMMP](INFO): Starting
2023-05-11T15:52:11.919Z,1683820331.919 [DATMMP](INFO): entering command mode
2023-05-11T15:52:12.120Z,1683820332.120 [DATMMP](INFO): Starting
2023-05-11T15:52:12.120Z,1683820332.120 [DATMMP](INFO): DAT read: 

2023-05-11T15:52:12.120Z,1683820332.120 [DATMMP](INFO): DAT read: user:1>

2023-05-11T15:52:12.121Z,1683820332.121 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-11T15:52:12.121Z,1683820332.121 [DATMMP](INFO): entering MMP mode
2023-05-11T15:52:12.372Z,1683820332.372 [DATMMP](INFO): Starting
2023-05-11T15:52:12.372Z,1683820332.372 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:52:12.374Z,1683820332.374 [DATMMP](INFO): status rx: 	 x1
2023-05-11T15:52:12.374Z,1683820332.374 [DATMMP](INFO): MMP status: 1: started
2023-05-11T15:52:12.374Z,1683820332.374 [DATMMP](INFO): Received message type: status
2023-05-11T15:52:12.375Z,1683820332.375 [DATMMP](INFO): Handled
2023-05-11T15:52:12.624Z,1683820332.624 [DATMMP](INFO): Starting
2023-05-11T15:52:12.625Z,1683820332.625 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-11T15:52:12.626Z,1683820332.626 [DATMMP](INFO): Setting local address: 10
2023-05-11T15:52:12.628Z,1683820332.628 [DATMMP](INFO): Setting time to: 15:52:12 And date to:5/11/2023
2023-05-11T15:52:12.876Z,1683820332.876 [DATMMP](INFO): Starting
2023-05-11T15:52:12.876Z,1683820332.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:52:12.877Z,1683820332.877 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-11T15:52:12.877Z,1683820332.877 [DATMMP](INFO): Received message type: mdmpower
2023-05-11T15:52:12.877Z,1683820332.877 [DATMMP](INFO): Handled
2023-05-11T15:52:13.128Z,1683820333.128 [DATMMP](INFO): Starting
2023-05-11T15:52:13.128Z,1683820333.128 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-11T15:52:13.129Z,1683820333.129 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-11T15:52:13.129Z,1683820333.129 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-11T15:52:13.129Z,1683820333.129 [DATMMP](INFO): Match for : sreg_txpower
2023-05-11T15:52:13.129Z,1683820333.129 [DATMMP](INFO): ACK
2023-05-11T15:52:13.380Z,1683820333.380 [DATMMP](INFO): Starting
2023-05-11T15:52:13.380Z,1683820333.380 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:52:13.381Z,1683820333.381 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-11T15:52:13.381Z,1683820333.381 [DATMMP](INFO): Received message type: mdmlocal
2023-05-11T15:52:13.381Z,1683820333.381 [DATMMP](INFO): Handled
2023-05-11T15:52:13.632Z,1683820333.632 [DATMMP](INFO): Starting
2023-05-11T15:52:13.632Z,1683820333.632 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-11T15:52:13.633Z,1683820333.633 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-11T15:52:13.633Z,1683820333.633 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-11T15:52:13.633Z,1683820333.633 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-11T15:52:13.633Z,1683820333.633 [DATMMP](INFO): ACK
2023-05-11T15:52:13.884Z,1683820333.884 [DATMMP](INFO): Starting
2023-05-11T15:52:13.884Z,1683820333.884 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-11T15:52:13.885Z,1683820333.885 [DATMMP](INFO): timedate rx: 	 x0	 xF	 x34	 xC	 x4	 xB	 x7B	 x0
2023-05-11T15:52:13.886Z,1683820333.886 [DATMMP](INFO): Received message type: timedate
2023-05-11T15:52:13.886Z,1683820333.886 [DATMMP](INFO): Match for : timedate
2023-05-11T15:52:13.886Z,1683820333.886 [DATMMP](INFO): ACK
2023-05-11T15:52:14.135Z,1683820334.135 [DATMMP](INFO): Starting
2023-05-11T15:52:14.136Z,1683820334.136 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:52:14.137Z,1683820334.137 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-11T15:52:14.137Z,1683820334.137 [DATMMP](INFO): Received message type: sys_verb
2023-05-11T15:52:14.137Z,1683820334.137 [DATMMP](INFO): Handled
2023-05-11T15:52:14.388Z,1683820334.388 [DATMMP](INFO): Starting
2023-05-11T15:52:14.388Z,1683820334.388 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-11T15:52:14.389Z,1683820334.389 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-11T15:52:14.389Z,1683820334.389 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-11T15:52:14.389Z,1683820334.389 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-11T15:52:14.389Z,1683820334.389 [DATMMP](INFO): ACK
2023-05-11T15:52:14.640Z,1683820334.640 [DATMMP](INFO): Starting
2023-05-11T15:52:14.640Z,1683820334.640 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-11T15:52:14.641Z,1683820334.641 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-11T15:52:14.641Z,1683820334.641 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-11T15:52:14.641Z,1683820334.641 [DATMMP](INFO): Received message type: version
2023-05-11T15:52:14.641Z,1683820334.641 [DATMMP](INFO): Match for : version
2023-05-11T15:52:14.642Z,1683820334.642 [DATMMP](INFO): ACK
2023-05-11T15:52:14.642Z,1683820334.642 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-11T15:52:22.037Z,1683820342.037 [NAL9602](INFO): Powering up NAL9602
2023-05-11T15:52:27.768Z,1683820347.768 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:52:27.769Z,1683820347.769 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-11T15:52:27.769Z,1683820347.769 [DATMMP](INFO): Received message type: timesync
2023-05-11T15:52:27.769Z,1683820347.769 [DATMMP](INFO): Handled
2023-05-11T15:52:32.945Z,1683820352.945 [NAL9602](INFO): NAL9602 initialized
2023-05-11T15:52:37.864Z,1683820357.864 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:52:37.865Z,1683820357.865 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-11T15:52:37.866Z,1683820357.866 [DATMMP](INFO): Received message type: timesync
2023-05-11T15:52:37.866Z,1683820357.866 [DATMMP](INFO): Handled
2023-05-11T15:52:44.707Z,1683820364.707 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-11T15:52:44.715Z,1683820364.715 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-11T15:52:55.660Z,1683820375.660 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002913
CHAN A1 (24V): 0.000060
CHAN A2 (12V): -0.007211
CHAN A3 (5V): -0.002499
CHAN B0 (3.3V): -0.000151
CHAN B1 (3.15aV): 0.000320
CHAN B2 (3.15bV): -0.000596
CHAN B3 (GND): 0.002034
OPEN: 0.005135
Full Scale: +/- 1 mA
2023-05-11T15:53:38.814Z,1683820418.814 [SBIT](IMPORTANT): SBIT PASSED
2023-05-11T15:53:38.814Z,1683820418.814 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-11T15:53:38.831Z,1683820418.831 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-11T15:53:38.831Z,1683820418.831 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-11T15:53:38.832Z,1683820418.832 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-11T15:53:38.832Z,1683820418.832 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-11T15:53:38.832Z,1683820418.832 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-11T15:53:38.832Z,1683820418.832 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-11T15:53:38.832Z,1683820418.832 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-11T15:53:39.221Z,1683820419.221 [MissionManager](IMPORTANT): Started mission Startup
2023-05-11T15:53:39.222Z,1683820419.222 [Startup] Running Loop=1
2023-05-11T15:53:39.222Z,1683820419.222 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-11T15:53:39.222Z,1683820419.222 [Startup:A.GoToSurface] Running Loop=1
2023-05-11T15:53:39.222Z,1683820419.222 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-11T15:53:39.222Z,1683820419.222 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-11T15:53:39.223Z,1683820419.223 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-11T15:53:39.224Z,1683820419.224 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-11T15:53:39.224Z,1683820419.224 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-11T15:53:39.225Z,1683820419.225 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-11T15:53:39.226Z,1683820419.226 [Startup:StartupSatComms] Running Loop=1
2023-05-11T15:53:39.226Z,1683820419.226 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-11T15:53:39.226Z,1683820419.226 [Startup:StartupSatComms:A] Running Loop=1
2023-05-11T15:53:39.643Z,1683820419.643 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-11T15:54:11.553Z,1683820451.553 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2023-05-11T15:54:33.425Z,1683820473.425 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2023-05-11T15:54:36.013Z,1683820476.013 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-11T15:54:36.016Z,1683820476.016 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-11T15:54:36.017Z,1683820476.017 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-11T15:54:36.020Z,1683820476.020 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-11T15:54:36.021Z,1683820476.021 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-11T15:54:36.024Z,1683820476.024 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-11T15:54:36.024Z,1683820476.024 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-11T15:54:36.025Z,1683820476.025 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-11T15:54:36.269Z,1683820476.269 [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-11T15:54:36.618Z,1683820476.618 [Startup:StartupSatComms:A] Stopped
2023-05-11T15:54:36.618Z,1683820476.618 [Startup:StartupSatComms:B] Running Loop=1
2023-05-11T15:54:37.021Z,1683820477.021 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-11T15:54:44.075Z,1683820484.075 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230510T224509/Courier0007.lzma
2023-05-11T15:54:45.077Z,1683820485.077 [DataOverHttps](INFO): Moved sent file to Logs/20230510T224509/Courier0007.lzma.bak
2023-05-11T15:54:45.077Z,1683820485.077 [DataOverHttps](INFO): SBD MOMSN=18289275
2023-05-11T15:55:01.763Z,1683820501.763 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230511T155142/Courier0000.lzma
2023-05-11T15:55:02.765Z,1683820502.765 [DataOverHttps](INFO): Moved sent file to Logs/20230511T155142/Courier0000.lzma.bak
2023-05-11T15:55:02.765Z,1683820502.765 [DataOverHttps](INFO): SBD MOMSN=18289280
2023-05-11T15:55:19.031Z,1683820519.031 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20230510T224509/Express0008.lzma
2023-05-11T15:55:20.033Z,1683820520.033 [DataOverHttps](INFO): Moved sent file to Logs/20230510T224509/Express0008.lzma.bak
2023-05-11T15:55:20.033Z,1683820520.033 [DataOverHttps](INFO): SBD MOMSN=18289315
2023-05-11T15:55:36.409Z,1683820536.409 [DataOverHttps](INFO): Sending 1068 bytes from file Logs/20230511T155142/Express0001.lzma
2023-05-11T15:55:36.784Z,1683820536.784 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-11T15:54:36.6Z
2023-05-11T15:55:36.784Z,1683820536.784 [Startup:StartupSatComms:B] Stopped
2023-05-11T15:55:36.784Z,1683820536.784 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-11T15:55:36.784Z,1683820536.784 [Startup:StartupSatComms] Stopped
2023-05-11T15:55:36.784Z,1683820536.784 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-11T15:55:36.785Z,1683820536.785 [Startup](INFO): Completed Startup
2023-05-11T15:55:36.785Z,1683820536.785 [MissionManager](INFO): Startup is completed.
2023-05-11T15:55:36.785Z,1683820536.785 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-11T15:55:36.785Z,1683820536.785 [Startup] Stopped
2023-05-11T15:55:36.785Z,1683820536.785 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-11T15:55:36.785Z,1683820536.785 [Startup:A.GoToSurface] Stopped
2023-05-11T15:55:36.785Z,1683820536.785 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-11T15:55:37.191Z,1683820537.191 [MissionManager](IMPORTANT): Started mission Default
2023-05-11T15:55:37.191Z,1683820537.191 [Default] Running Loop=1
2023-05-11T15:55:37.192Z,1683820537.192 [Default](DEBUG): Aggregate::initialize Default
2023-05-11T15:55:37.192Z,1683820537.192 [Default:B.GoToSurface] Running Loop=1
2023-05-11T15:55:37.192Z,1683820537.192 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-11T15:55:37.192Z,1683820537.192 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-11T15:55:37.192Z,1683820537.192 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-11T15:55:37.193Z,1683820537.193 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-11T15:55:37.193Z,1683820537.193 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-11T15:55:37.193Z,1683820537.193 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-11T15:55:37.193Z,1683820537.193 [Default:A.Wait] Running Loop=1
2023-05-11T15:55:37.193Z,1683820537.193 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-11T15:55:37.413Z,1683820537.413 [DataOverHttps](INFO): Moved sent file to Logs/20230511T155142/Express0001.lzma.bak
2023-05-11T15:55:37.413Z,1683820537.413 [DataOverHttps](INFO): SBD MOMSN=18289319
2023-05-11T15:55:50.527Z,1683820550.527 [Default:A.Wait](INFO): Done Waiting.
2023-05-11T15:55:50.528Z,1683820550.528 [Default:A.Wait] Stopped
2023-05-11T15:55:50.528Z,1683820550.528 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T15:55:50.924Z,1683820550.924 [Default:CheckIn] Running Loop=1
2023-05-11T15:55:50.924Z,1683820550.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T15:55:50.924Z,1683820550.924 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T15:55:51.375Z,1683820551.375 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-11T15:57:36.355Z,1683820656.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T15:58:15.027Z,1683820695.027 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683820656 second
2023-05-11T15:58:15.165Z,1683820695.165 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T15:58:15.165Z,1683820695.165 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T15:58:15.573Z,1683820695.573 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-11T15:58:22.483Z,1683820702.483 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230511T155142/Courier0004.lzma
2023-05-11T15:58:23.485Z,1683820703.485 [DataOverHttps](INFO): Moved sent file to Logs/20230511T155142/Courier0004.lzma.bak
2023-05-11T15:58:23.485Z,1683820703.485 [DataOverHttps](INFO): SBD MOMSN=18289375
2023-05-11T15:58:40.067Z,1683820720.067 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20230511T155142/Express0005.lzma
2023-05-11T15:58:40.829Z,1683820720.829 [DataOverHttps](INFO): Moved sent file to Logs/20230511T155142/Express0005.lzma.bak
2023-05-11T15:58:40.829Z,1683820720.829 [DataOverHttps](INFO): SBD MOMSN=18289920
2023-05-11T15:58:42.224Z,1683820722.224 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T15:58:42.224Z,1683820722.224 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T15:58:42.224Z,1683820722.224 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T15:58:47.061Z,1683820727.061 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T15:59:08.760Z,1683820748.760 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:08.761Z,1683820748.761 [DATMMP](INFO): doppler rx: 	 x0
2023-05-11T15:59:08.761Z,1683820748.761 [DATMMP](INFO): Received message type: doppler
2023-05-11T15:59:08.761Z,1683820748.761 [DATMMP](INFO): Handled
2023-05-11T15:59:09.012Z,1683820749.012 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:09.013Z,1683820749.013 [DATMMP](INFO): timestamp rx: 	 x1	 xF	 x3B	 x6	 x1FCC
2023-05-11T15:59:09.014Z,1683820749.014 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820761.516096
2023-05-11T15:59:09.014Z,1683820749.014 [DATMMP](INFO): Rx ping set to:1683820749.013265
2023-05-11T15:59:09.014Z,1683820749.014 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:09.015Z,1683820749.015 [DATMMP](INFO): Handled
2023-05-11T15:59:09.520Z,1683820749.520 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:09.521Z,1683820749.521 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T15:59:09.521Z,1683820749.521 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T15:59:09.521Z,1683820749.521 [DATMMP](INFO): Handled
2023-05-11T15:59:09.773Z,1683820749.773 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:09.774Z,1683820749.774 [DATMMP](INFO): directional_debug rx: 	 x199	 x101	 x1F	 x1F3	 x3490	 x4141	 x69D2	 x7AB3
2023-05-11T15:59:09.775Z,1683820749.775 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T15:59:09.775Z,1683820749.775 [DATMMP](INFO): Handled
2023-05-11T15:59:10.024Z,1683820750.024 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:10.026Z,1683820750.026 [DATMMP](INFO): directional rx: 	 x1BC	 xF0	 x443	 xFEFF	 xB00	 xFEFD	 x0	 x6BE	 x1	 x1	 x27B5966	 x-43569E0
2023-05-11T15:59:10.028Z,1683820750.028 [DATMMP](INFO): Received message type: directional
2023-05-11T15:59:10.028Z,1683820750.028 [DATMMP](INFO): Handled
2023-05-11T15:59:10.276Z,1683820750.276 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:10.277Z,1683820750.277 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T15:59:10.278Z,1683820750.278 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T15:59:10.278Z,1683820750.278 [DATMMP](INFO): Received message type: remote_header
2023-05-11T15:59:10.278Z,1683820750.278 [DATMMP](INFO): Handled
2023-05-11T15:59:10.528Z,1683820750.528 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:10.530Z,1683820750.530 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x83	 xAE	 xAC	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-11T15:59:10.530Z,1683820750.530 [DATMMP](INFO): Received message type: acstats
2023-05-11T15:59:10.530Z,1683820750.530 [DATMMP](INFO): Handled
2023-05-11T15:59:10.780Z,1683820750.780 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:10.781Z,1683820750.781 [DATMMP](INFO): timestamp rx: 	 x0	 xF	 x3B	 x8	 x1048
2023-05-11T15:59:10.781Z,1683820750.781 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:10.781Z,1683820750.781 [DATMMP](INFO): Handled
2023-05-11T15:59:23.900Z,1683820763.900 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:23.901Z,1683820763.901 [DATMMP](INFO): doppler rx: 	 x0
2023-05-11T15:59:23.901Z,1683820763.901 [DATMMP](INFO): Received message type: doppler
2023-05-11T15:59:23.901Z,1683820763.901 [DATMMP](INFO): Handled
2023-05-11T15:59:24.152Z,1683820764.152 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:24.153Z,1683820764.153 [DATMMP](INFO): timestamp rx: 	 x1	 xF	 x3B	 x15	 x25A7
2023-05-11T15:59:24.154Z,1683820764.154 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820791.137216
2023-05-11T15:59:24.154Z,1683820764.154 [DATMMP](INFO): Rx ping set to:1683820764.153069
2023-05-11T15:59:24.154Z,1683820764.154 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:24.154Z,1683820764.154 [DATMMP](INFO): Handled
2023-05-11T15:59:24.227Z,1683820764.227 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-11T15:59:24.230Z,1683820764.230 [BPC1](INFO): Received data from all battery sticks.
2023-05-11T15:59:24.656Z,1683820764.656 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:24.657Z,1683820764.657 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T15:59:24.657Z,1683820764.657 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T15:59:24.657Z,1683820764.657 [DATMMP](INFO): Handled
2023-05-11T15:59:24.908Z,1683820764.908 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:24.909Z,1683820764.909 [DATMMP](INFO): directional_debug rx: 	 x194	 x100	 x22	 x1F4	 x1D20	 x1A81	 x28B2	 x3533
2023-05-11T15:59:24.910Z,1683820764.910 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T15:59:24.910Z,1683820764.910 [DATMMP](INFO): Handled
2023-05-11T15:59:25.160Z,1683820765.160 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:25.162Z,1683820765.162 [DATMMP](INFO): directional rx: 	 x1B8	 xEF	 x448	 xFF00	 xB06	 xFF00	 x0	 x6BF	 x1	 xFFFF	 x27B5966	 x-43569E0
2023-05-11T15:59:25.163Z,1683820765.163 [DATMMP](INFO): Received message type: directional
2023-05-11T15:59:25.164Z,1683820765.164 [DATMMP](INFO): Handled
2023-05-11T15:59:25.412Z,1683820765.412 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:25.413Z,1683820765.413 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T15:59:25.414Z,1683820765.414 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T15:59:25.414Z,1683820765.414 [DATMMP](INFO): Received message type: remote_header
2023-05-11T15:59:25.414Z,1683820765.414 [DATMMP](INFO): Handled
2023-05-11T15:59:25.664Z,1683820765.664 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:25.666Z,1683820765.666 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x91	 xAF	 xAD	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-11T15:59:25.666Z,1683820765.666 [DATMMP](INFO): Received message type: acstats
2023-05-11T15:59:25.666Z,1683820765.666 [DATMMP](INFO): Handled
2023-05-11T15:59:25.916Z,1683820765.916 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:25.917Z,1683820765.917 [DATMMP](INFO): timestamp rx: 	 x0	 xF	 x3B	 x17	 x1623
2023-05-11T15:59:25.917Z,1683820765.917 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:25.918Z,1683820765.918 [DATMMP](INFO): Handled
2023-05-11T15:59:39.277Z,1683820779.277 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:39.286Z,1683820779.286 [DATMMP](INFO): doppler rx: 	 x0
2023-05-11T15:59:39.286Z,1683820779.286 [DATMMP](INFO): Received message type: doppler
2023-05-11T15:59:39.286Z,1683820779.286 [DATMMP](INFO): Handled
2023-05-11T15:59:39.528Z,1683820779.528 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:39.529Z,1683820779.529 [DATMMP](INFO): timestamp rx: 	 x1	 xF	 x3B	 x25	 xC42
2023-05-11T15:59:39.529Z,1683820779.529 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820807.675840
2023-05-11T15:59:39.530Z,1683820779.530 [DATMMP](INFO): Rx ping set to:1683820779.528985
2023-05-11T15:59:39.530Z,1683820779.530 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:39.530Z,1683820779.530 [DATMMP](INFO): Handled
2023-05-11T15:59:40.032Z,1683820780.032 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:40.033Z,1683820780.033 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T15:59:40.033Z,1683820780.033 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T15:59:40.033Z,1683820780.033 [DATMMP](INFO): Handled
2023-05-11T15:59:40.284Z,1683820780.284 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:40.285Z,1683820780.285 [DATMMP](INFO): directional_debug rx: 	 x1A6	 x110	 x1F	 x1D7	 x32B0	 x4091	 x6852	 x7BF3
2023-05-11T15:59:40.286Z,1683820780.286 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T15:59:40.286Z,1683820780.286 [DATMMP](INFO): Handled
2023-05-11T15:59:40.536Z,1683820780.536 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:40.538Z,1683820780.538 [DATMMP](INFO): directional rx: 	 x1C9	 xFA	 x436	 xFEF0	 xAF4	 xFEF2	 x0	 x6C0	 x3	 xFFFE	 x27B5966	 x-43569E0
2023-05-11T15:59:40.540Z,1683820780.540 [DATMMP](INFO): Received message type: directional
2023-05-11T15:59:40.540Z,1683820780.540 [DATMMP](INFO): Handled
2023-05-11T15:59:40.788Z,1683820780.788 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:40.789Z,1683820780.789 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T15:59:40.790Z,1683820780.790 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T15:59:40.790Z,1683820780.790 [DATMMP](INFO): Received message type: remote_header
2023-05-11T15:59:40.790Z,1683820780.790 [DATMMP](INFO): Handled
2023-05-11T15:59:41.040Z,1683820781.040 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:41.042Z,1683820781.042 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x22	 x82	 xAF	 xA9	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-11T15:59:41.042Z,1683820781.042 [DATMMP](INFO): Received message type: acstats
2023-05-11T15:59:41.043Z,1683820781.043 [DATMMP](INFO): Handled
2023-05-11T15:59:41.294Z,1683820781.294 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:41.296Z,1683820781.296 [DATMMP](INFO): timestamp rx: 	 x0	 xF	 x3B	 x26	 x23CE
2023-05-11T15:59:41.297Z,1683820781.297 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:41.297Z,1683820781.297 [DATMMP](INFO): Handled
2023-05-11T15:59:54.920Z,1683820794.920 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:54.921Z,1683820794.921 [DATMMP](INFO): doppler rx: 	 x0
2023-05-11T15:59:54.921Z,1683820794.921 [DATMMP](INFO): Received message type: doppler
2023-05-11T15:59:54.921Z,1683820794.921 [DATMMP](INFO): Handled
2023-05-11T15:59:55.172Z,1683820795.172 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:55.173Z,1683820795.173 [DATMMP](INFO): timestamp rx: 	 x1	 xF	 x3B	 x34	 x1DD6
2023-05-11T15:59:55.174Z,1683820795.174 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820802.618496
2023-05-11T15:59:55.174Z,1683820795.174 [DATMMP](INFO): Rx ping set to:1683820795.173017
2023-05-11T15:59:55.174Z,1683820795.174 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:55.174Z,1683820795.174 [DATMMP](INFO): Handled
2023-05-11T15:59:55.424Z,1683820795.424 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:55.425Z,1683820795.425 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T15:59:55.425Z,1683820795.425 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T15:59:55.425Z,1683820795.425 [DATMMP](INFO): Handled
2023-05-11T15:59:55.676Z,1683820795.676 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:55.677Z,1683820795.677 [DATMMP](INFO): directional_debug rx: 	 x19B	 xFD	 x1F	 x1CE	 x32F0	 x40D1	 x6812	 x72C3
2023-05-11T15:59:55.678Z,1683820795.678 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T15:59:55.678Z,1683820795.678 [DATMMP](INFO): Handled
2023-05-11T15:59:55.928Z,1683820795.928 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:55.930Z,1683820795.930 [DATMMP](INFO): directional rx: 	 x1C0	 xED	 x441	 xFF03	 xAFD	 xFF02	 x0	 x6BF	 x4	 xFFFF	 x27B5966	 x-43569E0
2023-05-11T15:59:55.932Z,1683820795.932 [DATMMP](INFO): Received message type: directional
2023-05-11T15:59:55.932Z,1683820795.932 [DATMMP](INFO): Handled
2023-05-11T15:59:56.180Z,1683820796.180 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:56.181Z,1683820796.181 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T15:59:56.182Z,1683820796.182 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T15:59:56.182Z,1683820796.182 [DATMMP](INFO): Received message type: remote_header
2023-05-11T15:59:56.182Z,1683820796.182 [DATMMP](INFO): Handled
2023-05-11T15:59:56.432Z,1683820796.432 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:56.434Z,1683820796.434 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x22	 x80	 xAF	 xAA	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-11T15:59:56.434Z,1683820796.434 [DATMMP](INFO): Received message type: acstats
2023-05-11T15:59:56.434Z,1683820796.434 [DATMMP](INFO): Handled
2023-05-11T15:59:56.684Z,1683820796.684 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T15:59:56.685Z,1683820796.685 [DATMMP](INFO): timestamp rx: 	 x0	 xF	 x3B	 x36	 xE52
2023-05-11T15:59:56.685Z,1683820796.685 [DATMMP](INFO): Received message type: timestamp
2023-05-11T15:59:56.685Z,1683820796.685 [DATMMP](INFO): Handled
2023-05-11T16:00:10.056Z,1683820810.056 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:10.057Z,1683820810.057 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-11T16:00:10.057Z,1683820810.057 [DATMMP](INFO): Received message type: doppler
2023-05-11T16:00:10.057Z,1683820810.057 [DATMMP](INFO): Handled
2023-05-11T16:00:10.308Z,1683820810.308 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:10.309Z,1683820810.309 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 x0	 x8	 x27C
2023-05-11T16:00:10.310Z,1683820810.310 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820814.221184
2023-05-11T16:00:10.310Z,1683820810.310 [DATMMP](INFO): Rx ping set to:1683820810.309267
2023-05-11T16:00:10.310Z,1683820810.310 [DATMMP](INFO): Received message type: timestamp
2023-05-11T16:00:10.310Z,1683820810.310 [DATMMP](INFO): Handled
2023-05-11T16:00:10.812Z,1683820810.812 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:10.813Z,1683820810.813 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T16:00:10.813Z,1683820810.813 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T16:00:10.813Z,1683820810.813 [DATMMP](INFO): Handled
2023-05-11T16:00:11.064Z,1683820811.064 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:11.065Z,1683820811.065 [DATMMP](INFO): directional_debug rx: 	 x19D	 xFC	 x1F	 x1DD	 x32D0	 x4071	 x6832	 x7893
2023-05-11T16:00:11.066Z,1683820811.066 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T16:00:11.066Z,1683820811.066 [DATMMP](INFO): Handled
2023-05-11T16:00:11.318Z,1683820811.318 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:11.322Z,1683820811.322 [DATMMP](INFO): directional rx: 	 x1C1	 xEC	 x43F	 xFF04	 xAFE	 xFF07	 x0	 x6C0	 x1	 xFFFC	 x27B5966	 x-43569E0
2023-05-11T16:00:11.328Z,1683820811.328 [DATMMP](INFO): Received message type: directional
2023-05-11T16:00:11.328Z,1683820811.328 [DATMMP](INFO): Handled
2023-05-11T16:00:11.568Z,1683820811.568 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:11.569Z,1683820811.569 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T16:00:11.570Z,1683820811.570 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T16:00:11.570Z,1683820811.570 [DATMMP](INFO): Received message type: remote_header
2023-05-11T16:00:11.570Z,1683820811.570 [DATMMP](INFO): Handled
2023-05-11T16:00:11.820Z,1683820811.820 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:11.822Z,1683820811.822 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x23	 x7E	 xAF	 xA8	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-11T16:00:11.822Z,1683820811.822 [DATMMP](INFO): Received message type: acstats
2023-05-11T16:00:11.822Z,1683820811.822 [DATMMP](INFO): Handled
2023-05-11T16:00:12.072Z,1683820812.072 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:12.073Z,1683820812.073 [DATMMP](INFO): timestamp rx: 	 x0	 x10	 x0	 x9	 x1A09
2023-05-11T16:00:12.073Z,1683820812.073 [DATMMP](INFO): Received message type: timestamp
2023-05-11T16:00:12.073Z,1683820812.073 [DATMMP](INFO): Handled
2023-05-11T16:00:25.444Z,1683820825.444 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:25.445Z,1683820825.445 [DATMMP](INFO): doppler rx: 	 x0
2023-05-11T16:00:25.445Z,1683820825.445 [DATMMP](INFO): Received message type: doppler
2023-05-11T16:00:25.445Z,1683820825.445 [DATMMP](INFO): Handled
2023-05-11T16:00:25.696Z,1683820825.696 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:25.697Z,1683820825.697 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 x0	 x17	 x121C
2023-05-11T16:00:25.698Z,1683820825.698 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820868.286720
2023-05-11T16:00:25.698Z,1683820825.698 [DATMMP](INFO): Rx ping set to:1683820825.697147
2023-05-11T16:00:25.698Z,1683820825.698 [DATMMP](INFO): Received message type: timestamp
2023-05-11T16:00:25.698Z,1683820825.698 [DATMMP](INFO): Handled
2023-05-11T16:00:26.200Z,1683820826.200 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:26.201Z,1683820826.201 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T16:00:26.201Z,1683820826.201 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T16:00:26.201Z,1683820826.201 [DATMMP](INFO): Handled
2023-05-11T16:00:26.452Z,1683820826.452 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:26.453Z,1683820826.453 [DATMMP](INFO): directional_debug rx: 	 x1A4	 x112	 x20	 x175	 x2C90	 x40F1	 x6722	 x7373
2023-05-11T16:00:26.454Z,1683820826.454 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T16:00:26.455Z,1683820826.455 [DATMMP](INFO): Handled
2023-05-11T16:00:26.704Z,1683820826.704 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:26.706Z,1683820826.706 [DATMMP](INFO): directional rx: 	 x1C7	 xFC	 x438	 xFEEE	 xAF7	 xFEF0	 x0	 x6BF	 x1	 xFFFE	 x27B5966	 x-43569E0
2023-05-11T16:00:26.708Z,1683820826.708 [DATMMP](INFO): Received message type: directional
2023-05-11T16:00:26.708Z,1683820826.708 [DATMMP](INFO): Handled
2023-05-11T16:00:26.956Z,1683820826.956 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:26.957Z,1683820826.957 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T16:00:26.958Z,1683820826.958 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T16:00:26.958Z,1683820826.958 [DATMMP](INFO): Received message type: remote_header
2023-05-11T16:00:26.958Z,1683820826.958 [DATMMP](INFO): Handled
2023-05-11T16:00:27.208Z,1683820827.208 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:27.210Z,1683820827.210 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7F	 xAF	 xA8	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-11T16:00:27.210Z,1683820827.210 [DATMMP](INFO): Received message type: acstats
2023-05-11T16:00:27.210Z,1683820827.210 [DATMMP](INFO): Handled
2023-05-11T16:00:27.460Z,1683820827.460 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:27.461Z,1683820827.461 [DATMMP](INFO): timestamp rx: 	 x0	 x10	 x0	 x19	 x299
2023-05-11T16:00:27.461Z,1683820827.461 [DATMMP](INFO): Received message type: timestamp
2023-05-11T16:00:27.461Z,1683820827.461 [DATMMP](INFO): Handled
2023-05-11T16:00:40.832Z,1683820840.832 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:40.833Z,1683820840.833 [DATMMP](INFO): doppler rx: 	 x0
2023-05-11T16:00:40.833Z,1683820840.833 [DATMMP](INFO): Received message type: doppler
2023-05-11T16:00:40.833Z,1683820840.833 [DATMMP](INFO): Handled
2023-05-11T16:00:41.084Z,1683820841.084 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:41.085Z,1683820841.085 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 x0	 x26	 x21BC
2023-05-11T16:00:41.086Z,1683820841.086 [DATMMP](INFO): Rx dataTimestamp_ set to:1683820858.352256
2023-05-11T16:00:41.086Z,1683820841.086 [DATMMP](INFO): Rx ping set to:1683820841.085093
2023-05-11T16:00:41.086Z,1683820841.086 [DATMMP](INFO): Received message type: timestamp
2023-05-11T16:00:41.086Z,1683820841.086 [DATMMP](INFO): Handled
2023-05-11T16:00:41.588Z,1683820841.588 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:41.589Z,1683820841.589 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-11T16:00:41.589Z,1683820841.589 [DATMMP](INFO): Received message type: nav_stat
2023-05-11T16:00:41.589Z,1683820841.589 [DATMMP](INFO): Handled
2023-05-11T16:00:41.840Z,1683820841.840 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:41.841Z,1683820841.841 [DATMMP](INFO): directional_debug rx: 	 x197	 x113	 x20	 x175	 x2C60	 x4631	 x6CF2	 x66B3
2023-05-11T16:00:41.842Z,1683820841.842 [DATMMP](INFO): Received message type: directional_debug
2023-05-11T16:00:41.843Z,1683820841.843 [DATMMP](INFO): Handled
2023-05-11T16:00:42.092Z,1683820842.092 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:42.094Z,1683820842.094 [DATMMP](INFO): directional rx: 	 x1B8	 xFD	 x445	 xFEED	 xB04	 xFEEF	 x0	 x6C0	 x2	 xFFFE	 x27B5966	 x-43569E0
2023-05-11T16:00:42.095Z,1683820842.095 [DATMMP](INFO): Received message type: directional
2023-05-11T16:00:42.096Z,1683820842.096 [DATMMP](INFO): Handled
2023-05-11T16:00:42.344Z,1683820842.344 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:42.346Z,1683820842.346 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-11T16:00:42.346Z,1683820842.346 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-11T16:00:42.346Z,1683820842.346 [DATMMP](INFO): Received message type: remote_header
2023-05-11T16:00:42.346Z,1683820842.346 [DATMMP](INFO): Handled
2023-05-11T16:00:42.596Z,1683820842.596 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:42.598Z,1683820842.598 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x80	 xB0	 xA9	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-11T16:00:42.599Z,1683820842.599 [DATMMP](INFO): Received message type: acstats
2023-05-11T16:00:42.600Z,1683820842.600 [DATMMP](INFO): Handled
2023-05-11T16:00:42.848Z,1683820842.848 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:00:42.849Z,1683820842.849 [DATMMP](INFO): timestamp rx: 	 x0	 x10	 x0	 x28	 x1239
2023-05-11T16:00:42.849Z,1683820842.849 [DATMMP](INFO): Received message type: timestamp
2023-05-11T16:00:42.849Z,1683820842.849 [DATMMP](INFO): Handled