2023-05-12T18:29:48.580Z,1683916188.580 [Supervisor](DEBUG): Initializing supervisor.
2023-05-12T18:29:48.584Z,1683916188.584 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-12T18:29:48.584Z,1683916188.584 [SyncHandler](INFO): Protected caller Thread ID is 7084
2023-05-12T18:29:48.585Z,1683916188.585 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-12T18:29:48.586Z,1683916188.586 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-12T18:29:48.586Z,1683916188.586 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7085
2023-05-12T18:29:48.590Z,1683916188.590 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-12T18:29:48.608Z,1683916188.608 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-12T18:29:48.609Z,1683916188.609 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-12T18:29:48.609Z,1683916188.609 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7086
2023-05-12T18:29:48.613Z,1683916188.613 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-12T18:29:48.614Z,1683916188.614 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-12T18:29:48.614Z,1683916188.614 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7087
2023-05-12T18:29:48.616Z,1683916188.616 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-12T18:29:48.617Z,1683916188.617 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-12T18:29:48.618Z,1683916188.618 [logger ThreadHandler](INFO): Protected caller Thread ID is 7088
2023-05-12T18:29:48.621Z,1683916188.621 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-12T18:29:48.622Z,1683916188.622 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-12T18:29:48.623Z,1683916188.623 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-12T18:29:48.723Z,1683916188.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-12T18:29:48.724Z,1683916188.724 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-12T18:29:49.410Z,1683916189.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-12T18:29:49.410Z,1683916189.410 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-12T18:29:49.640Z,1683916189.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-12T18:29:49.640Z,1683916189.640 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-12T18:29:49.721Z,1683916189.721 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-12T18:29:49.855Z,1683916189.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-12T18:29:49.855Z,1683916189.855 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-12T18:29:49.937Z,1683916189.937 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-12T18:29:50.046Z,1683916190.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-12T18:29:50.047Z,1683916190.047 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-12T18:29:50.432Z,1683916190.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-12T18:29:50.432Z,1683916190.432 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-12T18:29:50.920Z,1683916190.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-12T18:29:50.921Z,1683916190.921 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-12T18:29:51.144Z,1683916191.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-12T18:29:51.144Z,1683916191.144 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-12T18:29:51.333Z,1683916191.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-12T18:29:51.333Z,1683916191.333 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-12T18:29:51.841Z,1683916191.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-12T18:29:51.841Z,1683916191.841 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-12T18:29:52.139Z,1683916192.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-12T18:29:52.140Z,1683916192.140 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-12T18:29:52.355Z,1683916192.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-12T18:29:52.356Z,1683916192.356 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-12T18:29:52.721Z,1683916192.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-12T18:29:52.721Z,1683916192.721 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-12T18:29:53.059Z,1683916193.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-12T18:29:53.061Z,1683916193.061 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-12T18:29:53.062Z,1683916193.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-12T18:29:53.148Z,1683916193.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-12T18:29:53.307Z,1683916193.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-12T18:29:53.410Z,1683916193.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-12T18:29:53.497Z,1683916193.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-12T18:29:53.595Z,1683916193.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-12T18:29:54.379Z,1683916194.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-12T18:29:54.643Z,1683916194.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-12T18:29:54.644Z,1683916194.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-12T18:29:54.740Z,1683916194.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-12T18:29:54.840Z,1683916194.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-12T18:29:54.981Z,1683916194.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-12T18:29:55.083Z,1683916195.083 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-05-12T18:29:55.083Z,1683916195.083 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-12T18:29:55.091Z,1683916195.091 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-12T18:29:55.185Z,1683916195.185 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-12T18:29:55.186Z,1683916195.186 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-12T18:29:55.235Z,1683916195.235 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-12T18:29:55.293Z,1683916195.293 [VerticalControl] Loaded
2023-05-12T18:29:55.294Z,1683916195.294 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-12T18:29:55.296Z,1683916195.296 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-12T18:29:55.337Z,1683916195.337 [HorizontalControl] Loaded
2023-05-12T18:29:55.337Z,1683916195.337 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-12T18:29:55.339Z,1683916195.339 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-12T18:29:55.342Z,1683916195.342 [SpeedControl] Loaded
2023-05-12T18:29:55.343Z,1683916195.343 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-12T18:29:55.345Z,1683916195.345 [LoopControl](DEBUG): Construct LoopControl.
2023-05-12T18:29:55.346Z,1683916195.346 [LoopControl] Loaded
2023-05-12T18:29:55.346Z,1683916195.346 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-12T18:29:55.346Z,1683916195.346 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-12T18:29:55.347Z,1683916195.347 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-12T18:29:55.394Z,1683916195.394 [DepthRateCalculator] Loaded
2023-05-12T18:29:55.394Z,1683916195.394 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-12T18:29:55.399Z,1683916195.399 [PitchRateCalculator] Loaded
2023-05-12T18:29:55.399Z,1683916195.399 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-12T18:29:55.409Z,1683916195.409 [SpeedCalculator] Loaded
2023-05-12T18:29:55.409Z,1683916195.409 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-12T18:29:55.413Z,1683916195.413 [YawRateCalculator] Loaded
2023-05-12T18:29:55.414Z,1683916195.414 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-12T18:29:55.432Z,1683916195.432 [ElevatorOffsetCalculator] Loaded
2023-05-12T18:29:55.432Z,1683916195.432 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-12T18:29:55.433Z,1683916195.433 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-12T18:29:55.433Z,1683916195.433 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-12T18:29:55.459Z,1683916195.459 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-12T18:29:55.460Z,1683916195.460 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-12T18:29:55.529Z,1683916195.529 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-12T18:29:55.530Z,1683916195.530 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-12T18:29:55.825Z,1683916195.825 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-12T18:29:55.826Z,1683916195.826 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-12T18:29:55.965Z,1683916195.965 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-12T18:29:55.965Z,1683916195.965 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-12T18:29:56.434Z,1683916196.434 [AHRS_M2] Loaded
2023-05-12T18:29:56.434Z,1683916196.434 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-12T18:29:56.468Z,1683916196.468 [BackseatComponent] Loaded
2023-05-12T18:29:56.469Z,1683916196.469 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-12T18:29:56.470Z,1683916196.470 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2023-05-12T18:29:56.470Z,1683916196.470 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7170
2023-05-12T18:29:56.473Z,1683916196.473 [LcmUniversalReporter] Loaded
2023-05-12T18:29:56.473Z,1683916196.473 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-12T18:29:57.274Z,1683916197.274 [BPC1] Loaded
2023-05-12T18:29:57.274Z,1683916197.274 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-12T18:29:57.357Z,1683916197.357 [DATMMP] Loaded
2023-05-12T18:29:57.357Z,1683916197.357 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-12T18:29:57.358Z,1683916197.358 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2023-05-12T18:29:57.359Z,1683916197.359 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 7171
2023-05-12T18:29:57.433Z,1683916197.433 [DataOverHttps] Loaded
2023-05-12T18:29:57.433Z,1683916197.433 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-12T18:29:57.434Z,1683916197.434 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2023-05-12T18:29:57.434Z,1683916197.434 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7172
2023-05-12T18:29:57.455Z,1683916197.455 [Depth_Keller] Loaded
2023-05-12T18:29:57.455Z,1683916197.455 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-12T18:29:57.518Z,1683916197.518 [NAL9602] Loaded
2023-05-12T18:29:57.518Z,1683916197.518 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-12T18:29:57.547Z,1683916197.547 [Onboard] Loaded
2023-05-12T18:29:57.548Z,1683916197.548 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-12T18:29:57.549Z,1683916197.549 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-05-12T18:29:57.549Z,1683916197.549 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7173
2023-05-12T18:29:57.561Z,1683916197.561 [Power24vConverter] Loaded
2023-05-12T18:29:57.561Z,1683916197.561 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-12T18:29:57.574Z,1683916197.574 [Radio_Surface] Loaded
2023-05-12T18:29:57.575Z,1683916197.575 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-12T18:29:57.576Z,1683916197.576 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-05-12T18:29:57.576Z,1683916197.576 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7174
2023-05-12T18:29:57.621Z,1683916197.621 [RDI_Pathfinder] Loaded
2023-05-12T18:29:57.621Z,1683916197.621 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-12T18:29:57.622Z,1683916197.622 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-12T18:29:57.623Z,1683916197.623 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-12T18:29:57.729Z,1683916197.729 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-12T18:29:57.730Z,1683916197.730 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-12T18:29:57.743Z,1683916197.743 [NavChart] Loaded
2023-05-12T18:29:57.743Z,1683916197.743 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-12T18:29:57.749Z,1683916197.749 [UniversalFixResidualReporter] Loaded
2023-05-12T18:29:57.749Z,1683916197.749 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-12T18:29:57.750Z,1683916197.750 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-12T18:29:57.750Z,1683916197.750 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-12T18:29:57.884Z,1683916197.884 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-12T18:29:57.893Z,1683916197.893 [SBIT] Loaded
2023-05-12T18:29:57.894Z,1683916197.894 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-12T18:29:57.896Z,1683916197.896 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-12T18:29:57.909Z,1683916197.909 [IBIT] Loaded
2023-05-12T18:29:57.909Z,1683916197.909 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-12T18:29:57.915Z,1683916197.915 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-12T18:29:58.017Z,1683916198.017 [CBIT] Loaded
2023-05-12T18:29:58.018Z,1683916198.018 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-12T18:29:58.018Z,1683916198.018 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-12T18:29:58.019Z,1683916198.019 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-12T18:29:58.147Z,1683916198.147 [BuoyancyServo] Loaded
2023-05-12T18:29:58.148Z,1683916198.148 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-12T18:29:58.168Z,1683916198.168 [ElevatorServo] Loaded
2023-05-12T18:29:58.168Z,1683916198.168 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-12T18:29:58.188Z,1683916198.188 [MassServo] Loaded
2023-05-12T18:29:58.188Z,1683916198.188 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-12T18:29:58.206Z,1683916198.206 [RudderServo] Loaded
2023-05-12T18:29:58.206Z,1683916198.206 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-12T18:29:58.223Z,1683916198.223 [ThrusterServo] Loaded
2023-05-12T18:29:58.224Z,1683916198.224 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-12T18:29:58.224Z,1683916198.224 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-12T18:29:58.225Z,1683916198.225 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-12T18:29:58.235Z,1683916198.235 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-12T18:29:58.235Z,1683916198.235 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-12T18:29:58.401Z,1683916198.401 [CTD_Seabird] Loaded
2023-05-12T18:29:58.401Z,1683916198.401 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-12T18:29:58.402Z,1683916198.402 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0
2023-05-12T18:29:58.402Z,1683916198.402 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7175
2023-05-12T18:29:58.422Z,1683916198.422 [PAR_Licor] Loaded
2023-05-12T18:29:58.423Z,1683916198.423 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-12T18:29:58.454Z,1683916198.454 [WetLabsBB2FL] Loaded
2023-05-12T18:29:58.454Z,1683916198.454 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-12T18:29:58.455Z,1683916198.455 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0
2023-05-12T18:29:58.456Z,1683916198.456 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7176
2023-05-12T18:29:58.475Z,1683916198.475 [WetLabsUBAT] Loaded
2023-05-12T18:29:58.475Z,1683916198.475 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-12T18:29:58.476Z,1683916198.476 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0
2023-05-12T18:29:58.477Z,1683916198.477 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7177
2023-05-12T18:29:58.477Z,1683916198.477 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-12T18:29:58.484Z,1683916198.484 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-12T18:29:58.486Z,1683916198.486 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-12T18:29:58.497Z,1683916198.497 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-12T18:29:58.498Z,1683916198.498 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0
2023-05-12T18:29:58.499Z,1683916198.499 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7178
2023-05-12T18:29:58.503Z,1683916198.503 [Supervisor](INFO): Main Thread ID is 6926
2023-05-12T18:29:58.503Z,1683916198.503 [Supervisor](DEBUG): Running supervisor.
2023-05-12T18:29:58.504Z,1683916198.504 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7179
2023-05-12T18:29:58.504Z,1683916198.504 [CommandExec](INFO): Initializing the command executive.
2023-05-12T18:29:58.506Z,1683916198.506 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7180
2023-05-12T18:29:58.508Z,1683916198.508 [controlThread ThreadHandler](INFO): Handler Thread ID is 7181
2023-05-12T18:29:58.508Z,1683916198.508 [controlThread](DEBUG): Initializing ControlThread
2023-05-12T18:29:58.509Z,1683916198.509 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-12T18:29:58.511Z,1683916198.511 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-12T18:29:58.512Z,1683916198.512 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-12T18:29:58.512Z,1683916198.512 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-12T18:29:58.513Z,1683916198.513 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-12T18:29:58.513Z,1683916198.513 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-12T18:29:58.513Z,1683916198.513 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-12T18:29:58.514Z,1683916198.514 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-12T18:29:58.514Z,1683916198.514 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-12T18:29:58.520Z,1683916198.520 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-12T18:29:58.521Z,1683916198.521 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-12T18:29:58.521Z,1683916198.521 [SBIT](INFO): Initialize SBIT Component.
2023-05-12T18:29:58.521Z,1683916198.521 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-12T18:29:58.522Z,1683916198.522 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-12T18:29:58.522Z,1683916198.522 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-12T18:29:58.523Z,1683916198.523 [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:29:58.524Z,1683916198.524 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-12T18:29:58.525Z,1683916198.525 [IBIT](INFO): Initialize IBIT Component.
2023-05-12T18:29:58.526Z,1683916198.526 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-12T18:29:58.526Z,1683916198.526 [logger ThreadHandler](INFO): Handler Thread ID is 7182
2023-05-12T18:29:58.539Z,1683916198.539 [CBIT](DEBUG): Initialized mux pins.
2023-05-12T18:29:58.539Z,1683916198.539 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-12T18:29:58.548Z,1683916198.548 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7183
2023-05-12T18:29:58.559Z,1683916198.559 [DATMMP ThreadHandler](INFO): Handler Thread ID is 7184
2023-05-12T18:29:58.560Z,1683916198.560 [DATMMP](INFO): Start
2023-05-12T18:29:58.560Z,1683916198.560 [DATMMP](INFO): Powering up
2023-05-12T18:29:58.561Z,1683916198.561 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-12T18:29:58.563Z,1683916198.563 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-12T18:29:58.564Z,1683916198.564 [CBIT](DEBUG): Initializing heartbeat.
2023-05-12T18:29:58.565Z,1683916198.565 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7186
2023-05-12T18:29:58.566Z,1683916198.566 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-12T18:29:58.576Z,1683916198.576 [Onboard ThreadHandler](INFO): Handler Thread ID is 7187
2023-05-12T18:29:58.593Z,1683916198.593 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7188
2023-05-12T18:29:58.612Z,1683916198.612 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7189
2023-05-12T18:29:58.612Z,1683916198.612 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-12T18:29:58.616Z,1683916198.616 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7191
2023-05-12T18:29:58.620Z,1683916198.620 [WetLabsBB2FL](INFO): Powering up
2023-05-12T18:29:58.621Z,1683916198.621 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7193
2023-05-12T18:29:58.624Z,1683916198.624 [WetLabsUBAT](INFO): Powering up
2023-05-12T18:29:58.625Z,1683916198.625 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7195
2023-05-12T18:29:58.628Z,1683916198.628 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-12T18:29:58.628Z,1683916198.628 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-12T18:29:58.629Z,1683916198.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-12T18:29:58.629Z,1683916198.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-12T18:29:58.629Z,1683916198.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-12T18:29:58.629Z,1683916198.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-12T18:29:58.629Z,1683916198.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-12T18:29:58.629Z,1683916198.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-12T18:29:58.635Z,1683916198.635 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-12T18:29:58.635Z,1683916198.635 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-12T18:29:58.675Z,1683916198.675 [CBIT](DEBUG): Backplane powered.
2023-05-12T18:29:58.680Z,1683916198.680 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-12T18:29:58.797Z,1683916198.797 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T18:29:58.811Z,1683916198.811 [DATMMP](INFO): Starting
2023-05-12T18:29:58.901Z,1683916198.901 [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:29:58.902Z,1683916198.902 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-12T18:29:58.999Z,1683916198.999 [Radio_Surface](INFO): Powering up
2023-05-12T18:29:59.017Z,1683916199.017 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-12T18:29:59.019Z,1683916199.019 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-12T18:29:59.021Z,1683916199.021 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T18:29:59.063Z,1683916199.063 [DATMMP](INFO): Starting
2023-05-12T18:29:59.069Z,1683916199.069 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-12T18:29:59.082Z,1683916199.082 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-12T18:29:59.104Z,1683916199.104 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-12T18:29:59.120Z,1683916199.120 [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:29:59.124Z,1683916199.124 [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:29:59.147Z,1683916199.147 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-12T18:29:59.233Z,1683916199.233 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-12T18:29:59.234Z,1683916199.234 [Power24vConverter](INFO): Powering up.
2023-05-12T18:29:59.235Z,1683916199.235 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-12T18:29:59.261Z,1683916199.261 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-12T18:29:59.281Z,1683916199.281 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:29:59.287Z,1683916199.287 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-12T18:29:59.289Z,1683916199.289 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:29:59.295Z,1683916199.295 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-12T18:29:59.296Z,1683916199.296 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:29:59.307Z,1683916199.307 [MassServo](DEBUG): Initializing MassServo.
2023-05-12T18:29:59.308Z,1683916199.308 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:29:59.315Z,1683916199.315 [DATMMP](INFO): Starting
2023-05-12T18:29:59.316Z,1683916199.316 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-12T18:29:59.317Z,1683916199.317 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:29:59.323Z,1683916199.323 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-12T18:29:59.568Z,1683916199.568 [DATMMP](INFO): Starting
2023-05-12T18:29:59.819Z,1683916199.819 [DATMMP](INFO): Starting
2023-05-12T18:30:00.071Z,1683916200.071 [DATMMP](INFO): Starting
2023-05-12T18:30:00.323Z,1683916200.323 [DATMMP](INFO): Starting
2023-05-12T18:30:00.575Z,1683916200.575 [DATMMP](INFO): Starting
2023-05-12T18:30:00.827Z,1683916200.827 [DATMMP](INFO): Starting
2023-05-12T18:30:01.079Z,1683916201.079 [DATMMP](INFO): Starting
2023-05-12T18:30:01.235Z,1683916201.235 [WetLabsBB2FL](INFO): Powering down
2023-05-12T18:30:01.331Z,1683916201.331 [DATMMP](INFO): Starting
2023-05-12T18:30:01.583Z,1683916201.583 [DATMMP](INFO): Starting
2023-05-12T18:30:01.835Z,1683916201.835 [DATMMP](INFO): Starting
2023-05-12T18:30:02.087Z,1683916202.087 [DATMMP](INFO): Starting
2023-05-12T18:30:02.339Z,1683916202.339 [DATMMP](INFO): Starting
2023-05-12T18:30:02.591Z,1683916202.591 [DATMMP](INFO): Starting
2023-05-12T18:30:02.843Z,1683916202.843 [DATMMP](INFO): Starting
2023-05-12T18:30:03.095Z,1683916203.095 [DATMMP](INFO): Starting
2023-05-12T18:30:03.347Z,1683916203.347 [DATMMP](INFO): Starting
2023-05-12T18:30:03.603Z,1683916203.603 [DATMMP](INFO): Starting
2023-05-12T18:30:03.855Z,1683916203.855 [DATMMP](INFO): Starting
2023-05-12T18:30:04.107Z,1683916204.107 [DATMMP](INFO): Starting
2023-05-12T18:30:04.359Z,1683916204.359 [DATMMP](INFO): Starting
2023-05-12T18:30:04.611Z,1683916204.611 [DATMMP](INFO): Starting
2023-05-12T18:30:04.863Z,1683916204.863 [DATMMP](INFO): Starting
2023-05-12T18:30:05.115Z,1683916205.115 [DATMMP](INFO): Starting
2023-05-12T18:30:05.367Z,1683916205.367 [DATMMP](INFO): Starting
2023-05-12T18:30:05.619Z,1683916205.619 [DATMMP](INFO): Starting
2023-05-12T18:30:05.644Z,1683916205.644 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-12T18:30:05.871Z,1683916205.871 [DATMMP](INFO): Starting
2023-05-12T18:30:06.123Z,1683916206.123 [DATMMP](INFO): Starting
2023-05-12T18:30:06.375Z,1683916206.375 [DATMMP](INFO): Starting
2023-05-12T18:30:06.627Z,1683916206.627 [DATMMP](INFO): Starting
2023-05-12T18:30:06.880Z,1683916206.880 [DATMMP](INFO): Starting
2023-05-12T18:30:07.131Z,1683916207.131 [DATMMP](INFO): Starting
2023-05-12T18:30:07.383Z,1683916207.383 [DATMMP](INFO): Starting
2023-05-12T18:30:07.635Z,1683916207.635 [DATMMP](INFO): Starting
2023-05-12T18:30:07.875Z,1683916207.875 [WetLabsUBAT](INFO): Powering down
2023-05-12T18:30:07.887Z,1683916207.887 [DATMMP](INFO): Starting
2023-05-12T18:30:08.139Z,1683916208.139 [DATMMP](INFO): Starting
2023-05-12T18:30:08.391Z,1683916208.391 [DATMMP](INFO): Starting
2023-05-12T18:30:08.643Z,1683916208.643 [DATMMP](INFO): Starting
2023-05-12T18:30:08.895Z,1683916208.895 [DATMMP](INFO): Starting
2023-05-12T18:30:09.147Z,1683916209.147 [DATMMP](INFO): Starting
2023-05-12T18:30:09.399Z,1683916209.399 [DATMMP](INFO): Starting
2023-05-12T18:30:09.651Z,1683916209.651 [DATMMP](INFO): Starting
2023-05-12T18:30:09.903Z,1683916209.903 [DATMMP](INFO): Starting
2023-05-12T18:30:10.155Z,1683916210.155 [DATMMP](INFO): Starting
2023-05-12T18:30:10.407Z,1683916210.407 [DATMMP](INFO): Starting
2023-05-12T18:30:10.659Z,1683916210.659 [DATMMP](INFO): Starting
2023-05-12T18:30:10.660Z,1683916210.660 [DATMMP](INFO): DAT read: 

2023-05-12T18:30:10.661Z,1683916210.661 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-12T18:30:10.911Z,1683916210.911 [DATMMP](INFO): Starting
2023-05-12T18:30:11.163Z,1683916211.163 [DATMMP](INFO): Starting
2023-05-12T18:30:11.415Z,1683916211.415 [DATMMP](INFO): Starting
2023-05-12T18:30:11.667Z,1683916211.667 [DATMMP](INFO): Starting
2023-05-12T18:30:11.919Z,1683916211.919 [DATMMP](INFO): Starting
2023-05-12T18:30:12.171Z,1683916212.171 [DATMMP](INFO): Starting
2023-05-12T18:30:12.424Z,1683916212.424 [DATMMP](INFO): Starting
2023-05-12T18:30:12.424Z,1683916212.424 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-12T18:30:12.426Z,1683916212.426 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-12T18:30:12.426Z,1683916212.426 [DATMMP](INFO): DAT read: Apr 12 2000 18:30:06 

2023-05-12T18:30:12.675Z,1683916212.675 [DATMMP](INFO): Starting
2023-05-12T18:30:12.927Z,1683916212.927 [DATMMP](INFO): Starting
2023-05-12T18:30:13.180Z,1683916213.180 [DATMMP](INFO): Starting
2023-05-12T18:30:13.431Z,1683916213.431 [DATMMP](INFO): Starting
2023-05-12T18:30:13.432Z,1683916213.432 [DATMMP](INFO): DAT read: Features enabled [Bearing]

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

2023-05-12T18:30:13.434Z,1683916213.434 [DATMMP](INFO): commRate: 800
2023-05-12T18:30:13.683Z,1683916213.683 [DATMMP](INFO): Starting
2023-05-12T18:30:13.935Z,1683916213.935 [DATMMP](INFO): Starting
2023-05-12T18:30:14.187Z,1683916214.187 [DATMMP](INFO): Starting
2023-05-12T18:30:14.439Z,1683916214.439 [DATMMP](INFO): Starting
2023-05-12T18:30:14.691Z,1683916214.691 [DATMMP](INFO): Starting
2023-05-12T18:30:14.943Z,1683916214.943 [DATMMP](INFO): Starting
2023-05-12T18:30:15.195Z,1683916215.195 [DATMMP](INFO): Starting
2023-05-12T18:30:15.447Z,1683916215.447 [DATMMP](INFO): Starting
2023-05-12T18:30:15.499Z,1683916215.499 [DATMMP](INFO): entering command mode
2023-05-12T18:30:15.699Z,1683916215.699 [DATMMP](INFO): Starting
2023-05-12T18:30:15.699Z,1683916215.699 [DATMMP](INFO): DAT read: 

2023-05-12T18:30:15.700Z,1683916215.700 [DATMMP](INFO): DAT read: user:1>

2023-05-12T18:30:15.700Z,1683916215.700 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-12T18:30:15.701Z,1683916215.701 [DATMMP](INFO): entering MMP mode
2023-05-12T18:30:15.951Z,1683916215.951 [DATMMP](INFO): Starting
2023-05-12T18:30:15.952Z,1683916215.952 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:30:15.954Z,1683916215.954 [DATMMP](INFO): status rx: 	 x1
2023-05-12T18:30:15.954Z,1683916215.954 [DATMMP](INFO): MMP status: 1: started
2023-05-12T18:30:15.954Z,1683916215.954 [DATMMP](INFO): Received message type: status
2023-05-12T18:30:15.955Z,1683916215.955 [DATMMP](INFO): Handled
2023-05-12T18:30:16.203Z,1683916216.203 [DATMMP](INFO): Starting
2023-05-12T18:30:16.205Z,1683916216.205 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-12T18:30:16.205Z,1683916216.205 [DATMMP](INFO): Setting local address: 10
2023-05-12T18:30:16.208Z,1683916216.208 [DATMMP](INFO): Setting time to: 18:30:16 And date to:5/12/2023
2023-05-12T18:30:16.455Z,1683916216.455 [DATMMP](INFO): Starting
2023-05-12T18:30:16.456Z,1683916216.456 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:30:16.456Z,1683916216.456 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-12T18:30:16.457Z,1683916216.457 [DATMMP](INFO): Received message type: mdmpower
2023-05-12T18:30:16.457Z,1683916216.457 [DATMMP](INFO): Handled
2023-05-12T18:30:16.708Z,1683916216.708 [DATMMP](INFO): Starting
2023-05-12T18:30:16.709Z,1683916216.709 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-12T18:30:16.710Z,1683916216.710 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-12T18:30:16.710Z,1683916216.710 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-12T18:30:16.710Z,1683916216.710 [DATMMP](INFO): Match for : sreg_txpower
2023-05-12T18:30:16.710Z,1683916216.710 [DATMMP](INFO): ACK
2023-05-12T18:30:16.959Z,1683916216.959 [DATMMP](INFO): Starting
2023-05-12T18:30:16.960Z,1683916216.960 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:30:16.961Z,1683916216.961 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-12T18:30:16.961Z,1683916216.961 [DATMMP](INFO): Received message type: mdmlocal
2023-05-12T18:30:16.961Z,1683916216.961 [DATMMP](INFO): Handled
2023-05-12T18:30:17.211Z,1683916217.211 [DATMMP](INFO): Starting
2023-05-12T18:30:17.212Z,1683916217.212 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-12T18:30:17.213Z,1683916217.213 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-12T18:30:17.213Z,1683916217.213 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-12T18:30:17.213Z,1683916217.213 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-12T18:30:17.213Z,1683916217.213 [DATMMP](INFO): ACK
2023-05-12T18:30:17.463Z,1683916217.463 [DATMMP](INFO): Starting
2023-05-12T18:30:17.464Z,1683916217.464 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-12T18:30:17.465Z,1683916217.465 [DATMMP](INFO): timedate rx: 	 x0	 x12	 x1E	 x10	 x4	 xC	 x7B	 x0
2023-05-12T18:30:17.465Z,1683916217.465 [DATMMP](INFO): Received message type: timedate
2023-05-12T18:30:17.465Z,1683916217.465 [DATMMP](INFO): Match for : timedate
2023-05-12T18:30:17.466Z,1683916217.466 [DATMMP](INFO): ACK
2023-05-12T18:30:17.715Z,1683916217.715 [DATMMP](INFO): Starting
2023-05-12T18:30:17.716Z,1683916217.716 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:30:17.717Z,1683916217.717 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-12T18:30:17.717Z,1683916217.717 [DATMMP](INFO): Received message type: sys_verb
2023-05-12T18:30:17.717Z,1683916217.717 [DATMMP](INFO): Handled
2023-05-12T18:30:17.967Z,1683916217.967 [DATMMP](INFO): Starting
2023-05-12T18:30:17.968Z,1683916217.968 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-12T18:30:17.968Z,1683916217.968 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-12T18:30:17.969Z,1683916217.969 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-12T18:30:17.969Z,1683916217.969 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-12T18:30:17.969Z,1683916217.969 [DATMMP](INFO): ACK
2023-05-12T18:30:18.220Z,1683916218.220 [DATMMP](INFO): Starting
2023-05-12T18:30:18.220Z,1683916218.220 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-12T18:30:18.221Z,1683916218.221 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-12T18:30:18.222Z,1683916218.222 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-12T18:30:18.222Z,1683916218.222 [DATMMP](INFO): Received message type: version
2023-05-12T18:30:18.222Z,1683916218.222 [DATMMP](INFO): Match for : version
2023-05-12T18:30:18.222Z,1683916218.222 [DATMMP](INFO): ACK
2023-05-12T18:30:18.222Z,1683916218.222 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-12T18:30:27.820Z,1683916227.820 [NAL9602](INFO): Powering up NAL9602
2023-05-12T18:30:31.580Z,1683916231.580 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:30:31.581Z,1683916231.581 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T18:30:31.581Z,1683916231.581 [DATMMP](INFO): Received message type: timesync
2023-05-12T18:30:31.581Z,1683916231.581 [DATMMP](INFO): Handled
2023-05-12T18:30:38.669Z,1683916238.669 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-12T18:30:38.681Z,1683916238.681 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-12T18:30:38.681Z,1683916238.681 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-12T18:30:38.745Z,1683916238.745 [NAL9602](INFO): NAL9602 initialized
2023-05-12T18:30:38.786Z,1683916238.786 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-12T18:30:38.812Z,1683916238.812 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-12T18:30:38.833Z,1683916238.833 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-12T18:30:38.833Z,1683916238.833 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-12T18:30:38.834Z,1683916238.834 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-12T18:30:38.872Z,1683916238.872 [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:30:41.416Z,1683916241.416 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:30:41.417Z,1683916241.417 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T18:30:41.417Z,1683916241.417 [DATMMP](INFO): Received message type: timesync
2023-05-12T18:30:41.417Z,1683916241.417 [DATMMP](INFO): Handled
2023-05-12T18:30:49.816Z,1683916249.816 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683916241 second
2023-05-12T18:30:50.077Z,1683916250.077 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-12T18:30:50.085Z,1683916250.085 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-12T18:31:01.060Z,1683916261.060 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000711
CHAN A1 (24V): 0.000698
CHAN A2 (12V): -0.007556
CHAN A3 (5V): -0.001956
CHAN B0 (3.3V): -0.000377
CHAN B1 (3.15aV): 0.000523
CHAN B2 (3.15bV): -0.000317
CHAN B3 (GND): 0.001786
OPEN: 0.004735
Full Scale: +/- 1 mA
2023-05-12T18:31:44.230Z,1683916304.230 [SBIT](IMPORTANT): SBIT PASSED
2023-05-12T18:31:44.230Z,1683916304.230 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-12T18:31:44.235Z,1683916304.235 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-12T18:31:44.236Z,1683916304.236 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-12T18:31:44.236Z,1683916304.236 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-12T18:31:44.236Z,1683916304.236 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-12T18:31:44.236Z,1683916304.236 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-12T18:31:44.236Z,1683916304.236 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-12T18:31:44.236Z,1683916304.236 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-12T18:31:44.612Z,1683916304.612 [MissionManager](IMPORTANT): Started mission Startup
2023-05-12T18:31:44.613Z,1683916304.613 [Startup] Running Loop=1
2023-05-12T18:31:44.613Z,1683916304.613 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-12T18:31:44.613Z,1683916304.613 [Startup:A.GoToSurface] Running Loop=1
2023-05-12T18:31:44.613Z,1683916304.613 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T18:31:44.613Z,1683916304.613 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T18:31:44.614Z,1683916304.614 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T18:31:44.614Z,1683916304.614 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T18:31:44.614Z,1683916304.614 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T18:31:44.615Z,1683916304.615 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T18:31:44.616Z,1683916304.616 [Startup:StartupSatComms] Running Loop=1
2023-05-12T18:31:44.616Z,1683916304.616 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-12T18:31:44.617Z,1683916304.617 [Startup:StartupSatComms:A] Running Loop=1
2023-05-12T18:31:45.038Z,1683916305.038 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-12T18:31:45.038Z,1683916305.038 [Startup:StartupSatComms:A] Stopped
2023-05-12T18:31:45.038Z,1683916305.038 [Startup:StartupSatComms:B] Running Loop=1
2023-05-12T18:31:45.492Z,1683916305.492 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T18:32:26.699Z,1683916346.699 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005744
2023-05-12T18:32:34.299Z,1683916354.299 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T180624/Courier0010.lzma
2023-05-12T18:32:35.301Z,1683916355.301 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Courier0010.lzma.bak
2023-05-12T18:32:35.301Z,1683916355.301 [DataOverHttps](INFO): SBD MOMSN=18297967
2023-05-12T18:32:45.199Z,1683916365.199 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T18:31:45.0Z
2023-05-12T18:32:45.199Z,1683916365.199 [Startup:StartupSatComms:B] Stopped
2023-05-12T18:32:45.199Z,1683916365.199 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-12T18:32:45.199Z,1683916365.199 [Startup:StartupSatComms] Stopped
2023-05-12T18:32:45.200Z,1683916365.200 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-12T18:32:45.200Z,1683916365.200 [Startup](INFO): Completed Startup
2023-05-12T18:32:45.200Z,1683916365.200 [MissionManager](INFO): Startup is completed.
2023-05-12T18:32:45.201Z,1683916365.201 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-12T18:32:45.201Z,1683916365.201 [Startup] Stopped
2023-05-12T18:32:45.201Z,1683916365.201 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-12T18:32:45.201Z,1683916365.201 [Startup:A.GoToSurface] Stopped
2023-05-12T18:32:45.201Z,1683916365.201 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T18:32:45.599Z,1683916365.599 [MissionManager](IMPORTANT): Started mission Default
2023-05-12T18:32:45.599Z,1683916365.599 [Default] Running Loop=1
2023-05-12T18:32:45.599Z,1683916365.599 [Default](DEBUG): Aggregate::initialize Default
2023-05-12T18:32:45.599Z,1683916365.599 [Default:B.GoToSurface] Running Loop=1
2023-05-12T18:32:45.599Z,1683916365.599 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T18:32:45.600Z,1683916365.600 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T18:32:45.600Z,1683916365.600 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T18:32:45.600Z,1683916365.600 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T18:32:45.600Z,1683916365.600 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T18:32:45.601Z,1683916365.601 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T18:32:45.601Z,1683916365.601 [Default:A.Wait] Running Loop=1
2023-05-12T18:32:45.601Z,1683916365.601 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-12T18:32:52.095Z,1683916372.095 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230512T182948/Courier0000.lzma
2023-05-12T18:32:53.097Z,1683916373.097 [DataOverHttps](INFO): Moved sent file to Logs/20230512T182948/Courier0000.lzma.bak
2023-05-12T18:32:53.097Z,1683916373.097 [DataOverHttps](INFO): SBD MOMSN=18297972
2023-05-12T18:32:58.960Z,1683916378.960 [Default:A.Wait](INFO): Done Waiting.
2023-05-12T18:32:58.960Z,1683916378.960 [Default:A.Wait] Stopped
2023-05-12T18:32:58.960Z,1683916378.960 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T18:32:59.340Z,1683916379.340 [Default:CheckIn] Running Loop=1
2023-05-12T18:32:59.340Z,1683916379.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T18:32:59.340Z,1683916379.340 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T18:32:59.738Z,1683916379.738 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-12T18:33:07.598Z,1683916387.598 [CommandExec](IMPORTANT): got command get depth
2023-05-12T18:33:07.599Z,1683916387.599 [CommandExec](IMPORTANT): depth 0.800000 m
2023-05-12T18:33:36.705Z,1683916416.705 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:36.707Z,1683916416.707 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:33:36.707Z,1683916416.707 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:33:36.708Z,1683916416.708 [DATMMP](INFO): Handled
2023-05-12T18:33:36.956Z,1683916416.956 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:36.957Z,1683916416.957 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x21	 x23	 x653
2023-05-12T18:33:36.958Z,1683916416.958 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916430.849920
2023-05-12T18:33:36.958Z,1683916416.958 [DATMMP](INFO): Rx ping set to:1683916416.956761
2023-05-12T18:33:36.958Z,1683916416.958 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:33:36.958Z,1683916416.958 [DATMMP](INFO): Handled
2023-05-12T18:33:37.460Z,1683916417.460 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:37.461Z,1683916417.461 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:33:37.461Z,1683916417.461 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:33:37.461Z,1683916417.461 [DATMMP](INFO): Handled
2023-05-12T18:33:37.712Z,1683916417.712 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:37.713Z,1683916417.713 [DATMMP](INFO): directional_debug rx: 	 x195	 x106	 x21	 x1BF	 x2AA0	 x40C1	 x6932	 x75F3
2023-05-12T18:33:37.714Z,1683916417.714 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:33:37.714Z,1683916417.714 [DATMMP](INFO): Handled
2023-05-12T18:33:37.964Z,1683916417.964 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:37.965Z,1683916417.965 [DATMMP](INFO): directional rx: 	 x1B8	 xF4	 x447	 xFEFA	 xAFA	 xFEFA	 x0	 x6B6	 x4	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:33:37.967Z,1683916417.967 [DATMMP](INFO): Received message type: directional
2023-05-12T18:33:37.968Z,1683916417.968 [DATMMP](INFO): Handled
2023-05-12T18:33:38.216Z,1683916418.216 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:38.217Z,1683916418.217 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:33:38.217Z,1683916418.217 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:33:38.218Z,1683916418.218 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:33:38.218Z,1683916418.218 [DATMMP](INFO): Handled
2023-05-12T18:33:38.479Z,1683916418.479 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:38.481Z,1683916418.481 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7D	 xAC	 xA8	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:33:38.481Z,1683916418.481 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:33:38.481Z,1683916418.481 [DATMMP](INFO): Handled
2023-05-12T18:33:38.725Z,1683916418.725 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:38.728Z,1683916418.728 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x21	 x24	 x1DDF
2023-05-12T18:33:38.728Z,1683916418.728 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:33:38.729Z,1683916418.729 [DATMMP](INFO): Handled
2023-05-12T18:33:52.092Z,1683916432.092 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:52.092Z,1683916432.092 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:33:52.093Z,1683916432.093 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:33:52.093Z,1683916432.093 [DATMMP](INFO): Handled
2023-05-12T18:33:52.344Z,1683916432.344 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:52.345Z,1683916432.345 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x21	 x32	 x13FF
2023-05-12T18:33:52.345Z,1683916432.345 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916480.038336
2023-05-12T18:33:52.346Z,1683916432.346 [DATMMP](INFO): Rx ping set to:1683916432.344809
2023-05-12T18:33:52.346Z,1683916432.346 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:33:52.346Z,1683916432.346 [DATMMP](INFO): Handled
2023-05-12T18:33:52.848Z,1683916432.848 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:52.849Z,1683916432.849 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:33:52.849Z,1683916432.849 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:33:52.849Z,1683916432.849 [DATMMP](INFO): Handled
2023-05-12T18:33:53.100Z,1683916433.100 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:53.101Z,1683916433.101 [DATMMP](INFO): directional_debug rx: 	 x1A2	 xC3	 x22	 x174	 x2220	 x2A21	 x2D52	 x4A23
2023-05-12T18:33:53.102Z,1683916433.102 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:33:53.102Z,1683916433.102 [DATMMP](INFO): Handled
2023-05-12T18:33:53.352Z,1683916433.352 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:53.353Z,1683916433.353 [DATMMP](INFO): directional rx: 	 x1CB	 xC0	 x43A	 xFF3D	 xAF0	 xFF3B	 x0	 x6B7	 x3	 x0	 x27B5966	 x-43569E0
2023-05-12T18:33:53.355Z,1683916433.355 [DATMMP](INFO): Received message type: directional
2023-05-12T18:33:53.355Z,1683916433.355 [DATMMP](INFO): Handled
2023-05-12T18:33:53.604Z,1683916433.604 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:53.605Z,1683916433.605 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:33:53.605Z,1683916433.605 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:33:53.606Z,1683916433.606 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:33:53.606Z,1683916433.606 [DATMMP](INFO): Handled
2023-05-12T18:33:53.856Z,1683916433.856 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:53.861Z,1683916433.861 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x1D	 x89	 xAF	 xAF	 xD	 xFFFF	 x0	 x24	 x0	 x0
2023-05-12T18:33:53.862Z,1683916433.862 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:33:53.862Z,1683916433.862 [DATMMP](INFO): Handled
2023-05-12T18:33:54.108Z,1683916434.108 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:33:54.109Z,1683916434.109 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x21	 x34	 x47B
2023-05-12T18:33:54.109Z,1683916434.109 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:33:54.109Z,1683916434.109 [DATMMP](INFO): Handled
2023-05-12T18:34:07.464Z,1683916447.464 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:07.464Z,1683916447.464 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:34:07.465Z,1683916447.465 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:34:07.465Z,1683916447.465 [DATMMP](INFO): Handled
2023-05-12T18:34:07.716Z,1683916447.716 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:07.717Z,1683916447.717 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x22	 x5	 x239D
2023-05-12T18:34:07.717Z,1683916447.717 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916470.034816
2023-05-12T18:34:07.718Z,1683916447.718 [DATMMP](INFO): Rx ping set to:1683916447.716829
2023-05-12T18:34:07.718Z,1683916447.718 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:07.718Z,1683916447.718 [DATMMP](INFO): Handled
2023-05-12T18:34:08.220Z,1683916448.220 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:08.221Z,1683916448.221 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:34:08.221Z,1683916448.221 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:34:08.221Z,1683916448.221 [DATMMP](INFO): Handled
2023-05-12T18:34:08.484Z,1683916448.484 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:08.485Z,1683916448.485 [DATMMP](INFO): directional_debug rx: 	 x19E	 xBA	 x20	 x171	 x36B0	 x41E1	 x6FE2	 x7553
2023-05-12T18:34:08.486Z,1683916448.486 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:34:08.486Z,1683916448.486 [DATMMP](INFO): Handled
2023-05-12T18:34:08.737Z,1683916448.737 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:08.742Z,1683916448.742 [DATMMP](INFO): directional rx: 	 x1C3	 xB9	 x43E	 xFF46	 xAF2	 xFF46	 x0	 x6B4	 x1	 x0	 x27B5966	 x-43569E0
2023-05-12T18:34:08.747Z,1683916448.747 [DATMMP](INFO): Received message type: directional
2023-05-12T18:34:08.747Z,1683916448.747 [DATMMP](INFO): Handled
2023-05-12T18:34:08.988Z,1683916448.988 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:08.989Z,1683916448.989 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:34:08.990Z,1683916448.990 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:34:08.990Z,1683916448.990 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:34:08.990Z,1683916448.990 [DATMMP](INFO): Handled
2023-05-12T18:34:09.240Z,1683916449.240 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:09.242Z,1683916449.242 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7A	 xAF	 xB0	 xD	 xFFFF	 x0	 x24	 x0	 x0
2023-05-12T18:34:09.242Z,1683916449.242 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:34:09.242Z,1683916449.242 [DATMMP](INFO): Handled
2023-05-12T18:34:09.492Z,1683916449.492 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:09.493Z,1683916449.493 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x22	 x7	 x1419
2023-05-12T18:34:09.493Z,1683916449.493 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:09.493Z,1683916449.493 [DATMMP](INFO): Handled
2023-05-12T18:34:22.616Z,1683916462.616 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:22.616Z,1683916462.616 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:34:22.617Z,1683916462.617 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:34:22.617Z,1683916462.617 [DATMMP](INFO): Handled
2023-05-12T18:34:22.868Z,1683916462.868 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:22.869Z,1683916462.869 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x22	 x15	 x25C
2023-05-12T18:34:22.870Z,1683916462.870 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916466.942080
2023-05-12T18:34:22.870Z,1683916462.870 [DATMMP](INFO): Rx ping set to:1683916462.868990
2023-05-12T18:34:22.870Z,1683916462.870 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:22.870Z,1683916462.870 [DATMMP](INFO): Handled
2023-05-12T18:34:23.372Z,1683916463.372 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:23.373Z,1683916463.373 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x22	 x0	 xA	 xC	 x0
2023-05-12T18:34:23.373Z,1683916463.373 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:34:23.374Z,1683916463.374 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:34:23.374Z,1683916463.374 [DATMMP](INFO): Handled
2023-05-12T18:34:23.876Z,1683916463.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:23.877Z,1683916463.877 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:34:23.877Z,1683916463.877 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:34:23.877Z,1683916463.877 [DATMMP](INFO): Handled
2023-05-12T18:34:24.130Z,1683916464.130 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:24.132Z,1683916464.132 [DATMMP](INFO): directional_debug rx: 	 x17D	 x10A	 x22	 x1F9	 x3230	 x3FB1	 x6C92	 x7FF3
2023-05-12T18:34:24.133Z,1683916464.133 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:34:24.133Z,1683916464.133 [DATMMP](INFO): Handled
2023-05-12T18:34:24.384Z,1683916464.384 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:24.385Z,1683916464.385 [DATMMP](INFO): directional rx: 	 x19D	 xF6	 x45F	 xFEF6	 xB12	 xFEF5	 x0	 x6B3	 x0	 x0	 x27B5966	 x-43569E0
2023-05-12T18:34:24.387Z,1683916464.387 [DATMMP](INFO): Received message type: directional
2023-05-12T18:34:24.387Z,1683916464.387 [DATMMP](INFO): Handled
2023-05-12T18:34:24.636Z,1683916464.636 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:24.638Z,1683916464.638 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x23	 x7D	 xB7	 xBE	 xD	 xFFFF	 xA9	 x21	 xC	 x0
2023-05-12T18:34:24.638Z,1683916464.638 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:34:24.638Z,1683916464.638 [DATMMP](INFO): Handled
2023-05-12T18:34:24.888Z,1683916464.888 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:24.889Z,1683916464.889 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x22
2023-05-12T18:34:24.890Z,1683916464.890 [DATMMP](INFO): Check CRC
2023-05-12T18:34:24.891Z,1683916464.891 [DATMMP](INFO): data rx parseDataResponse 34
2023-05-12T18:34:24.891Z,1683916464.891 [DATMMP](INFO): Got DATA: len 34
2023-05-12T18:34:24.892Z,1683916464.892 [DATMMP](INFO): Received command: set _.pressure 0.746086 atmosphere
2023-05-12T18:34:24.892Z,1683916464.892 [DATMMP](INFO): #Outgoing data=1
2023-05-12T18:34:24.892Z,1683916464.892 [DATMMP](INFO): Sending ack
2023-05-12T18:34:24.892Z,1683916464.892 [DATMMP](INFO): Received message type: data_rx
2023-05-12T18:34:24.892Z,1683916464.892 [DATMMP](INFO): Handled
2023-05-12T18:34:24.892Z,1683916464.892 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T18:34:24.893Z,1683916464.893 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T18:34:25.140Z,1683916465.140 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:25.140Z,1683916465.140 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:34:25.141Z,1683916465.141 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:34:25.141Z,1683916465.141 [DATMMP](INFO): Handled
2023-05-12T18:34:25.392Z,1683916465.392 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:25.393Z,1683916465.393 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x22	 x16	 x1D33
2023-05-12T18:34:25.393Z,1683916465.393 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916471.046528
2023-05-12T18:34:25.394Z,1683916465.394 [DATMMP](INFO): Rx ping set to:1683916465.392692
2023-05-12T18:34:25.394Z,1683916465.394 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:25.394Z,1683916465.394 [DATMMP](INFO): Handled
2023-05-12T18:34:25.394Z,1683916465.394 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T18:34:25.394Z,1683916465.394 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T18:34:25.644Z,1683916465.644 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:25.645Z,1683916465.645 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:34:25.645Z,1683916465.645 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:34:25.645Z,1683916465.645 [DATMMP](INFO): Handled
2023-05-12T18:34:25.645Z,1683916465.645 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T18:34:25.646Z,1683916465.646 [DATMMP](INFO): Tx to 12 len 4 xid 15
2023-05-12T18:34:25.646Z,1683916465.646 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T18:34:25.646Z,1683916465.646 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:25.896Z,1683916465.896 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:25.897Z,1683916465.897 [DATMMP](INFO): directional_debug rx: 	 x1A2	 xC8	 x22	 x172	 x3690	 x4171	 x6FD2	 x5653
2023-05-12T18:34:25.898Z,1683916465.898 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:34:25.898Z,1683916465.898 [DATMMP](INFO): Handled
2023-05-12T18:34:25.898Z,1683916465.898 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:26.150Z,1683916466.150 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:26.152Z,1683916466.152 [DATMMP](INFO): directional rx: 	 x1CC	 xC4	 x43A	 xFF38	 xAED	 xFF37	 x0	 x6B3	 x1	 x1	 x27B5966	 x-43569E0
2023-05-12T18:34:26.154Z,1683916466.154 [DATMMP](INFO): Received message type: directional
2023-05-12T18:34:26.154Z,1683916466.154 [DATMMP](INFO): Handled
2023-05-12T18:34:26.154Z,1683916466.154 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:26.297Z,1683916466.297 [CommandExec](IMPORTANT): got command set _.pressure 0.746086 atmosphere
2023-05-12T18:34:26.404Z,1683916466.404 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:26.406Z,1683916466.406 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:34:26.406Z,1683916466.406 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:34:26.406Z,1683916466.406 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:34:26.406Z,1683916466.406 [DATMMP](INFO): Handled
2023-05-12T18:34:26.406Z,1683916466.406 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:26.656Z,1683916466.656 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:26.658Z,1683916466.658 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x80	 xB0	 xAE	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:34:26.658Z,1683916466.658 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:34:26.658Z,1683916466.658 [DATMMP](INFO): Handled
2023-05-12T18:34:26.658Z,1683916466.658 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:26.908Z,1683916466.908 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:26.909Z,1683916466.909 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x22	 x18	 xDAF
2023-05-12T18:34:26.909Z,1683916466.909 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:26.909Z,1683916466.909 [DATMMP](INFO): Handled
2023-05-12T18:34:26.909Z,1683916466.909 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:27.160Z,1683916467.160 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-12T18:34:27.161Z,1683916467.161 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T18:34:27.161Z,1683916467.161 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T18:34:27.161Z,1683916467.161 [DATMMP](DEBUG): Command complete send
2023-05-12T18:34:27.162Z,1683916467.162 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T18:34:27.162Z,1683916467.162 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T18:34:27.162Z,1683916467.162 [DATMMP](INFO): Handled
2023-05-12T18:34:27.162Z,1683916467.162 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T18:34:27.162Z,1683916467.162 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T18:34:27.412Z,1683916467.412 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:27.413Z,1683916467.413 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x22	 x18	 x1A2A
2023-05-12T18:34:27.413Z,1683916467.413 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:27.413Z,1683916467.413 [DATMMP](INFO): Handled
2023-05-12T18:34:31.956Z,1683916471.956 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:31.956Z,1683916471.956 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:34:31.957Z,1683916471.957 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:34:31.957Z,1683916471.957 [DATMMP](INFO): Handled
2023-05-12T18:34:32.210Z,1683916472.210 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:32.211Z,1683916472.211 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x22	 x1E	 x1007
2023-05-12T18:34:32.212Z,1683916472.212 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916510.071680
2023-05-12T18:34:32.212Z,1683916472.212 [DATMMP](INFO): Rx ping set to:1683916472.211305
2023-05-12T18:34:32.212Z,1683916472.212 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:32.212Z,1683916472.212 [DATMMP](INFO): Handled
2023-05-12T18:34:32.716Z,1683916472.716 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:32.717Z,1683916472.717 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x23	 x0	 xA	 xC	 x0
2023-05-12T18:34:32.717Z,1683916472.717 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:34:32.718Z,1683916472.718 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:34:32.718Z,1683916472.718 [DATMMP](INFO): Handled
2023-05-12T18:34:33.220Z,1683916473.220 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:33.221Z,1683916473.221 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:34:33.221Z,1683916473.221 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:34:33.221Z,1683916473.221 [DATMMP](INFO): Handled
2023-05-12T18:34:33.472Z,1683916473.472 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:33.473Z,1683916473.473 [DATMMP](INFO): directional_debug rx: 	 x19B	 xD3	 x21	 x169	 x3680	 x4391	 x6FB2	 x6933
2023-05-12T18:34:33.474Z,1683916473.474 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:34:33.474Z,1683916473.474 [DATMMP](INFO): Handled
2023-05-12T18:34:33.724Z,1683916473.724 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:33.725Z,1683916473.725 [DATMMP](INFO): directional rx: 	 x1C3	 xCC	 x441	 xFF2D	 xAF5	 xFF2E	 x0	 x6B4	 x0	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:34:33.728Z,1683916473.728 [DATMMP](INFO): Received message type: directional
2023-05-12T18:34:33.728Z,1683916473.728 [DATMMP](INFO): Handled
2023-05-12T18:34:33.976Z,1683916473.976 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:33.978Z,1683916473.978 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x23	 x7C	 xA9	 xBC	 xD	 xFFFF	 xA8	 x22	 xB	 x0
2023-05-12T18:34:33.978Z,1683916473.978 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:34:33.978Z,1683916473.978 [DATMMP](INFO): Handled
2023-05-12T18:34:34.228Z,1683916474.228 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:34.229Z,1683916474.229 [DATMMP](INFO): data_rx rx: 	 xC	 x0	 x0	 x23
2023-05-12T18:34:34.229Z,1683916474.229 [DATMMP](INFO): Check CRC
2023-05-12T18:34:34.229Z,1683916474.229 [DATMMP](INFO): data rx parseDataResponse 35
2023-05-12T18:34:34.229Z,1683916474.229 [DATMMP](INFO): Got DATA: len 35
2023-05-12T18:34:34.230Z,1683916474.230 [DATMMP](INFO): Received command: set _.temperature 24.058100 celsius
2023-05-12T18:34:34.230Z,1683916474.230 [DATMMP](INFO): #Outgoing data=1
2023-05-12T18:34:34.230Z,1683916474.230 [DATMMP](INFO): Sending ack
2023-05-12T18:34:34.230Z,1683916474.230 [DATMMP](INFO): Received message type: data_rx
2023-05-12T18:34:34.230Z,1683916474.230 [DATMMP](INFO): Handled
2023-05-12T18:34:34.230Z,1683916474.230 [DATMMP](INFO): *** SENDING_FILL_BUFFER ***
2023-05-12T18:34:34.231Z,1683916474.231 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA
2023-05-12T18:34:34.480Z,1683916474.480 [DATMMP](INFO): *** SENDING_NAV_DATA ***
2023-05-12T18:34:34.480Z,1683916474.480 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT
2023-05-12T18:34:34.732Z,1683916474.732 [DATMMP](INFO): *** SENDING_TRANSMIT ***
2023-05-12T18:34:34.734Z,1683916474.734 [DATMMP](INFO): Tx to 12 len 4 xid 16
2023-05-12T18:34:34.738Z,1683916474.738 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2023-05-12T18:34:34.738Z,1683916474.738 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY ***
2023-05-12T18:34:34.984Z,1683916474.984 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-12T18:34:34.985Z,1683916474.985 [DATMMP](INFO): cmd_result rx: 	 x1	 x7	 x0	 x1
2023-05-12T18:34:34.985Z,1683916474.985 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED
2023-05-12T18:34:34.985Z,1683916474.985 [DATMMP](DEBUG): Command complete send
2023-05-12T18:34:34.985Z,1683916474.985 [DATMMP](ERROR): 1:COMPLETE
2023-05-12T18:34:34.985Z,1683916474.985 [DATMMP](INFO): Received message type: cmd_result
2023-05-12T18:34:34.986Z,1683916474.986 [DATMMP](INFO): Handled
2023-05-12T18:34:34.986Z,1683916474.986 [DATMMP](INFO): *** SENDING_VERIFIED ***
2023-05-12T18:34:34.986Z,1683916474.986 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2023-05-12T18:34:35.236Z,1683916475.236 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:35.237Z,1683916475.237 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x22	 x21	 x1C1F
2023-05-12T18:34:35.237Z,1683916475.237 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:35.237Z,1683916475.237 [DATMMP](INFO): Handled
2023-05-12T18:34:36.868Z,1683916476.868 [CommandExec](IMPORTANT): got command set _.temperature 24.058100 celsius
2023-05-12T18:34:38.276Z,1683916478.276 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:38.276Z,1683916478.276 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:34:38.277Z,1683916478.277 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:34:38.277Z,1683916478.277 [DATMMP](INFO): Handled
2023-05-12T18:34:38.528Z,1683916478.528 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:38.529Z,1683916478.529 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x22	 x24	 x19D7
2023-05-12T18:34:38.529Z,1683916478.529 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916476.628736
2023-05-12T18:34:38.530Z,1683916478.530 [DATMMP](INFO): Rx ping set to:1683916478.528860
2023-05-12T18:34:38.530Z,1683916478.530 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:38.530Z,1683916478.530 [DATMMP](INFO): Handled
2023-05-12T18:34:39.032Z,1683916479.032 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:39.033Z,1683916479.033 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:34:39.033Z,1683916479.033 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:34:39.033Z,1683916479.033 [DATMMP](INFO): Handled
2023-05-12T18:34:39.284Z,1683916479.284 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:39.285Z,1683916479.285 [DATMMP](INFO): directional_debug rx: 	 x1C6	 xD8	 x21	 x15D	 x3670	 x41A1	 x6FB2	 x7EF3
2023-05-12T18:34:39.286Z,1683916479.286 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:34:39.286Z,1683916479.286 [DATMMP](INFO): Handled
2023-05-12T18:34:39.536Z,1683916479.536 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:39.537Z,1683916479.537 [DATMMP](INFO): directional rx: 	 x1F2	 xCE	 x416	 xFF28	 xACA	 xFF2A	 x0	 x6B4	 x1	 xFFFE	 x27B5966	 x-43569E0
2023-05-12T18:34:39.539Z,1683916479.539 [DATMMP](INFO): Received message type: directional
2023-05-12T18:34:39.539Z,1683916479.539 [DATMMP](INFO): Handled
2023-05-12T18:34:39.788Z,1683916479.788 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:39.789Z,1683916479.789 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:34:39.789Z,1683916479.789 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:34:39.790Z,1683916479.790 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:34:39.790Z,1683916479.790 [DATMMP](INFO): Handled
2023-05-12T18:34:40.040Z,1683916480.040 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:40.042Z,1683916480.042 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7C	 xB0	 xA6	 xD	 xFFFF	 x0	 x24	 x0	 x0
2023-05-12T18:34:40.042Z,1683916480.042 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:34:40.042Z,1683916480.042 [DATMMP](INFO): Handled
2023-05-12T18:34:40.292Z,1683916480.292 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:40.293Z,1683916480.293 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x22	 x26	 xA53
2023-05-12T18:34:40.293Z,1683916480.293 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:40.293Z,1683916480.293 [DATMMP](INFO): Handled
2023-05-12T18:34:53.672Z,1683916493.672 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:53.672Z,1683916493.672 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:34:53.673Z,1683916493.673 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:34:53.673Z,1683916493.673 [DATMMP](INFO): Handled
2023-05-12T18:34:53.924Z,1683916493.924 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:53.925Z,1683916493.925 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x22	 x34	 x72
2023-05-12T18:34:53.925Z,1683916493.925 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916493.118784
2023-05-12T18:34:53.926Z,1683916493.926 [DATMMP](INFO): Rx ping set to:1683916493.924822
2023-05-12T18:34:53.926Z,1683916493.926 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:53.926Z,1683916493.926 [DATMMP](INFO): Handled
2023-05-12T18:34:54.177Z,1683916494.177 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:54.180Z,1683916494.180 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:34:54.180Z,1683916494.180 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:34:54.180Z,1683916494.180 [DATMMP](INFO): Handled
2023-05-12T18:34:54.428Z,1683916494.428 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:54.429Z,1683916494.429 [DATMMP](INFO): directional_debug rx: 	 x17E	 x10C	 x21	 x20C	 x31C0	 x4141	 x6A72	 x7F83
2023-05-12T18:34:54.430Z,1683916494.430 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:34:54.430Z,1683916494.430 [DATMMP](INFO): Handled
2023-05-12T18:34:54.680Z,1683916494.680 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:54.682Z,1683916494.682 [DATMMP](INFO): directional rx: 	 x19D	 xF8	 x45E	 xFEF4	 xB13	 xFEF4	 x0	 x6B5	 x1	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:34:54.683Z,1683916494.683 [DATMMP](INFO): Received message type: directional
2023-05-12T18:34:54.684Z,1683916494.684 [DATMMP](INFO): Handled
2023-05-12T18:34:54.932Z,1683916494.932 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:54.933Z,1683916494.933 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:34:54.933Z,1683916494.933 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:34:54.934Z,1683916494.934 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:34:54.934Z,1683916494.934 [DATMMP](INFO): Handled
2023-05-12T18:34:55.184Z,1683916495.184 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:55.186Z,1683916495.186 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x1D3	 x5	 x7A	 xAF	 xB1	 xD	 xFFFF	 x0	 x24	 x0	 x0
2023-05-12T18:34:55.186Z,1683916495.186 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:34:55.186Z,1683916495.186 [DATMMP](INFO): Handled
2023-05-12T18:34:55.436Z,1683916495.436 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:34:55.437Z,1683916495.437 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x22	 x35	 x17FE
2023-05-12T18:34:55.437Z,1683916495.437 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:34:55.437Z,1683916495.437 [DATMMP](INFO): Handled
2023-05-12T18:35:08.796Z,1683916508.796 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:08.796Z,1683916508.796 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T18:35:08.797Z,1683916508.797 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:35:08.797Z,1683916508.797 [DATMMP](INFO): Handled
2023-05-12T18:35:09.048Z,1683916509.048 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:09.049Z,1683916509.049 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x23	 x7	 x64C
2023-05-12T18:35:09.049Z,1683916509.049 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916522.778240
2023-05-12T18:35:09.050Z,1683916509.050 [DATMMP](INFO): Rx ping set to:1683916509.048880
2023-05-12T18:35:09.050Z,1683916509.050 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:35:09.050Z,1683916509.050 [DATMMP](INFO): Handled
2023-05-12T18:35:09.552Z,1683916509.552 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:09.553Z,1683916509.553 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:35:09.553Z,1683916509.553 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:35:09.553Z,1683916509.553 [DATMMP](INFO): Handled
2023-05-12T18:35:09.804Z,1683916509.804 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:09.805Z,1683916509.805 [DATMMP](INFO): directional_debug rx: 	 x1A6	 xC6	 x20	 x185	 x36C0	 x4211	 x6FE2	 x7FF3
2023-05-12T18:35:09.806Z,1683916509.806 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:35:09.806Z,1683916509.806 [DATMMP](INFO): Handled
2023-05-12T18:35:10.056Z,1683916510.056 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:10.057Z,1683916510.057 [DATMMP](INFO): directional rx: 	 x1D0	 xC2	 x436	 xFF3A	 xAE9	 xFF3B	 x0	 x6B4	 x2	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T18:35:10.064Z,1683916510.064 [DATMMP](INFO): Received message type: directional
2023-05-12T18:35:10.064Z,1683916510.064 [DATMMP](INFO): Handled
2023-05-12T18:35:10.308Z,1683916510.308 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:10.309Z,1683916510.309 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:35:10.309Z,1683916510.309 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:35:10.310Z,1683916510.310 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:35:10.310Z,1683916510.310 [DATMMP](INFO): Handled
2023-05-12T18:35:10.560Z,1683916510.560 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:10.562Z,1683916510.562 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x23	 x7C	 xAF	 xA9	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:35:10.562Z,1683916510.562 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:35:10.562Z,1683916510.562 [DATMMP](INFO): Handled
2023-05-12T18:35:10.812Z,1683916510.812 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:10.813Z,1683916510.813 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x23	 x8	 x1DD8
2023-05-12T18:35:10.813Z,1683916510.813 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:35:10.813Z,1683916510.813 [DATMMP](INFO): Handled
2023-05-12T18:35:24.176Z,1683916524.176 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:24.177Z,1683916524.177 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:35:24.177Z,1683916524.177 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:35:24.177Z,1683916524.177 [DATMMP](INFO): Handled
2023-05-12T18:35:24.428Z,1683916524.428 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:24.429Z,1683916524.429 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 x23	 x16	 x15EC
2023-05-12T18:35:24.429Z,1683916524.429 [DATMMP](INFO): Rx dataTimestamp_ set to:1683916576.843776
2023-05-12T18:35:24.430Z,1683916524.430 [DATMMP](INFO): Rx ping set to:1683916524.428773
2023-05-12T18:35:24.430Z,1683916524.430 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:35:24.430Z,1683916524.430 [DATMMP](INFO): Handled
2023-05-12T18:35:24.932Z,1683916524.932 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:24.933Z,1683916524.933 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T18:35:24.933Z,1683916524.933 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:35:24.933Z,1683916524.933 [DATMMP](INFO): Handled
2023-05-12T18:35:25.184Z,1683916525.184 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:25.185Z,1683916525.185 [DATMMP](INFO): directional_debug rx: 	 x1A7	 xD1	 x1F	 x1A9	 x3680	 x4121	 x6FE2	 x7FF3
2023-05-12T18:35:25.186Z,1683916525.186 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:35:25.186Z,1683916525.186 [DATMMP](INFO): Handled
2023-05-12T18:35:25.436Z,1683916525.436 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:25.438Z,1683916525.438 [DATMMP](INFO): directional rx: 	 x1D0	 xCA	 x435	 xFF2F	 xAEA	 xFF2D	 x0	 x6B5	 x1	 x2	 x27B5966	 x-43569E0
2023-05-12T18:35:25.439Z,1683916525.439 [DATMMP](INFO): Received message type: directional
2023-05-12T18:35:25.440Z,1683916525.440 [DATMMP](INFO): Handled
2023-05-12T18:35:25.688Z,1683916525.688 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:25.689Z,1683916525.689 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:35:25.689Z,1683916525.689 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:35:25.689Z,1683916525.689 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:35:25.690Z,1683916525.690 [DATMMP](INFO): Handled
2023-05-12T18:35:25.940Z,1683916525.940 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:25.942Z,1683916525.942 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7C	 xAE	 xAD	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:35:25.942Z,1683916525.942 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:35:25.942Z,1683916525.942 [DATMMP](INFO): Handled
2023-05-12T18:35:26.192Z,1683916526.192 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:35:26.193Z,1683916526.193 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 x23	 x18	 x667
2023-05-12T18:35:26.194Z,1683916526.194 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:35:26.194Z,1683916526.194 [DATMMP](INFO): Handled
2023-05-12T18:35:42.135Z,1683916542.135 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T18:36:28.594Z,1683916588.594 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-05-12T18:37:27.989Z,1683916647.989 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-12T18:37:27.992Z,1683916647.992 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T18:37:59.508Z,1683916679.508 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T18:32:59.3Z
2023-05-12T18:37:59.508Z,1683916679.508 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T18:37:59.508Z,1683916679.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T18:37:59.926Z,1683916679.926 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T18:38:07.163Z,1683916687.163 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20230512T182948/Courier0004.lzma
2023-05-12T18:38:08.165Z,1683916688.165 [DataOverHttps](INFO): Moved sent file to Logs/20230512T182948/Courier0004.lzma.bak
2023-05-12T18:38:08.165Z,1683916688.165 [DataOverHttps](INFO): SBD MOMSN=18297992
2023-05-12T18:38:24.499Z,1683916704.499 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20230512T180624/Express0011.lzma
2023-05-12T18:38:25.504Z,1683916705.504 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Express0011.lzma.bak
2023-05-12T18:38:25.504Z,1683916705.504 [DataOverHttps](INFO): SBD MOMSN=18297994
2023-05-12T18:38:31.012Z,1683916711.012 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-12T18:38:41.596Z,1683916721.596 [DataOverHttps](INFO): Sending 1054 bytes from file Logs/20230512T182948/Express0001.lzma
2023-05-12T18:38:42.597Z,1683916722.597 [DataOverHttps](INFO): Moved sent file to Logs/20230512T182948/Express0001.lzma.bak
2023-05-12T18:38:42.597Z,1683916722.597 [DataOverHttps](INFO): SBD MOMSN=18297998
2023-05-12T18:38:58.643Z,1683916738.643 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20230512T182948/Express0005.lzma
2023-05-12T18:38:59.645Z,1683916739.645 [DataOverHttps](INFO): Moved sent file to Logs/20230512T182948/Express0005.lzma.bak
2023-05-12T18:38:59.645Z,1683916739.645 [DataOverHttps](INFO): SBD MOMSN=18298023
2023-05-12T18:42:01.216Z,1683916921.216 [CommandExec](IMPORTANT): got command restart application
2023-05-12T18:42:02.220Z,1683916922.220 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-12T18:42:02.220Z,1683916922.220 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:02.220Z,1683916922.220 [CommandExec](INFO): Uninitializing the command executive.
2023-05-12T18:42:02.221Z,1683916922.221 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-12T18:42:02.221Z,1683916922.221 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.339Z,1683916922.339 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-12T18:42:02.339Z,1683916922.339 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-12T18:42:02.339Z,1683916922.339 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.340Z,1683916922.340 [NavChartDb](INFO): Join timeout helper Thread ID is 7225
2023-05-12T18:42:02.635Z,1683916922.635 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:02.636Z,1683916922.636 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.656Z,1683916922.656 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-12T18:42:02.657Z,1683916922.657 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.657Z,1683916922.657 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7226
2023-05-12T18:42:02.683Z,1683916922.683 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:02.683Z,1683916922.683 [WetLabsUBAT](INFO): Powering down
2023-05-12T18:42:02.684Z,1683916922.684 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.687Z,1683916922.687 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-12T18:42:02.687Z,1683916922.687 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.688Z,1683916922.688 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7227
2023-05-12T18:42:02.887Z,1683916922.887 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:02.887Z,1683916922.887 [WetLabsBB2FL](INFO): Powering down
2023-05-12T18:42:02.888Z,1683916922.888 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.899Z,1683916922.899 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-12T18:42:02.899Z,1683916922.899 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:02.900Z,1683916922.900 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7228
2023-05-12T18:42:03.655Z,1683916923.655 [CTD_Seabird](INFO): Powering down
2023-05-12T18:42:03.671Z,1683916923.671 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:03.671Z,1683916923.671 [CTD_Seabird](INFO): Powering down
2023-05-12T18:42:03.683Z,1683916923.683 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:03.703Z,1683916923.703 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-12T18:42:03.703Z,1683916923.703 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:03.704Z,1683916923.704 [Radio_Surface](INFO): Join timeout helper Thread ID is 7229
2023-05-12T18:42:03.967Z,1683916923.967 [Radio_Surface](INFO): Powering down
2023-05-12T18:42:03.968Z,1683916923.968 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:03.968Z,1683916923.968 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:03.976Z,1683916923.976 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-12T18:42:03.976Z,1683916923.976 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:03.977Z,1683916923.977 [Onboard](INFO): Join timeout helper Thread ID is 7230
2023-05-12T18:42:04.219Z,1683916924.219 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:04.220Z,1683916924.220 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:04.224Z,1683916924.224 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-12T18:42:04.224Z,1683916924.224 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:04.224Z,1683916924.224 [DataOverHttps](INFO): Join timeout helper Thread ID is 7231
2023-05-12T18:42:06.558Z,1683916926.558 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:06.581Z,1683916926.581 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.584Z,1683916926.584 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-12T18:42:06.584Z,1683916926.584 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.584Z,1683916926.584 [DATMMP](INFO): Join timeout helper Thread ID is 7232
2023-05-12T18:42:06.756Z,1683916926.756 [DATMMP](INFO): Stop
2023-05-12T18:42:06.757Z,1683916926.757 [DATMMP](INFO): uninitialize
2023-05-12T18:42:06.757Z,1683916926.757 [DATMMP](INFO): Powering down
2023-05-12T18:42:06.824Z,1683916926.824 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:06.824Z,1683916926.824 [DATMMP](INFO): uninitialize
2023-05-12T18:42:06.824Z,1683916926.824 [DATMMP](INFO): Powering down
2023-05-12T18:42:06.825Z,1683916926.825 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.828Z,1683916926.828 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-12T18:42:06.828Z,1683916926.828 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.828Z,1683916926.828 [BackseatComponent](INFO): Join timeout helper Thread ID is 7233
2023-05-12T18:42:06.836Z,1683916926.836 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:06.836Z,1683916926.836 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.848Z,1683916926.848 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-12T18:42:06.848Z,1683916926.848 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.848Z,1683916926.848 [logger](INFO): Join timeout helper Thread ID is 7234
2023-05-12T18:42:06.871Z,1683916926.871 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:06.872Z,1683916926.872 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.888Z,1683916926.888 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-12T18:42:06.888Z,1683916926.888 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.888Z,1683916926.888 [CommandLine](INFO): Join timeout helper Thread ID is 7235
2023-05-12T18:42:06.935Z,1683916926.935 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:06.936Z,1683916926.936 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.952Z,1683916926.952 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-12T18:42:06.952Z,1683916926.952 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.952Z,1683916926.952 [CommandExec](INFO): Join timeout helper Thread ID is 7236
2023-05-12T18:42:06.960Z,1683916926.960 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-12T18:42:06.960Z,1683916926.960 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:06.960Z,1683916926.960 [controlThread](INFO): Join timeout helper Thread ID is 7237
2023-05-12T18:42:06.981Z,1683916926.981 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:42:06.981Z,1683916926.981 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-12T18:42:06.982Z,1683916926.982 [AHRS_M2](INFO): Powering down
2023-05-12T18:42:07.052Z,1683916927.052 [NAL9602](INFO): Powering down
2023-05-12T18:42:07.123Z,1683916927.123 [RDI_Pathfinder](INFO): Powering down
2023-05-12T18:42:07.125Z,1683916927.125 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-12T18:42:07.126Z,1683916927.126 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-12T18:42:07.126Z,1683916927.126 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-12T18:42:07.127Z,1683916927.127 [MissionManager](INFO): Uninitializing Mission Default
2023-05-12T18:42:07.127Z,1683916927.127 [Default] Stopped
2023-05-12T18:42:07.127Z,1683916927.127 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-12T18:42:07.127Z,1683916927.127 [Default:B.GoToSurface] Stopped
2023-05-12T18:42:07.127Z,1683916927.127 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T18:42:07.127Z,1683916927.127 [Default:CheckIn] Stopped
2023-05-12T18:42:07.127Z,1683916927.127 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T18:42:07.128Z,1683916927.128 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T18:42:07.130Z,1683916927.130 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-12T18:42:07.131Z,1683916927.131 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-12T18:42:07.131Z,1683916927.131 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-12T18:42:07.131Z,1683916927.131 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-12T18:42:07.132Z,1683916927.132 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-12T18:42:07.132Z,1683916927.132 [BuoyancyServo](INFO): Powering down
2023-05-12T18:42:07.143Z,1683916927.143 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-12T18:42:07.143Z,1683916927.143 [ElevatorServo](INFO): Powering down
2023-05-12T18:42:07.144Z,1683916927.144 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-12T18:42:07.144Z,1683916927.144 [MassServo](INFO): Powering down
2023-05-12T18:42:07.145Z,1683916927.145 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-12T18:42:07.145Z,1683916927.145 [RudderServo](INFO): Powering down
2023-05-12T18:42:07.146Z,1683916927.146 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-12T18:42:07.146Z,1683916927.146 [ThrusterServo](INFO): Powering down
2023-05-12T18:42:07.147Z,1683916927.147 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-12T18:42:07.147Z,1683916927.147 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-12T18:42:07.148Z,1683916927.148 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-12T18:42:07.148Z,1683916927.148 [CBIT](DEBUG): Powering off loads.
2023-05-12T18:42:07.159Z,1683916927.159 [CBIT](DEBUG): Disabling WDT.
2023-05-12T18:42:07.171Z,1683916927.171 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-12T18:42:07.172Z,1683916927.172 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.230Z,1683916927.230 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.233Z,1683916927.233 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.239Z,1683916927.239 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.245Z,1683916927.245 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.285Z,1683916927.285 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.350Z,1683916927.350 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.352Z,1683916927.352 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.355Z,1683916927.355 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.364Z,1683916927.364 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:42:07.426Z,1683916927.426 [logger ThreadHandler](INFO): Thread cancelled.