2023-05-12T15:55:36.056Z,1683906936.056 [Supervisor](DEBUG): Initializing supervisor.
2023-05-12T15:55:36.061Z,1683906936.061 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-12T15:55:36.061Z,1683906936.061 [SyncHandler](INFO): Protected caller Thread ID is 6777
2023-05-12T15:55:36.062Z,1683906936.062 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-12T15:55:36.062Z,1683906936.062 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-12T15:55:36.063Z,1683906936.063 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6778
2023-05-12T15:55:36.067Z,1683906936.067 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-12T15:55:36.084Z,1683906936.084 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-12T15:55:36.085Z,1683906936.085 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-12T15:55:36.085Z,1683906936.085 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6779
2023-05-12T15:55:36.089Z,1683906936.089 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-12T15:55:36.090Z,1683906936.090 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-12T15:55:36.091Z,1683906936.091 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6780
2023-05-12T15:55:36.093Z,1683906936.093 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-12T15:55:36.094Z,1683906936.094 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-12T15:55:36.095Z,1683906936.095 [logger ThreadHandler](INFO): Protected caller Thread ID is 6781
2023-05-12T15:55:36.098Z,1683906936.098 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-12T15:55:36.099Z,1683906936.099 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-12T15:55:36.100Z,1683906936.100 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-12T15:55:36.197Z,1683906936.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-12T15:55:36.197Z,1683906936.197 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-12T15:55:36.868Z,1683906936.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-12T15:55:36.868Z,1683906936.868 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-12T15:55:37.158Z,1683906937.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-12T15:55:37.159Z,1683906937.159 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-12T15:55:37.247Z,1683906937.247 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-12T15:55:37.377Z,1683906937.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-12T15:55:37.378Z,1683906937.378 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-12T15:55:37.459Z,1683906937.459 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-12T15:55:37.566Z,1683906937.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-12T15:55:37.567Z,1683906937.567 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-12T15:55:38.008Z,1683906938.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-12T15:55:38.008Z,1683906938.008 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-12T15:55:38.478Z,1683906938.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-12T15:55:38.479Z,1683906938.479 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-12T15:55:38.693Z,1683906938.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-12T15:55:38.694Z,1683906938.694 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-12T15:55:38.873Z,1683906938.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-12T15:55:38.873Z,1683906938.873 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-12T15:55:39.360Z,1683906939.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-12T15:55:39.361Z,1683906939.361 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-12T15:55:39.648Z,1683906939.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-12T15:55:39.649Z,1683906939.649 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-12T15:55:39.857Z,1683906939.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-12T15:55:39.858Z,1683906939.858 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-12T15:55:40.212Z,1683906940.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-12T15:55:40.212Z,1683906940.212 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-12T15:55:40.535Z,1683906940.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-12T15:55:40.537Z,1683906940.537 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-12T15:55:40.537Z,1683906940.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-12T15:55:40.622Z,1683906940.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-12T15:55:40.774Z,1683906940.774 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-12T15:55:40.876Z,1683906940.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-12T15:55:40.962Z,1683906940.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-12T15:55:41.057Z,1683906941.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-12T15:55:41.232Z,1683906941.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-12T15:55:41.492Z,1683906941.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-12T15:55:41.493Z,1683906941.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-12T15:55:41.586Z,1683906941.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-12T15:55:41.684Z,1683906941.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-12T15:55:41.820Z,1683906941.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-12T15:55:41.939Z,1683906941.939 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-05-12T15:55:41.939Z,1683906941.939 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-12T15:55:41.947Z,1683906941.947 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-12T15:55:42.181Z,1683906942.181 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-12T15:55:42.182Z,1683906942.182 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-12T15:55:42.388Z,1683906942.388 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-12T15:55:42.563Z,1683906942.563 [VerticalControl] Loaded
2023-05-12T15:55:42.564Z,1683906942.564 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-12T15:55:42.566Z,1683906942.566 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-12T15:55:42.607Z,1683906942.607 [HorizontalControl] Loaded
2023-05-12T15:55:42.607Z,1683906942.607 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-12T15:55:42.609Z,1683906942.609 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-12T15:55:42.612Z,1683906942.612 [SpeedControl] Loaded
2023-05-12T15:55:42.613Z,1683906942.613 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-12T15:55:42.615Z,1683906942.615 [LoopControl](DEBUG): Construct LoopControl.
2023-05-12T15:55:42.616Z,1683906942.616 [LoopControl] Loaded
2023-05-12T15:55:42.616Z,1683906942.616 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-12T15:55:42.616Z,1683906942.616 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-12T15:55:42.617Z,1683906942.617 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-12T15:55:42.735Z,1683906942.735 [DepthRateCalculator] Loaded
2023-05-12T15:55:42.735Z,1683906942.735 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-12T15:55:42.740Z,1683906942.740 [PitchRateCalculator] Loaded
2023-05-12T15:55:42.740Z,1683906942.740 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-12T15:55:42.750Z,1683906942.750 [SpeedCalculator] Loaded
2023-05-12T15:55:42.750Z,1683906942.750 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-12T15:55:42.754Z,1683906942.754 [YawRateCalculator] Loaded
2023-05-12T15:55:42.754Z,1683906942.754 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-12T15:55:42.780Z,1683906942.780 [ElevatorOffsetCalculator] Loaded
2023-05-12T15:55:42.780Z,1683906942.780 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-12T15:55:42.781Z,1683906942.781 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-12T15:55:42.781Z,1683906942.781 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-12T15:55:42.807Z,1683906942.807 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-12T15:55:42.808Z,1683906942.808 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-12T15:55:42.909Z,1683906942.909 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-12T15:55:42.910Z,1683906942.910 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-12T15:55:43.300Z,1683906943.300 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-12T15:55:43.300Z,1683906943.300 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-12T15:55:43.441Z,1683906943.441 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-12T15:55:43.441Z,1683906943.441 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-12T15:55:43.886Z,1683906943.886 [AHRS_M2] Loaded
2023-05-12T15:55:43.887Z,1683906943.887 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-12T15:55:43.922Z,1683906943.922 [BackseatComponent] Loaded
2023-05-12T15:55:43.922Z,1683906943.922 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-12T15:55:43.923Z,1683906943.923 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2023-05-12T15:55:43.923Z,1683906943.923 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6864
2023-05-12T15:55:43.926Z,1683906943.926 [LcmUniversalReporter] Loaded
2023-05-12T15:55:43.926Z,1683906943.926 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-12T15:55:44.730Z,1683906944.730 [BPC1] Loaded
2023-05-12T15:55:44.731Z,1683906944.731 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-12T15:55:44.813Z,1683906944.813 [DATMMP] Loaded
2023-05-12T15:55:44.814Z,1683906944.814 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-12T15:55:44.815Z,1683906944.815 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2023-05-12T15:55:44.815Z,1683906944.815 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 6865
2023-05-12T15:55:44.888Z,1683906944.888 [DataOverHttps] Loaded
2023-05-12T15:55:44.888Z,1683906944.888 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-12T15:55:44.889Z,1683906944.889 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2023-05-12T15:55:44.890Z,1683906944.890 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6866
2023-05-12T15:55:44.910Z,1683906944.910 [Depth_Keller] Loaded
2023-05-12T15:55:44.911Z,1683906944.911 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-12T15:55:44.973Z,1683906944.973 [NAL9602] Loaded
2023-05-12T15:55:44.973Z,1683906944.973 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-12T15:55:45.003Z,1683906945.003 [Onboard] Loaded
2023-05-12T15:55:45.003Z,1683906945.003 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-12T15:55:45.004Z,1683906945.004 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-05-12T15:55:45.004Z,1683906945.004 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6867
2023-05-12T15:55:45.016Z,1683906945.016 [Power24vConverter] Loaded
2023-05-12T15:55:45.017Z,1683906945.017 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-12T15:55:45.029Z,1683906945.029 [Radio_Surface] Loaded
2023-05-12T15:55:45.030Z,1683906945.030 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-12T15:55:45.031Z,1683906945.031 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-05-12T15:55:45.031Z,1683906945.031 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6868
2023-05-12T15:55:45.076Z,1683906945.076 [RDI_Pathfinder] Loaded
2023-05-12T15:55:45.076Z,1683906945.076 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-12T15:55:45.077Z,1683906945.077 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-12T15:55:45.077Z,1683906945.077 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-12T15:55:45.185Z,1683906945.185 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-12T15:55:45.185Z,1683906945.185 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-12T15:55:45.199Z,1683906945.199 [NavChart] Loaded
2023-05-12T15:55:45.199Z,1683906945.199 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-12T15:55:45.204Z,1683906945.204 [UniversalFixResidualReporter] Loaded
2023-05-12T15:55:45.205Z,1683906945.205 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-12T15:55:45.205Z,1683906945.205 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-12T15:55:45.206Z,1683906945.206 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-12T15:55:45.341Z,1683906945.341 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-12T15:55:45.350Z,1683906945.350 [SBIT] Loaded
2023-05-12T15:55:45.350Z,1683906945.350 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-12T15:55:45.353Z,1683906945.353 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-12T15:55:45.366Z,1683906945.366 [IBIT] Loaded
2023-05-12T15:55:45.366Z,1683906945.366 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-12T15:55:45.372Z,1683906945.372 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-12T15:55:45.475Z,1683906945.475 [CBIT] Loaded
2023-05-12T15:55:45.476Z,1683906945.476 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-12T15:55:45.476Z,1683906945.476 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-12T15:55:45.476Z,1683906945.476 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-12T15:55:45.608Z,1683906945.608 [BuoyancyServo] Loaded
2023-05-12T15:55:45.608Z,1683906945.608 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-12T15:55:45.629Z,1683906945.629 [ElevatorServo] Loaded
2023-05-12T15:55:45.629Z,1683906945.629 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-12T15:55:45.648Z,1683906945.648 [MassServo] Loaded
2023-05-12T15:55:45.649Z,1683906945.649 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-12T15:55:45.667Z,1683906945.667 [RudderServo] Loaded
2023-05-12T15:55:45.667Z,1683906945.667 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-12T15:55:45.684Z,1683906945.684 [ThrusterServo] Loaded
2023-05-12T15:55:45.684Z,1683906945.684 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-12T15:55:45.685Z,1683906945.685 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-12T15:55:45.685Z,1683906945.685 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-12T15:55:45.695Z,1683906945.695 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-12T15:55:45.696Z,1683906945.696 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-12T15:55:45.863Z,1683906945.863 [CTD_Seabird] Loaded
2023-05-12T15:55:45.863Z,1683906945.863 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-12T15:55:45.864Z,1683906945.864 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0
2023-05-12T15:55:45.865Z,1683906945.865 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6869
2023-05-12T15:55:45.885Z,1683906945.885 [PAR_Licor] Loaded
2023-05-12T15:55:45.885Z,1683906945.885 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-12T15:55:45.916Z,1683906945.916 [WetLabsBB2FL] Loaded
2023-05-12T15:55:45.916Z,1683906945.916 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-12T15:55:45.917Z,1683906945.917 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0
2023-05-12T15:55:45.918Z,1683906945.918 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6870
2023-05-12T15:55:45.937Z,1683906945.937 [WetLabsUBAT] Loaded
2023-05-12T15:55:45.938Z,1683906945.938 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-12T15:55:45.939Z,1683906945.939 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0
2023-05-12T15:55:45.939Z,1683906945.939 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 6871
2023-05-12T15:55:45.939Z,1683906945.939 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-12T15:55:45.946Z,1683906945.946 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-12T15:55:45.948Z,1683906945.948 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-12T15:55:45.960Z,1683906945.960 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-12T15:55:45.961Z,1683906945.961 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0
2023-05-12T15:55:45.961Z,1683906945.961 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6872
2023-05-12T15:55:45.965Z,1683906945.965 [Supervisor](INFO): Main Thread ID is 6776
2023-05-12T15:55:45.965Z,1683906945.965 [Supervisor](DEBUG): Running supervisor.
2023-05-12T15:55:45.966Z,1683906945.966 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6873
2023-05-12T15:55:45.967Z,1683906945.967 [CommandExec](INFO): Initializing the command executive.
2023-05-12T15:55:45.968Z,1683906945.968 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6874
2023-05-12T15:55:45.970Z,1683906945.970 [controlThread ThreadHandler](INFO): Handler Thread ID is 6875
2023-05-12T15:55:45.971Z,1683906945.971 [controlThread](DEBUG): Initializing ControlThread
2023-05-12T15:55:45.972Z,1683906945.972 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-12T15:55:45.973Z,1683906945.973 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-12T15:55:45.974Z,1683906945.974 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-12T15:55:45.974Z,1683906945.974 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-12T15:55:45.975Z,1683906945.975 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-12T15:55:45.975Z,1683906945.975 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-12T15:55:45.976Z,1683906945.976 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-12T15:55:45.976Z,1683906945.976 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-12T15:55:45.976Z,1683906945.976 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-12T15:55:45.981Z,1683906945.981 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-12T15:55:45.982Z,1683906945.982 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-12T15:55:45.982Z,1683906945.982 [SBIT](INFO): Initialize SBIT Component.
2023-05-12T15:55:45.983Z,1683906945.983 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-12T15:55:45.983Z,1683906945.983 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-12T15:55:45.983Z,1683906945.983 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-12T15:55:45.984Z,1683906945.984 [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-12T15:55:45.985Z,1683906945.985 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-12T15:55:45.986Z,1683906945.986 [IBIT](INFO): Initialize IBIT Component.
2023-05-12T15:55:45.987Z,1683906945.987 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-12T15:55:45.988Z,1683906945.988 [logger ThreadHandler](INFO): Handler Thread ID is 6876
2023-05-12T15:55:45.000Z,1683906946.000 [CBIT](DEBUG): Initialized mux pins.
2023-05-12T15:55:45.000Z,1683906946.000 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-12T15:55:46.008Z,1683906946.008 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6877
2023-05-12T15:55:46.020Z,1683906946.020 [DATMMP ThreadHandler](INFO): Handler Thread ID is 6878
2023-05-12T15:55:46.021Z,1683906946.021 [DATMMP](INFO): Start
2023-05-12T15:55:46.021Z,1683906946.021 [DATMMP](INFO): Powering up
2023-05-12T15:55:46.021Z,1683906946.021 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-12T15:55:46.024Z,1683906946.024 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-12T15:55:46.024Z,1683906946.024 [CBIT](DEBUG): Initializing heartbeat.
2023-05-12T15:55:46.026Z,1683906946.026 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6880
2023-05-12T15:55:46.027Z,1683906946.027 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-12T15:55:46.036Z,1683906946.036 [Onboard ThreadHandler](INFO): Handler Thread ID is 6881
2023-05-12T15:55:46.066Z,1683906946.066 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6882
2023-05-12T15:55:46.080Z,1683906946.080 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6883
2023-05-12T15:55:46.081Z,1683906946.081 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-12T15:55:46.085Z,1683906946.085 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6885
2023-05-12T15:55:46.089Z,1683906946.089 [WetLabsBB2FL](INFO): Powering up
2023-05-12T15:55:46.090Z,1683906946.090 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 6887
2023-05-12T15:55:46.096Z,1683906946.096 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-12T15:55:46.096Z,1683906946.096 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-12T15:55:46.097Z,1683906946.097 [WetLabsUBAT](INFO): Powering up
2023-05-12T15:55:46.098Z,1683906946.098 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6889
2023-05-12T15:55:46.101Z,1683906946.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-12T15:55:46.101Z,1683906946.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-12T15:55:46.101Z,1683906946.101 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-12T15:55:46.101Z,1683906946.101 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-12T15:55:46.102Z,1683906946.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-12T15:55:46.102Z,1683906946.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-12T15:55:46.102Z,1683906946.102 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-12T15:55:46.102Z,1683906946.102 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-12T15:55:46.136Z,1683906946.136 [CBIT](DEBUG): Backplane powered.
2023-05-12T15:55:46.141Z,1683906946.141 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-12T15:55:46.170Z,1683906946.170 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T15:55:46.193Z,1683906946.193 [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-12T15:55:46.194Z,1683906946.194 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-12T15:55:46.272Z,1683906946.272 [DATMMP](INFO): Starting
2023-05-12T15:55:46.293Z,1683906946.293 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-12T15:55:46.294Z,1683906946.294 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-12T15:55:46.321Z,1683906946.321 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T15:55:46.348Z,1683906946.348 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-12T15:55:46.350Z,1683906946.350 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-12T15:55:46.372Z,1683906946.372 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-12T15:55:46.396Z,1683906946.396 [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-12T15:55:46.400Z,1683906946.400 [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-12T15:55:46.407Z,1683906946.407 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-12T15:55:46.468Z,1683906946.468 [Radio_Surface](INFO): Powering up
2023-05-12T15:55:46.486Z,1683906946.486 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-12T15:55:46.487Z,1683906946.487 [Power24vConverter](INFO): Powering up.
2023-05-12T15:55:46.497Z,1683906946.497 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-12T15:55:46.524Z,1683906946.524 [DATMMP](INFO): Starting
2023-05-12T15:55:46.527Z,1683906946.527 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-12T15:55:46.570Z,1683906946.570 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-12T15:55:46.576Z,1683906946.576 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-12T15:55:46.577Z,1683906946.577 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-12T15:55:46.584Z,1683906946.584 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-12T15:55:46.585Z,1683906946.585 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-12T15:55:46.592Z,1683906946.592 [MassServo](DEBUG): Initializing MassServo.
2023-05-12T15:55:46.593Z,1683906946.593 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-12T15:55:46.600Z,1683906946.600 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-12T15:55:46.601Z,1683906946.601 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-12T15:55:46.608Z,1683906946.608 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-12T15:55:46.776Z,1683906946.776 [DATMMP](INFO): Starting
2023-05-12T15:55:47.040Z,1683906947.040 [DATMMP](INFO): Starting
2023-05-12T15:55:47.292Z,1683906947.292 [DATMMP](INFO): Starting
2023-05-12T15:55:47.544Z,1683906947.544 [DATMMP](INFO): Starting
2023-05-12T15:55:47.796Z,1683906947.796 [DATMMP](INFO): Starting
2023-05-12T15:55:48.048Z,1683906948.048 [DATMMP](INFO): Starting
2023-05-12T15:55:48.300Z,1683906948.300 [DATMMP](INFO): Starting
2023-05-12T15:55:48.552Z,1683906948.552 [DATMMP](INFO): Starting
2023-05-12T15:55:48.700Z,1683906948.700 [WetLabsBB2FL](INFO): Powering down
2023-05-12T15:55:48.804Z,1683906948.804 [DATMMP](INFO): Starting
2023-05-12T15:55:49.056Z,1683906949.056 [DATMMP](INFO): Starting
2023-05-12T15:55:49.309Z,1683906949.309 [DATMMP](INFO): Starting
2023-05-12T15:55:49.560Z,1683906949.560 [DATMMP](INFO): Starting
2023-05-12T15:55:49.812Z,1683906949.812 [DATMMP](INFO): Starting
2023-05-12T15:55:50.064Z,1683906950.064 [DATMMP](INFO): Starting
2023-05-12T15:55:50.316Z,1683906950.316 [DATMMP](INFO): Starting
2023-05-12T15:55:50.568Z,1683906950.568 [DATMMP](INFO): Starting
2023-05-12T15:55:50.820Z,1683906950.820 [DATMMP](INFO): Starting
2023-05-12T15:55:51.072Z,1683906951.072 [DATMMP](INFO): Starting
2023-05-12T15:55:51.324Z,1683906951.324 [DATMMP](INFO): Starting
2023-05-12T15:55:51.576Z,1683906951.576 [DATMMP](INFO): Starting
2023-05-12T15:55:51.828Z,1683906951.828 [DATMMP](INFO): Starting
2023-05-12T15:55:52.081Z,1683906952.081 [DATMMP](INFO): Starting
2023-05-12T15:55:52.332Z,1683906952.332 [DATMMP](INFO): Starting
2023-05-12T15:55:52.584Z,1683906952.584 [DATMMP](INFO): Starting
2023-05-12T15:55:52.836Z,1683906952.836 [DATMMP](INFO): Starting
2023-05-12T15:55:53.088Z,1683906953.088 [DATMMP](INFO): Starting
2023-05-12T15:55:53.340Z,1683906953.340 [DATMMP](INFO): Starting
2023-05-12T15:55:53.592Z,1683906953.592 [DATMMP](INFO): Starting
2023-05-12T15:55:53.844Z,1683906953.844 [DATMMP](INFO): Starting
2023-05-12T15:55:54.097Z,1683906954.097 [DATMMP](INFO): Starting
2023-05-12T15:55:54.348Z,1683906954.348 [DATMMP](INFO): Starting
2023-05-12T15:55:54.600Z,1683906954.600 [DATMMP](INFO): Starting
2023-05-12T15:55:54.852Z,1683906954.852 [DATMMP](INFO): Starting
2023-05-12T15:55:55.104Z,1683906955.104 [DATMMP](INFO): Starting
2023-05-12T15:55:55.356Z,1683906955.356 [DATMMP](INFO): Starting
2023-05-12T15:55:55.360Z,1683906955.360 [WetLabsUBAT](INFO): Powering down
2023-05-12T15:55:55.608Z,1683906955.608 [DATMMP](INFO): Starting
2023-05-12T15:55:55.860Z,1683906955.860 [DATMMP](INFO): Starting
2023-05-12T15:55:56.112Z,1683906956.112 [DATMMP](INFO): Starting
2023-05-12T15:55:56.364Z,1683906956.364 [DATMMP](INFO): Starting
2023-05-12T15:55:56.616Z,1683906956.616 [DATMMP](INFO): Starting
2023-05-12T15:55:56.868Z,1683906956.868 [DATMMP](INFO): Starting
2023-05-12T15:55:57.120Z,1683906957.120 [DATMMP](INFO): Starting
2023-05-12T15:55:57.372Z,1683906957.372 [DATMMP](INFO): Starting
2023-05-12T15:55:57.624Z,1683906957.624 [DATMMP](INFO): Starting
2023-05-12T15:55:57.876Z,1683906957.876 [DATMMP](INFO): Starting
2023-05-12T15:55:58.128Z,1683906958.128 [DATMMP](INFO): Starting
2023-05-12T15:55:58.129Z,1683906958.129 [DATMMP](INFO): DAT read: 

2023-05-12T15:55:58.130Z,1683906958.130 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-12T15:55:58.380Z,1683906958.380 [DATMMP](INFO): Starting
2023-05-12T15:55:58.632Z,1683906958.632 [DATMMP](INFO): Starting
2023-05-12T15:55:58.884Z,1683906958.884 [DATMMP](INFO): Starting
2023-05-12T15:55:59.136Z,1683906959.136 [DATMMP](INFO): Starting
2023-05-12T15:55:59.388Z,1683906959.388 [DATMMP](INFO): Starting
2023-05-12T15:55:59.641Z,1683906959.641 [DATMMP](INFO): Starting
2023-05-12T15:55:59.892Z,1683906959.892 [DATMMP](INFO): Starting
2023-05-12T15:55:59.893Z,1683906959.893 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-12T15:55:59.894Z,1683906959.894 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-12T15:55:59.894Z,1683906959.894 [DATMMP](INFO): DAT read: Apr 11 2000 23:35:52 

2023-05-12T15:55:59.958Z,1683906959.958 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005076
2023-05-12T15:56:00.144Z,1683906960.144 [DATMMP](INFO): Starting
2023-05-12T15:56:00.396Z,1683906960.396 [DATMMP](INFO): Starting
2023-05-12T15:56:00.648Z,1683906960.648 [DATMMP](INFO): Starting
2023-05-12T15:56:00.900Z,1683906960.900 [DATMMP](INFO): Starting
2023-05-12T15:56:00.901Z,1683906960.901 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-12T15:56:00.902Z,1683906960.902 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-12T15:56:00.902Z,1683906960.902 [DATMMP](INFO): commRate: 800
2023-05-12T15:56:01.152Z,1683906961.152 [DATMMP](INFO): Starting
2023-05-12T15:56:01.404Z,1683906961.404 [DATMMP](INFO): Starting
2023-05-12T15:56:01.656Z,1683906961.656 [DATMMP](INFO): Starting
2023-05-12T15:56:01.908Z,1683906961.908 [DATMMP](INFO): Starting
2023-05-12T15:56:02.160Z,1683906962.160 [DATMMP](INFO): Starting
2023-05-12T15:56:02.412Z,1683906962.412 [DATMMP](INFO): Starting
2023-05-12T15:56:02.664Z,1683906962.664 [DATMMP](INFO): Starting
2023-05-12T15:56:02.916Z,1683906962.916 [DATMMP](INFO): Starting
2023-05-12T15:56:02.969Z,1683906962.969 [DATMMP](INFO): entering command mode
2023-05-12T15:56:03.168Z,1683906963.168 [DATMMP](INFO): Starting
2023-05-12T15:56:03.168Z,1683906963.168 [DATMMP](INFO): DAT read: 

2023-05-12T15:56:03.169Z,1683906963.169 [DATMMP](INFO): DAT read: user:1>

2023-05-12T15:56:03.169Z,1683906963.169 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-12T15:56:03.169Z,1683906963.169 [DATMMP](INFO): entering MMP mode
2023-05-12T15:56:03.420Z,1683906963.420 [DATMMP](INFO): Starting
2023-05-12T15:56:03.421Z,1683906963.421 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T15:56:03.422Z,1683906963.422 [DATMMP](INFO): status rx: 	 x1
2023-05-12T15:56:03.423Z,1683906963.423 [DATMMP](INFO): MMP status: 1: started
2023-05-12T15:56:03.423Z,1683906963.423 [DATMMP](INFO): Received message type: status
2023-05-12T15:56:03.423Z,1683906963.423 [DATMMP](INFO): Handled
2023-05-12T15:56:03.672Z,1683906963.672 [DATMMP](INFO): Starting
2023-05-12T15:56:03.673Z,1683906963.673 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-12T15:56:03.674Z,1683906963.674 [DATMMP](INFO): Setting local address: 10
2023-05-12T15:56:03.677Z,1683906963.677 [DATMMP](INFO): Setting time to: 15:56:3 And date to:5/12/2023
2023-05-12T15:56:03.924Z,1683906963.924 [DATMMP](INFO): Starting
2023-05-12T15:56:03.924Z,1683906963.924 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T15:56:03.925Z,1683906963.925 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-12T15:56:03.925Z,1683906963.925 [DATMMP](INFO): Received message type: mdmpower
2023-05-12T15:56:03.926Z,1683906963.926 [DATMMP](INFO): Handled
2023-05-12T15:56:04.176Z,1683906964.176 [DATMMP](INFO): Starting
2023-05-12T15:56:04.176Z,1683906964.176 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-12T15:56:04.177Z,1683906964.177 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-12T15:56:04.177Z,1683906964.177 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-12T15:56:04.177Z,1683906964.177 [DATMMP](INFO): Match for : sreg_txpower
2023-05-12T15:56:04.178Z,1683906964.178 [DATMMP](INFO): ACK
2023-05-12T15:56:04.428Z,1683906964.428 [DATMMP](INFO): Starting
2023-05-12T15:56:04.428Z,1683906964.428 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T15:56:04.429Z,1683906964.429 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-12T15:56:04.429Z,1683906964.429 [DATMMP](INFO): Received message type: mdmlocal
2023-05-12T15:56:04.429Z,1683906964.429 [DATMMP](INFO): Handled
2023-05-12T15:56:04.680Z,1683906964.680 [DATMMP](INFO): Starting
2023-05-12T15:56:04.680Z,1683906964.680 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-12T15:56:04.681Z,1683906964.681 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-12T15:56:04.681Z,1683906964.681 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-12T15:56:04.681Z,1683906964.681 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-12T15:56:04.681Z,1683906964.681 [DATMMP](INFO): ACK
2023-05-12T15:56:04.932Z,1683906964.932 [DATMMP](INFO): Starting
2023-05-12T15:56:04.932Z,1683906964.932 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-12T15:56:04.934Z,1683906964.934 [DATMMP](INFO): timedate rx: 	 x0	 xF	 x38	 x3	 x4	 xC	 x7B	 x0
2023-05-12T15:56:04.934Z,1683906964.934 [DATMMP](INFO): Received message type: timedate
2023-05-12T15:56:04.934Z,1683906964.934 [DATMMP](INFO): Match for : timedate
2023-05-12T15:56:04.934Z,1683906964.934 [DATMMP](INFO): ACK
2023-05-12T15:56:05.184Z,1683906965.184 [DATMMP](INFO): Starting
2023-05-12T15:56:05.184Z,1683906965.184 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T15:56:05.185Z,1683906965.185 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-12T15:56:05.185Z,1683906965.185 [DATMMP](INFO): Received message type: sys_verb
2023-05-12T15:56:05.186Z,1683906965.186 [DATMMP](INFO): Handled
2023-05-12T15:56:05.436Z,1683906965.436 [DATMMP](INFO): Starting
2023-05-12T15:56:05.436Z,1683906965.436 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-12T15:56:05.437Z,1683906965.437 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-12T15:56:05.437Z,1683906965.437 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-12T15:56:05.437Z,1683906965.437 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-12T15:56:05.438Z,1683906965.438 [DATMMP](INFO): ACK
2023-05-12T15:56:05.688Z,1683906965.688 [DATMMP](INFO): Starting
2023-05-12T15:56:05.688Z,1683906965.688 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-12T15:56:05.689Z,1683906965.689 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-12T15:56:05.689Z,1683906965.689 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-12T15:56:05.690Z,1683906965.690 [DATMMP](INFO): Received message type: version
2023-05-12T15:56:05.690Z,1683906965.690 [DATMMP](INFO): Match for : version
2023-05-12T15:56:05.690Z,1683906965.690 [DATMMP](INFO): ACK
2023-05-12T15:56:05.690Z,1683906965.690 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-12T15:56:15.097Z,1683906975.097 [NAL9602](INFO): Powering up NAL9602
2023-05-12T15:56:19.816Z,1683906979.816 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T15:56:19.817Z,1683906979.817 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T15:56:19.818Z,1683906979.818 [DATMMP](INFO): Received message type: timesync
2023-05-12T15:56:19.818Z,1683906979.818 [DATMMP](INFO): Handled
2023-05-12T15:56:26.028Z,1683906986.028 [NAL9602](INFO): NAL9602 initialized
2023-05-12T15:56:29.912Z,1683906989.912 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T15:56:29.913Z,1683906989.913 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T15:56:29.914Z,1683906989.914 [DATMMP](INFO): Received message type: timesync
2023-05-12T15:56:29.914Z,1683906989.914 [DATMMP](INFO): Handled
2023-05-12T15:56:37.767Z,1683906997.767 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-12T15:56:37.780Z,1683906997.780 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-12T15:56:48.704Z,1683907008.704 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001105
CHAN A1 (24V): 0.000287
CHAN A2 (12V): -0.007785
CHAN A3 (5V): -0.002513
CHAN B0 (3.3V): 0.000110
CHAN B1 (3.15aV): -0.000149
CHAN B2 (3.15bV): -0.000811
CHAN B3 (GND): 0.002221
OPEN: 0.004671
Full Scale: +/- 1 mA
2023-05-12T15:57:12.507Z,1683907032.507 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-12T15:57:12.527Z,1683907032.527 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-12T15:57:12.527Z,1683907032.527 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-12T15:57:12.546Z,1683907032.546 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-12T15:57:12.546Z,1683907032.546 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-12T15:57:12.550Z,1683907032.550 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-12T15:57:12.551Z,1683907032.551 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-12T15:57:12.572Z,1683907032.572 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-12T15:57:12.908Z,1683907032.908 [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-12T15:57:31.505Z,1683907051.505 [SBIT](IMPORTANT): SBIT PASSED
2023-05-12T15:57:31.505Z,1683907051.505 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-12T15:57:31.506Z,1683907051.506 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-12T15:57:31.897Z,1683907051.897 [MissionManager](IMPORTANT): Started mission Startup
2023-05-12T15:57:31.897Z,1683907051.897 [Startup] Running Loop=1
2023-05-12T15:57:31.897Z,1683907051.897 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-12T15:57:31.897Z,1683907051.897 [Startup:A.GoToSurface] Running Loop=1
2023-05-12T15:57:31.897Z,1683907051.897 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T15:57:31.898Z,1683907051.898 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T15:57:31.898Z,1683907051.898 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T15:57:31.899Z,1683907051.899 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T15:57:31.899Z,1683907051.899 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T15:57:31.900Z,1683907051.900 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T15:57:31.901Z,1683907051.901 [Startup:StartupSatComms] Running Loop=1
2023-05-12T15:57:31.902Z,1683907051.902 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-12T15:57:31.902Z,1683907051.902 [Startup:StartupSatComms:A] Running Loop=1
2023-05-12T15:57:32.313Z,1683907052.313 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-12T15:57:32.313Z,1683907052.313 [Startup:StartupSatComms:A] Stopped
2023-05-12T15:57:32.313Z,1683907052.313 [Startup:StartupSatComms:B] Running Loop=1
2023-05-12T15:57:32.681Z,1683907052.681 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T15:57:39.460Z,1683907059.460 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T231925/Courier0003.lzma
2023-05-12T15:57:40.462Z,1683907060.462 [DataOverHttps](INFO): Moved sent file to Logs/20230511T231925/Courier0003.lzma.bak
2023-05-12T15:57:40.462Z,1683907060.462 [DataOverHttps](INFO): SBD MOMSN=18294644
2023-05-12T15:57:56.023Z,1683907076.023 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T155536/Courier0000.lzma
2023-05-12T15:57:57.009Z,1683907077.009 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Courier0000.lzma.bak
2023-05-12T15:57:57.010Z,1683907077.010 [DataOverHttps](INFO): SBD MOMSN=18294646
2023-05-12T15:58:13.964Z,1683907093.964 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20230511T231925/Express0001.lzma
2023-05-12T15:58:14.965Z,1683907094.965 [DataOverHttps](INFO): Moved sent file to Logs/20230511T231925/Express0001.lzma.bak
2023-05-12T15:58:14.966Z,1683907094.966 [DataOverHttps](INFO): SBD MOMSN=18294651
2023-05-12T15:58:23.939Z,1683907103.939 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683907094 second
2023-05-12T15:58:31.472Z,1683907111.472 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20230511T231925/Express0004.lzma
2023-05-12T15:58:32.317Z,1683907112.317 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T15:57:32.3Z
2023-05-12T15:58:32.317Z,1683907112.317 [Startup:StartupSatComms:B] Stopped
2023-05-12T15:58:32.318Z,1683907112.318 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-12T15:58:32.318Z,1683907112.318 [Startup:StartupSatComms] Stopped
2023-05-12T15:58:32.318Z,1683907112.318 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-12T15:58:32.319Z,1683907112.319 [Startup](INFO): Completed Startup
2023-05-12T15:58:32.319Z,1683907112.319 [MissionManager](INFO): Startup is completed.
2023-05-12T15:58:32.319Z,1683907112.319 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-12T15:58:32.319Z,1683907112.319 [Startup] Stopped
2023-05-12T15:58:32.319Z,1683907112.319 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-12T15:58:32.319Z,1683907112.319 [Startup:A.GoToSurface] Stopped
2023-05-12T15:58:32.319Z,1683907112.319 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T15:58:32.474Z,1683907112.474 [DataOverHttps](INFO): Moved sent file to Logs/20230511T231925/Express0004.lzma.bak
2023-05-12T15:58:32.474Z,1683907112.474 [DataOverHttps](INFO): SBD MOMSN=18294658
2023-05-12T15:58:32.715Z,1683907112.715 [MissionManager](IMPORTANT): Started mission Default
2023-05-12T15:58:32.715Z,1683907112.715 [Default] Running Loop=1
2023-05-12T15:58:32.715Z,1683907112.715 [Default](DEBUG): Aggregate::initialize Default
2023-05-12T15:58:32.715Z,1683907112.715 [Default:B.GoToSurface] Running Loop=1
2023-05-12T15:58:32.715Z,1683907112.715 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T15:58:32.716Z,1683907112.716 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T15:58:32.716Z,1683907112.716 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T15:58:32.716Z,1683907112.716 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T15:58:32.717Z,1683907112.717 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T15:58:32.717Z,1683907112.717 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T15:58:32.717Z,1683907112.717 [Default:A.Wait] Running Loop=1
2023-05-12T15:58:32.717Z,1683907112.717 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-12T15:58:46.101Z,1683907126.101 [Default:A.Wait](INFO): Done Waiting.
2023-05-12T15:58:46.101Z,1683907126.101 [Default:A.Wait] Stopped
2023-05-12T15:58:46.101Z,1683907126.101 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T15:58:46.465Z,1683907126.465 [Default:CheckIn] Running Loop=1
2023-05-12T15:58:46.466Z,1683907126.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T15:58:46.466Z,1683907126.466 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T15:58:46.834Z,1683907126.834 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-12T16:01:29.264Z,1683907289.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T16:03:16.329Z,1683907396.329 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-12T16:03:16.332Z,1683907396.332 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T16:03:46.636Z,1683907426.636 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T15:58:46.5Z
2023-05-12T16:03:46.637Z,1683907426.637 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T16:03:46.637Z,1683907426.637 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T16:03:47.050Z,1683907427.050 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T16:03:54.263Z,1683907434.263 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20230512T155536/Courier0004.lzma
2023-05-12T16:03:55.265Z,1683907435.265 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Courier0004.lzma.bak
2023-05-12T16:03:55.266Z,1683907435.266 [DataOverHttps](INFO): SBD MOMSN=18295164
2023-05-12T16:04:11.361Z,1683907451.361 [DataOverHttps](INFO): Sending 1033 bytes from file Logs/20230512T155536/Express0001.lzma
2023-05-12T16:04:12.361Z,1683907452.361 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Express0001.lzma.bak
2023-05-12T16:04:12.362Z,1683907452.362 [DataOverHttps](INFO): SBD MOMSN=18295166
2023-05-12T16:04:17.328Z,1683907457.328 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-12T16:04:27.464Z,1683907467.464 [DataOverHttps](INFO): Sending 360 bytes from file Logs/20230512T155536/Express0005.lzma
2023-05-12T16:04:28.461Z,1683907468.461 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Express0005.lzma.bak
2023-05-12T16:04:28.461Z,1683907468.461 [DataOverHttps](INFO): SBD MOMSN=18295192
2023-05-12T16:04:29.884Z,1683907469.884 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T16:04:29.884Z,1683907469.884 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T16:04:29.885Z,1683907469.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T16:09:30.452Z,1683907770.452 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T16:09:30.452Z,1683907770.452 [Default:CheckIn:C.Wait] Stopped
2023-05-12T16:09:30.452Z,1683907770.452 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T16:09:30.452Z,1683907770.452 [Default:CheckIn:D] Running Loop=1
2023-05-12T16:09:30.847Z,1683907770.847 [Default:CheckIn:D] Stopped
2023-05-12T16:09:30.847Z,1683907770.847 [Default:CheckIn:E] Running Loop=1
2023-05-12T16:09:31.251Z,1683907771.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.968862 min 
2023-05-12T16:09:31.251Z,1683907771.251 [Default:CheckIn:E] Stopped
2023-05-12T16:09:31.251Z,1683907771.251 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T16:09:31.251Z,1683907771.251 [Default:CheckIn] Stopped
2023-05-12T16:09:31.251Z,1683907771.251 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T16:09:31.252Z,1683907771.252 [Default:CheckIn](INFO): Running loop #2
2023-05-12T16:09:31.252Z,1683907771.252 [Default:CheckIn] Running Loop=2
2023-05-12T16:09:31.252Z,1683907771.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T16:09:31.252Z,1683907771.252 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T16:09:32.857Z,1683907772.857 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T16:09:32.857Z,1683907772.857 [NAL9602] Data Fault, FailCount= 1
2023-05-12T16:09:32.857Z,1683907772.857 [NAL9602](ERROR): Data Fault
2023-05-12T16:09:32.877Z,1683907772.877 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T16:09:33.257Z,1683907773.257 [NAL9602](INFO): Powering down
2023-05-12T16:09:34.117Z,1683907774.117 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T16:09:34.117Z,1683907774.117 [NAL9602] No Fault, FailCount= 1
2023-05-12T16:10:03.561Z,1683907803.561 [NAL9602](INFO): Powering up NAL9602
2023-05-12T16:10:14.469Z,1683907814.469 [NAL9602](INFO): NAL9602 initialized
2023-05-12T16:11:32.120Z,1683907892.120 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:32.121Z,1683907892.121 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:11:32.121Z,1683907892.121 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:11:32.121Z,1683907892.121 [DATMMP](INFO): Handled
2023-05-12T16:11:32.372Z,1683907892.372 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:32.373Z,1683907892.373 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xB	 x1E	 x256
2023-05-12T16:11:32.374Z,1683907892.374 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907895.880640
2023-05-12T16:11:32.375Z,1683907892.375 [DATMMP](INFO): Rx ping set to:1683907892.373542
2023-05-12T16:11:32.375Z,1683907892.375 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:11:32.375Z,1683907892.375 [DATMMP](INFO): Handled
2023-05-12T16:11:32.876Z,1683907892.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:32.877Z,1683907892.877 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:11:32.877Z,1683907892.877 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:11:32.877Z,1683907892.877 [DATMMP](INFO): Handled
2023-05-12T16:11:33.128Z,1683907893.128 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:33.130Z,1683907893.130 [DATMMP](INFO): directional_debug rx: 	 x19B	 x109	 x23	 x1F5	 x21F0	 x2021	 x2D72	 x4573
2023-05-12T16:11:33.131Z,1683907893.131 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:11:33.131Z,1683907893.131 [DATMMP](INFO): Handled
2023-05-12T16:11:33.381Z,1683907893.381 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:33.383Z,1683907893.383 [DATMMP](INFO): directional rx: 	 x1BF	 xF6	 x441	 xFEF7	 xAF9	 xFEF6	 x0	 x6BA	 x3	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T16:11:33.385Z,1683907893.385 [DATMMP](INFO): Received message type: directional
2023-05-12T16:11:33.385Z,1683907893.385 [DATMMP](INFO): Handled
2023-05-12T16:11:33.632Z,1683907893.632 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:33.634Z,1683907893.634 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:11:33.634Z,1683907893.634 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:11:33.634Z,1683907893.634 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:11:33.634Z,1683907893.634 [DATMMP](INFO): Handled
2023-05-12T16:11:33.885Z,1683907893.885 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:33.887Z,1683907893.887 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x22	 x8E	 xC3	 xC2	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:11:33.887Z,1683907893.887 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:11:33.887Z,1683907893.887 [DATMMP](INFO): Handled
2023-05-12T16:11:47.492Z,1683907907.492 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:47.493Z,1683907907.493 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:11:47.493Z,1683907907.493 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:11:47.493Z,1683907907.493 [DATMMP](INFO): Handled
2023-05-12T16:11:47.745Z,1683907907.745 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:47.746Z,1683907907.746 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xB	 x2D	 x11F4
2023-05-12T16:11:47.746Z,1683907907.746 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907949.925696
2023-05-12T16:11:47.746Z,1683907907.746 [DATMMP](INFO): Rx ping set to:1683907907.745642
2023-05-12T16:11:47.747Z,1683907907.747 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:11:47.747Z,1683907907.747 [DATMMP](INFO): Handled
2023-05-12T16:11:48.248Z,1683907908.248 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:48.249Z,1683907908.249 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:11:48.249Z,1683907908.249 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:11:48.249Z,1683907908.249 [DATMMP](INFO): Handled
2023-05-12T16:11:48.500Z,1683907908.500 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:48.502Z,1683907908.502 [DATMMP](INFO): directional_debug rx: 	 x188	 xF7	 x20	 x1D7	 x2AF0	 x4141	 x6782	 x75F3
2023-05-12T16:11:48.503Z,1683907908.503 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:11:48.503Z,1683907908.503 [DATMMP](INFO): Handled
2023-05-12T16:11:48.752Z,1683907908.752 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:48.754Z,1683907908.754 [DATMMP](INFO): directional rx: 	 x1AA	 xE8	 x454	 xFF09	 xB0C	 xFF07	 x0	 x6B9	 x4	 x0	 x27B5966	 x-43569E0
2023-05-12T16:11:48.756Z,1683907908.756 [DATMMP](INFO): Received message type: directional
2023-05-12T16:11:48.756Z,1683907908.756 [DATMMP](INFO): Handled
2023-05-12T16:11:49.004Z,1683907909.004 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:49.006Z,1683907909.006 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:11:49.006Z,1683907909.006 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:11:49.006Z,1683907909.006 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:11:49.006Z,1683907909.006 [DATMMP](INFO): Handled
2023-05-12T16:11:49.257Z,1683907909.257 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:11:49.258Z,1683907909.258 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7C	 xC3	 xBE	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:11:49.259Z,1683907909.259 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:11:49.259Z,1683907909.259 [DATMMP](INFO): Handled
2023-05-12T16:11:56.638Z,1683907916.638 [CommandExec](IMPORTANT): got command get Vehicle.id
2023-05-12T16:11:56.639Z,1683907916.639 [CommandExec](IMPORTANT): Vehicle.id 10 enum
2023-05-12T16:12:02.618Z,1683907922.618 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:02.618Z,1683907922.618 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:12:02.619Z,1683907922.619 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:12:02.619Z,1683907922.619 [DATMMP](INFO): Handled
2023-05-12T16:12:02.868Z,1683907922.868 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:02.869Z,1683907922.869 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xC	 x0	 x17D0
2023-05-12T16:12:02.870Z,1683907922.870 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907979.557056
2023-05-12T16:12:02.870Z,1683907922.870 [DATMMP](INFO): Rx ping set to:1683907922.869368
2023-05-12T16:12:02.870Z,1683907922.870 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:12:02.870Z,1683907922.870 [DATMMP](INFO): Handled
2023-05-12T16:12:03.372Z,1683907923.372 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:03.373Z,1683907923.373 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:12:03.373Z,1683907923.373 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:12:03.373Z,1683907923.373 [DATMMP](INFO): Handled
2023-05-12T16:12:03.624Z,1683907923.624 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:03.626Z,1683907923.626 [DATMMP](INFO): directional_debug rx: 	 x186	 xF2	 x1F	 x20E	 x3420	 x4761	 x6FF2	 x6BF3
2023-05-12T16:12:03.627Z,1683907923.627 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:12:03.627Z,1683907923.627 [DATMMP](INFO): Handled
2023-05-12T16:12:03.876Z,1683907923.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:03.878Z,1683907923.878 [DATMMP](INFO): directional rx: 	 x1A9	 xE4	 x456	 xFF0E	 xB0D	 xFF0B	 x0	 x6B8	 x4	 x2	 x27B5966	 x-43569E0
2023-05-12T16:12:03.880Z,1683907923.880 [DATMMP](INFO): Received message type: directional
2023-05-12T16:12:03.880Z,1683907923.880 [DATMMP](INFO): Handled
2023-05-12T16:12:04.128Z,1683907924.128 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:04.130Z,1683907924.130 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:12:04.130Z,1683907924.130 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:12:04.130Z,1683907924.130 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:12:04.130Z,1683907924.130 [DATMMP](INFO): Handled
2023-05-12T16:12:04.380Z,1683907924.380 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:04.382Z,1683907924.382 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7F	 xC7	 xC0	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:12:04.382Z,1683907924.382 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:12:04.382Z,1683907924.382 [DATMMP](INFO): Handled
2023-05-12T16:12:18.240Z,1683907938.240 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:18.241Z,1683907938.241 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T16:12:18.241Z,1683907938.241 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:12:18.241Z,1683907938.241 [DATMMP](INFO): Handled
2023-05-12T16:12:18.492Z,1683907938.492 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:18.493Z,1683907938.493 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xC	 x10	 x5F
2023-05-12T16:12:18.494Z,1683907938.494 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907936.972800
2023-05-12T16:12:18.494Z,1683907938.494 [DATMMP](INFO): Rx ping set to:1683907938.493427
2023-05-12T16:12:18.494Z,1683907938.494 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:12:18.495Z,1683907938.494 [DATMMP](INFO): Handled
2023-05-12T16:12:18.744Z,1683907938.744 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:18.745Z,1683907938.745 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:12:18.745Z,1683907938.745 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:12:18.746Z,1683907938.746 [DATMMP](INFO): Handled
2023-05-12T16:12:18.996Z,1683907938.996 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:18.998Z,1683907938.998 [DATMMP](INFO): directional_debug rx: 	 x183	 x10F	 x1F	 x22A	 x2AB0	 x42D1	 x6922	 x7733
2023-05-12T16:12:18.999Z,1683907938.999 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:12:18.999Z,1683907938.999 [DATMMP](INFO): Handled
2023-05-12T16:12:19.248Z,1683907939.248 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:19.250Z,1683907939.250 [DATMMP](INFO): directional rx: 	 x1A3	 xFA	 x459	 xFEF1	 xB12	 xFEEF	 x0	 x6BA	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T16:12:19.253Z,1683907939.253 [DATMMP](INFO): Received message type: directional
2023-05-12T16:12:19.253Z,1683907939.253 [DATMMP](INFO): Handled
2023-05-12T16:12:19.500Z,1683907939.500 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:19.502Z,1683907939.502 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:12:19.502Z,1683907939.502 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:12:19.502Z,1683907939.502 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:12:19.502Z,1683907939.502 [DATMMP](INFO): Handled
2023-05-12T16:12:19.752Z,1683907939.752 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:19.754Z,1683907939.754 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x22	 x7C	 xC6	 xC3	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:12:19.754Z,1683907939.754 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:12:19.755Z,1683907939.755 [DATMMP](INFO): Handled
2023-05-12T16:12:20.006Z,1683907940.006 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:20.008Z,1683907940.008 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:12:20.008Z,1683907940.008 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:12:20.008Z,1683907940.008 [DATMMP](INFO): Handled
2023-05-12T16:12:20.256Z,1683907940.256 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:20.257Z,1683907940.257 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xC	 x11	 xB1F
2023-05-12T16:12:20.258Z,1683907940.258 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907964.841728
2023-05-12T16:12:20.258Z,1683907940.258 [DATMMP](INFO): Rx ping set to:1683907940.257394
2023-05-12T16:12:20.258Z,1683907940.258 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:12:20.258Z,1683907940.258 [DATMMP](INFO): Handled
2023-05-12T16:12:20.508Z,1683907940.508 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:20.509Z,1683907940.509 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:12:20.509Z,1683907940.509 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:12:20.509Z,1683907940.509 [DATMMP](INFO): Handled
2023-05-12T16:12:20.760Z,1683907940.760 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:20.762Z,1683907940.762 [DATMMP](INFO): directional_debug rx: 	 x189	 xF8	 x22	 x1F9	 x2A30	 x34F1	 x60B2	 x5A23
2023-05-12T16:12:20.763Z,1683907940.763 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:12:20.763Z,1683907940.763 [DATMMP](INFO): Handled
2023-05-12T16:12:21.012Z,1683907941.012 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:21.014Z,1683907941.014 [DATMMP](INFO): directional rx: 	 x1AC	 xE8	 x453	 xFF08	 xB0C	 xFF07	 x0	 x6BA	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T16:12:21.016Z,1683907941.016 [DATMMP](INFO): Received message type: directional
2023-05-12T16:12:21.016Z,1683907941.016 [DATMMP](INFO): Handled
2023-05-12T16:12:21.264Z,1683907941.264 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:21.266Z,1683907941.266 [DATMMP](INFO): remote_header rx: 	 xF	 x4	 x5	 x22	 x0	 x9	 xC	 x0
2023-05-12T16:12:21.266Z,1683907941.266 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:12:21.266Z,1683907941.266 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:12:21.266Z,1683907941.266 [DATMMP](INFO): Handled
2023-05-12T16:12:21.516Z,1683907941.516 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:21.518Z,1683907941.518 [DATMMP](INFO): acstats rx: 	 xC	 x5	 x0	 x2D0	 x22	 x7E	 xAE	 xBD	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T16:12:21.518Z,1683907941.518 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:12:21.518Z,1683907941.518 [DATMMP](INFO): Handled
2023-05-12T16:12:33.364Z,1683907953.364 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:33.365Z,1683907953.365 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:12:33.365Z,1683907953.365 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:12:33.365Z,1683907953.365 [DATMMP](INFO): Handled
2023-05-12T16:12:33.616Z,1683907953.616 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:33.617Z,1683907953.617 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xC	 x1F	 xE0A
2023-05-12T16:12:33.618Z,1683907953.618 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907986.102400
2023-05-12T16:12:33.618Z,1683907953.618 [DATMMP](INFO): Rx ping set to:1683907953.617382
2023-05-12T16:12:33.618Z,1683907953.618 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:12:33.618Z,1683907953.618 [DATMMP](INFO): Handled
2023-05-12T16:12:34.120Z,1683907954.120 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:34.121Z,1683907954.121 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:12:34.121Z,1683907954.121 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:12:34.121Z,1683907954.121 [DATMMP](INFO): Handled
2023-05-12T16:12:34.372Z,1683907954.372 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:34.373Z,1683907954.374 [DATMMP](INFO): directional_debug rx: 	 x19F	 x109	 x22	 x1E3	 x2980	 x35D1	 x6062	 x5A33
2023-05-12T16:12:34.375Z,1683907954.375 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:12:34.375Z,1683907954.375 [DATMMP](INFO): Handled
2023-05-12T16:12:34.624Z,1683907954.624 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:34.626Z,1683907954.626 [DATMMP](INFO): directional rx: 	 x1C2	 xF6	 x43D	 xFEF7	 xAF6	 xFEF6	 x0	 x6BA	 x3	 x0	 x27B5966	 x-43569E0
2023-05-12T16:12:34.628Z,1683907954.628 [DATMMP](INFO): Received message type: directional
2023-05-12T16:12:34.628Z,1683907954.628 [DATMMP](INFO): Handled
2023-05-12T16:12:34.876Z,1683907954.876 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:34.878Z,1683907954.878 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:12:34.878Z,1683907954.878 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:12:34.878Z,1683907954.878 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:12:34.878Z,1683907954.878 [DATMMP](INFO): Handled
2023-05-12T16:12:35.128Z,1683907955.128 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:35.130Z,1683907955.130 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x81	 xC4	 xC5	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:12:35.130Z,1683907955.130 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:12:35.130Z,1683907955.130 [DATMMP](INFO): Handled
2023-05-12T16:12:48.992Z,1683907968.992 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:48.993Z,1683907968.993 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:12:48.993Z,1683907968.993 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:12:48.993Z,1683907968.993 [DATMMP](INFO): Handled
2023-05-12T16:12:49.244Z,1683907969.244 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:49.245Z,1683907969.245 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xC	 x2E	 x1DA9
2023-05-12T16:12:49.246Z,1683907969.246 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907976.157696
2023-05-12T16:12:49.246Z,1683907969.246 [DATMMP](INFO): Rx ping set to:1683907969.245387
2023-05-12T16:12:49.246Z,1683907969.246 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:12:49.246Z,1683907969.246 [DATMMP](INFO): Handled
2023-05-12T16:12:49.496Z,1683907969.496 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:49.497Z,1683907969.497 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:12:49.497Z,1683907969.497 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:12:49.497Z,1683907969.497 [DATMMP](INFO): Handled
2023-05-12T16:12:49.749Z,1683907969.749 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:49.750Z,1683907969.750 [DATMMP](INFO): directional_debug rx: 	 x178	 xF7	 x20	 x1CE	 x2A90	 x4671	 x6EA2	 x6B43
2023-05-12T16:12:49.752Z,1683907969.752 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:12:49.753Z,1683907969.753 [DATMMP](INFO): Handled
2023-05-12T16:12:50.002Z,1683907970.002 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:50.006Z,1683907970.006 [DATMMP](INFO): directional rx: 	 x198	 xE8	 x464	 xFF09	 xB1F	 xFF08	 x0	 x6BC	 x2	 x0	 x27B5966	 x-43569E0
2023-05-12T16:12:50.011Z,1683907970.011 [DATMMP](INFO): Received message type: directional
2023-05-12T16:12:50.028Z,1683907970.028 [DATMMP](INFO): Handled
2023-05-12T16:12:50.252Z,1683907970.252 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:50.254Z,1683907970.254 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:12:50.254Z,1683907970.254 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:12:50.254Z,1683907970.254 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:12:50.254Z,1683907970.254 [DATMMP](INFO): Handled
2023-05-12T16:12:50.504Z,1683907970.504 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:12:50.506Z,1683907970.506 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x219	 x22	 x79	 xC3	 xC5	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:12:50.506Z,1683907970.506 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:12:50.506Z,1683907970.506 [DATMMP](INFO): Handled
2023-05-12T16:13:04.112Z,1683907984.112 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:04.113Z,1683907984.113 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T16:13:04.113Z,1683907984.113 [DATMMP](INFO): Received message type: doppler
2023-05-12T16:13:04.113Z,1683907984.113 [DATMMP](INFO): Handled
2023-05-12T16:13:04.364Z,1683907984.364 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:04.365Z,1683907984.365 [DATMMP](INFO): timestamp rx: 	 x1	 x10	 xD	 x2	 x444
2023-05-12T16:13:04.366Z,1683907984.366 [DATMMP](INFO): Rx dataTimestamp_ set to:1683907992.696320
2023-05-12T16:13:04.366Z,1683907984.366 [DATMMP](INFO): Rx ping set to:1683907984.365380
2023-05-12T16:13:04.366Z,1683907984.366 [DATMMP](INFO): Received message type: timestamp
2023-05-12T16:13:04.366Z,1683907984.366 [DATMMP](INFO): Handled
2023-05-12T16:13:04.868Z,1683907984.868 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:04.869Z,1683907984.869 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-12T16:13:04.869Z,1683907984.869 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T16:13:04.869Z,1683907984.869 [DATMMP](INFO): Handled
2023-05-12T16:13:05.120Z,1683907985.120 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:05.122Z,1683907985.122 [DATMMP](INFO): directional_debug rx: 	 x185	 x100	 x20	 x1CB	 x2AA0	 x4661	 x6DF2	 x6AF3
2023-05-12T16:13:05.123Z,1683907985.123 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T16:13:05.123Z,1683907985.123 [DATMMP](INFO): Handled
2023-05-12T16:13:05.376Z,1683907985.376 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:05.378Z,1683907985.378 [DATMMP](INFO): directional rx: 	 x1A7	 xEF	 x457	 xFF00	 xB12	 xFF01	 x0	 x6BC	 x1	 xFFFF	 x27B5966	 x-43569E0
2023-05-12T16:13:05.380Z,1683907985.380 [DATMMP](INFO): Received message type: directional
2023-05-12T16:13:05.381Z,1683907985.381 [DATMMP](INFO): Handled
2023-05-12T16:13:05.628Z,1683907985.628 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:05.630Z,1683907985.630 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 x9	 xC	 x0
2023-05-12T16:13:05.630Z,1683907985.630 [DATMMP](INFO): DATA Src=12, Dst=9
2023-05-12T16:13:05.630Z,1683907985.630 [DATMMP](INFO): Received message type: remote_header
2023-05-12T16:13:05.630Z,1683907985.630 [DATMMP](INFO): Handled
2023-05-12T16:13:05.880Z,1683907985.880 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T16:13:05.882Z,1683907985.882 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x7C	 xC3	 xC6	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T16:13:05.882Z,1683907985.882 [DATMMP](INFO): Received message type: acstats
2023-05-12T16:13:05.883Z,1683907985.883 [DATMMP](INFO): Handled
2023-05-12T16:14:31.456Z,1683908071.456 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T16:09:31.3Z
2023-05-12T16:14:31.456Z,1683908071.456 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T16:14:31.457Z,1683908071.457 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T16:14:38.491Z,1683908078.491 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T155536/Courier0007.lzma
2023-05-12T16:14:39.493Z,1683908079.493 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Courier0007.lzma.bak
2023-05-12T16:14:39.493Z,1683908079.493 [DataOverHttps](INFO): SBD MOMSN=18295204
2023-05-12T16:14:54.019Z,1683908094.019 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20230512T155536/Express0008.lzma
2023-05-12T16:14:55.009Z,1683908095.009 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Express0008.lzma.bak
2023-05-12T16:14:55.010Z,1683908095.010 [DataOverHttps](INFO): SBD MOMSN=18295206
2023-05-12T16:14:56.606Z,1683908096.606 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T16:14:56.606Z,1683908096.606 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T16:14:56.606Z,1683908096.606 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T16:15:18.012Z,1683908118.012 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T16:15:48.732Z,1683908148.732 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-12T16:17:30.585Z,1683908250.585 [CommandExec](IMPORTANT): got command restart application
2023-05-12T16:17:31.588Z,1683908251.588 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-12T16:17:31.588Z,1683908251.588 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:31.588Z,1683908251.588 [CommandExec](INFO): Uninitializing the command executive.
2023-05-12T16:17:31.588Z,1683908251.588 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-12T16:17:31.589Z,1683908251.589 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:31.748Z,1683908251.748 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-12T16:17:31.748Z,1683908251.748 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-12T16:17:31.748Z,1683908251.748 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:31.749Z,1683908251.749 [NavChartDb](INFO): Join timeout helper Thread ID is 6924
2023-05-12T16:17:31.856Z,1683908251.856 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:31.856Z,1683908251.856 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:31.864Z,1683908251.864 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-12T16:17:31.864Z,1683908251.864 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:31.864Z,1683908251.864 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 6925
2023-05-12T16:17:32.264Z,1683908252.264 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:32.264Z,1683908252.264 [WetLabsUBAT](INFO): Powering down
2023-05-12T16:17:32.265Z,1683908252.265 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:32.272Z,1683908252.272 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-12T16:17:32.273Z,1683908252.273 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:32.273Z,1683908252.273 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6926
2023-05-12T16:17:32.640Z,1683908252.640 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:32.640Z,1683908252.640 [WetLabsBB2FL](INFO): Powering down
2023-05-12T16:17:32.641Z,1683908252.641 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:32.644Z,1683908252.644 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-12T16:17:32.644Z,1683908252.644 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:32.644Z,1683908252.644 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6927
2023-05-12T16:17:32.720Z,1683908252.720 [CTD_Seabird](INFO): Powering down
2023-05-12T16:17:32.732Z,1683908252.732 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:32.732Z,1683908252.732 [CTD_Seabird](INFO): Powering down
2023-05-12T16:17:32.744Z,1683908252.744 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:32.744Z,1683908252.744 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-12T16:17:32.744Z,1683908252.744 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:32.745Z,1683908252.745 [Radio_Surface](INFO): Join timeout helper Thread ID is 6928
2023-05-12T16:17:33.088Z,1683908253.088 [Radio_Surface](INFO): Powering down
2023-05-12T16:17:33.089Z,1683908253.089 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:33.089Z,1683908253.089 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:33.101Z,1683908253.101 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-12T16:17:33.101Z,1683908253.101 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:33.101Z,1683908253.101 [Onboard](INFO): Join timeout helper Thread ID is 6929
2023-05-12T16:17:33.872Z,1683908253.872 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-12T16:17:37.224Z,1683908257.224 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:37.224Z,1683908257.224 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:37.236Z,1683908257.236 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-12T16:17:37.236Z,1683908257.236 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:37.237Z,1683908257.237 [DataOverHttps](INFO): Join timeout helper Thread ID is 6930
2023-05-12T16:17:37.888Z,1683908257.888 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:37.888Z,1683908257.888 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:37.892Z,1683908257.892 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-12T16:17:37.893Z,1683908257.893 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:37.893Z,1683908257.893 [DATMMP](INFO): Join timeout helper Thread ID is 6931
2023-05-12T16:17:38.053Z,1683908258.053 [DATMMP](INFO): Stop
2023-05-12T16:17:38.053Z,1683908258.053 [DATMMP](INFO): uninitialize
2023-05-12T16:17:38.053Z,1683908258.053 [DATMMP](INFO): Powering down
2023-05-12T16:17:38.124Z,1683908258.124 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:38.125Z,1683908258.125 [DATMMP](INFO): uninitialize
2023-05-12T16:17:38.125Z,1683908258.125 [DATMMP](INFO): Powering down
2023-05-12T16:17:38.126Z,1683908258.126 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.144Z,1683908258.144 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-12T16:17:38.145Z,1683908258.145 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.145Z,1683908258.145 [BackseatComponent](INFO): Join timeout helper Thread ID is 6932
2023-05-12T16:17:38.312Z,1683908258.312 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:38.312Z,1683908258.312 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.320Z,1683908258.320 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-12T16:17:38.321Z,1683908258.321 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.321Z,1683908258.321 [logger](INFO): Join timeout helper Thread ID is 6933
2023-05-12T16:17:38.348Z,1683908258.348 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:38.348Z,1683908258.348 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.360Z,1683908258.360 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-12T16:17:38.360Z,1683908258.360 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.361Z,1683908258.361 [CommandLine](INFO): Join timeout helper Thread ID is 6934
2023-05-12T16:17:38.368Z,1683908258.368 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:38.368Z,1683908258.368 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.380Z,1683908258.380 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-12T16:17:38.380Z,1683908258.380 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.381Z,1683908258.381 [CommandExec](INFO): Join timeout helper Thread ID is 6935
2023-05-12T16:17:38.382Z,1683908258.382 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-12T16:17:38.382Z,1683908258.382 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.383Z,1683908258.383 [controlThread](INFO): Join timeout helper Thread ID is 6936
2023-05-12T16:17:38.600Z,1683908258.600 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T16:17:38.600Z,1683908258.600 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-12T16:17:38.601Z,1683908258.601 [AHRS_M2](INFO): Powering down
2023-05-12T16:17:38.672Z,1683908258.672 [NAL9602](INFO): Powering down
2023-05-12T16:17:38.744Z,1683908258.744 [RDI_Pathfinder](INFO): Powering down
2023-05-12T16:17:38.746Z,1683908258.746 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-12T16:17:38.746Z,1683908258.746 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-12T16:17:38.747Z,1683908258.747 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-12T16:17:38.747Z,1683908258.747 [MissionManager](INFO): Uninitializing Mission Default
2023-05-12T16:17:38.748Z,1683908258.748 [Default] Stopped
2023-05-12T16:17:38.748Z,1683908258.748 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-12T16:17:38.748Z,1683908258.748 [Default:B.GoToSurface] Stopped
2023-05-12T16:17:38.748Z,1683908258.748 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T16:17:38.748Z,1683908258.748 [Default:CheckIn] Stopped
2023-05-12T16:17:38.748Z,1683908258.748 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T16:17:38.748Z,1683908258.748 [Default:CheckIn:C.Wait] Stopped
2023-05-12T16:17:38.748Z,1683908258.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T16:17:38.751Z,1683908258.751 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-12T16:17:38.751Z,1683908258.751 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-12T16:17:38.751Z,1683908258.751 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-12T16:17:38.752Z,1683908258.752 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-12T16:17:38.752Z,1683908258.752 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-12T16:17:38.752Z,1683908258.752 [BuoyancyServo](INFO): Powering down
2023-05-12T16:17:38.764Z,1683908258.764 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-12T16:17:38.764Z,1683908258.764 [ElevatorServo](INFO): Powering down
2023-05-12T16:17:38.765Z,1683908258.765 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-12T16:17:38.765Z,1683908258.765 [MassServo](INFO): Powering down
2023-05-12T16:17:38.766Z,1683908258.766 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-12T16:17:38.766Z,1683908258.766 [RudderServo](INFO): Powering down
2023-05-12T16:17:38.767Z,1683908258.767 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-12T16:17:38.767Z,1683908258.767 [ThrusterServo](INFO): Powering down
2023-05-12T16:17:38.768Z,1683908258.768 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-12T16:17:38.768Z,1683908258.768 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-12T16:17:38.768Z,1683908258.768 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-12T16:17:38.768Z,1683908258.768 [CBIT](DEBUG): Powering off loads.
2023-05-12T16:17:38.779Z,1683908258.779 [CBIT](DEBUG): Disabling WDT.
2023-05-12T16:17:38.791Z,1683908258.791 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-12T16:17:38.793Z,1683908258.793 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.851Z,1683908258.851 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.853Z,1683908258.853 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.859Z,1683908258.859 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.865Z,1683908258.865 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.906Z,1683908258.906 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.975Z,1683908258.975 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.977Z,1683908258.977 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.980Z,1683908258.980 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:38.991Z,1683908258.991 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T16:17:39.053Z,1683908259.053 [logger ThreadHandler](INFO): Thread cancelled.