2023-05-12T18:06:24.415Z,1683914784.415 [Supervisor](DEBUG): Initializing supervisor.
2023-05-12T18:06:24.420Z,1683914784.420 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-12T18:06:24.420Z,1683914784.420 [SyncHandler](INFO): Protected caller Thread ID is 6927
2023-05-12T18:06:24.421Z,1683914784.421 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-12T18:06:24.422Z,1683914784.422 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-12T18:06:24.422Z,1683914784.422 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6928
2023-05-12T18:06:24.426Z,1683914784.426 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-12T18:06:24.443Z,1683914784.443 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-12T18:06:24.444Z,1683914784.444 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-12T18:06:24.444Z,1683914784.444 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6929
2023-05-12T18:06:24.448Z,1683914784.448 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-12T18:06:24.449Z,1683914784.449 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-12T18:06:24.450Z,1683914784.450 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6930
2023-05-12T18:06:24.452Z,1683914784.452 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-12T18:06:24.453Z,1683914784.453 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-12T18:06:24.453Z,1683914784.453 [logger ThreadHandler](INFO): Protected caller Thread ID is 6931
2023-05-12T18:06:24.456Z,1683914784.456 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-12T18:06:24.457Z,1683914784.457 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-12T18:06:24.458Z,1683914784.458 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-12T18:06:24.555Z,1683914784.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-12T18:06:24.555Z,1683914784.555 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-12T18:06:25.221Z,1683914785.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-12T18:06:25.221Z,1683914785.221 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-12T18:06:25.445Z,1683914785.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-12T18:06:25.446Z,1683914785.446 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-12T18:06:25.527Z,1683914785.527 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-12T18:06:25.658Z,1683914785.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-12T18:06:25.659Z,1683914785.659 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-12T18:06:25.740Z,1683914785.740 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-12T18:06:25.848Z,1683914785.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-12T18:06:25.848Z,1683914785.848 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-12T18:06:26.295Z,1683914786.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-12T18:06:26.295Z,1683914786.295 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-12T18:06:26.778Z,1683914786.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-12T18:06:26.779Z,1683914786.779 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-12T18:06:26.996Z,1683914786.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-12T18:06:26.997Z,1683914786.997 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-12T18:06:27.179Z,1683914787.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-12T18:06:27.180Z,1683914787.180 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-12T18:06:27.681Z,1683914787.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-12T18:06:27.681Z,1683914787.681 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-12T18:06:27.970Z,1683914787.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-12T18:06:27.970Z,1683914787.970 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-12T18:06:28.184Z,1683914788.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-12T18:06:28.185Z,1683914788.185 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-12T18:06:28.541Z,1683914788.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-12T18:06:28.541Z,1683914788.541 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-12T18:06:28.868Z,1683914788.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-12T18:06:28.870Z,1683914788.870 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-12T18:06:28.871Z,1683914788.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-12T18:06:28.955Z,1683914788.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-12T18:06:29.110Z,1683914789.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-12T18:06:29.227Z,1683914789.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-12T18:06:29.447Z,1683914789.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-12T18:06:29.656Z,1683914789.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-12T18:06:29.938Z,1683914789.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-12T18:06:30.196Z,1683914790.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-12T18:06:30.197Z,1683914790.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-12T18:06:30.291Z,1683914790.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-12T18:06:30.389Z,1683914790.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-12T18:06:30.528Z,1683914790.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-12T18:06:30.628Z,1683914790.628 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-05-12T18:06:30.628Z,1683914790.628 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-12T18:06:30.636Z,1683914790.636 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-12T18:06:30.730Z,1683914790.730 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-12T18:06:30.731Z,1683914790.731 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-12T18:06:30.780Z,1683914790.780 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-12T18:06:30.838Z,1683914790.838 [VerticalControl] Loaded
2023-05-12T18:06:30.838Z,1683914790.838 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-12T18:06:30.841Z,1683914790.841 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-12T18:06:30.880Z,1683914790.880 [HorizontalControl] Loaded
2023-05-12T18:06:30.880Z,1683914790.880 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-12T18:06:30.883Z,1683914790.883 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-12T18:06:30.886Z,1683914790.886 [SpeedControl] Loaded
2023-05-12T18:06:30.886Z,1683914790.886 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-12T18:06:30.889Z,1683914790.889 [LoopControl](DEBUG): Construct LoopControl.
2023-05-12T18:06:30.889Z,1683914790.889 [LoopControl] Loaded
2023-05-12T18:06:30.889Z,1683914790.889 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-12T18:06:30.890Z,1683914790.890 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-12T18:06:30.890Z,1683914790.890 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-12T18:06:30.937Z,1683914790.937 [DepthRateCalculator] Loaded
2023-05-12T18:06:30.937Z,1683914790.937 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-12T18:06:30.942Z,1683914790.942 [PitchRateCalculator] Loaded
2023-05-12T18:06:30.942Z,1683914790.942 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-12T18:06:30.952Z,1683914790.952 [SpeedCalculator] Loaded
2023-05-12T18:06:30.952Z,1683914790.952 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-12T18:06:30.956Z,1683914790.956 [YawRateCalculator] Loaded
2023-05-12T18:06:30.956Z,1683914790.956 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-12T18:06:30.974Z,1683914790.974 [ElevatorOffsetCalculator] Loaded
2023-05-12T18:06:30.974Z,1683914790.974 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-12T18:06:30.975Z,1683914790.975 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-12T18:06:30.975Z,1683914790.975 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-12T18:06:31.001Z,1683914791.001 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-12T18:06:31.002Z,1683914791.002 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-12T18:06:31.071Z,1683914791.071 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-12T18:06:31.072Z,1683914791.072 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-12T18:06:31.366Z,1683914791.366 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-12T18:06:31.366Z,1683914791.366 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-12T18:06:31.506Z,1683914791.506 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-12T18:06:31.507Z,1683914791.507 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-12T18:06:31.950Z,1683914791.950 [AHRS_M2] Loaded
2023-05-12T18:06:31.951Z,1683914791.951 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-12T18:06:31.985Z,1683914791.985 [BackseatComponent] Loaded
2023-05-12T18:06:31.985Z,1683914791.985 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-12T18:06:31.986Z,1683914791.986 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2023-05-12T18:06:31.987Z,1683914791.987 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7014
2023-05-12T18:06:31.989Z,1683914791.989 [LcmUniversalReporter] Loaded
2023-05-12T18:06:31.989Z,1683914791.989 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-12T18:06:32.777Z,1683914792.777 [BPC1] Loaded
2023-05-12T18:06:32.777Z,1683914792.777 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-12T18:06:32.859Z,1683914792.859 [DATMMP] Loaded
2023-05-12T18:06:32.859Z,1683914792.859 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-12T18:06:32.860Z,1683914792.860 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2023-05-12T18:06:32.860Z,1683914792.860 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 7015
2023-05-12T18:06:32.932Z,1683914792.932 [DataOverHttps] Loaded
2023-05-12T18:06:32.932Z,1683914792.932 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-12T18:06:32.933Z,1683914792.933 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0
2023-05-12T18:06:32.934Z,1683914792.934 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7016
2023-05-12T18:06:32.954Z,1683914792.954 [Depth_Keller] Loaded
2023-05-12T18:06:32.954Z,1683914792.954 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-12T18:06:33.015Z,1683914793.015 [NAL9602] Loaded
2023-05-12T18:06:33.015Z,1683914793.015 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-12T18:06:33.044Z,1683914793.044 [Onboard] Loaded
2023-05-12T18:06:33.044Z,1683914793.044 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-12T18:06:33.045Z,1683914793.045 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-05-12T18:06:33.045Z,1683914793.045 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7017
2023-05-12T18:06:33.057Z,1683914793.057 [Power24vConverter] Loaded
2023-05-12T18:06:33.058Z,1683914793.058 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-12T18:06:33.070Z,1683914793.070 [Radio_Surface] Loaded
2023-05-12T18:06:33.070Z,1683914793.070 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-12T18:06:33.071Z,1683914793.071 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-05-12T18:06:33.072Z,1683914793.072 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7018
2023-05-12T18:06:33.115Z,1683914793.115 [RDI_Pathfinder] Loaded
2023-05-12T18:06:33.116Z,1683914793.116 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-12T18:06:33.116Z,1683914793.116 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-12T18:06:33.117Z,1683914793.117 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-12T18:06:33.223Z,1683914793.223 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-12T18:06:33.223Z,1683914793.223 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-12T18:06:33.236Z,1683914793.236 [NavChart] Loaded
2023-05-12T18:06:33.236Z,1683914793.236 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-12T18:06:33.242Z,1683914793.242 [UniversalFixResidualReporter] Loaded
2023-05-12T18:06:33.242Z,1683914793.242 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-12T18:06:33.243Z,1683914793.243 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-12T18:06:33.243Z,1683914793.243 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-12T18:06:33.377Z,1683914793.377 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-12T18:06:33.386Z,1683914793.386 [SBIT] Loaded
2023-05-12T18:06:33.386Z,1683914793.386 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-12T18:06:33.389Z,1683914793.389 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-12T18:06:33.401Z,1683914793.401 [IBIT] Loaded
2023-05-12T18:06:33.401Z,1683914793.401 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-12T18:06:33.407Z,1683914793.407 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-12T18:06:33.508Z,1683914793.508 [CBIT] Loaded
2023-05-12T18:06:33.508Z,1683914793.508 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-12T18:06:33.508Z,1683914793.508 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-12T18:06:33.509Z,1683914793.509 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-12T18:06:33.636Z,1683914793.636 [BuoyancyServo] Loaded
2023-05-12T18:06:33.637Z,1683914793.637 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-12T18:06:33.656Z,1683914793.656 [ElevatorServo] Loaded
2023-05-12T18:06:33.657Z,1683914793.657 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-12T18:06:33.676Z,1683914793.676 [MassServo] Loaded
2023-05-12T18:06:33.676Z,1683914793.676 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-12T18:06:33.694Z,1683914793.694 [RudderServo] Loaded
2023-05-12T18:06:33.694Z,1683914793.694 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-12T18:06:33.711Z,1683914793.711 [ThrusterServo] Loaded
2023-05-12T18:06:33.711Z,1683914793.711 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-12T18:06:33.712Z,1683914793.712 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-12T18:06:33.712Z,1683914793.712 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-12T18:06:33.722Z,1683914793.722 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-12T18:06:33.722Z,1683914793.722 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-12T18:06:33.887Z,1683914793.887 [CTD_Seabird] Loaded
2023-05-12T18:06:33.887Z,1683914793.887 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-12T18:06:33.888Z,1683914793.888 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0
2023-05-12T18:06:33.889Z,1683914793.889 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7019
2023-05-12T18:06:33.908Z,1683914793.908 [PAR_Licor] Loaded
2023-05-12T18:06:33.909Z,1683914793.909 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-12T18:06:33.939Z,1683914793.939 [WetLabsBB2FL] Loaded
2023-05-12T18:06:33.939Z,1683914793.939 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-12T18:06:33.940Z,1683914793.940 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0
2023-05-12T18:06:33.940Z,1683914793.940 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7020
2023-05-12T18:06:33.959Z,1683914793.959 [WetLabsUBAT] Loaded
2023-05-12T18:06:33.960Z,1683914793.960 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-12T18:06:33.961Z,1683914793.961 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0
2023-05-12T18:06:33.961Z,1683914793.961 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7021
2023-05-12T18:06:33.961Z,1683914793.961 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-12T18:06:33.968Z,1683914793.968 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-12T18:06:33.970Z,1683914793.970 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-12T18:06:33.981Z,1683914793.981 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-12T18:06:33.982Z,1683914793.982 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0
2023-05-12T18:06:33.982Z,1683914793.982 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7022
2023-05-12T18:06:33.987Z,1683914793.987 [Supervisor](INFO): Main Thread ID is 6926
2023-05-12T18:06:33.987Z,1683914793.987 [Supervisor](DEBUG): Running supervisor.
2023-05-12T18:06:33.988Z,1683914793.988 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7023
2023-05-12T18:06:33.988Z,1683914793.988 [CommandExec](INFO): Initializing the command executive.
2023-05-12T18:06:33.990Z,1683914793.990 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7024
2023-05-12T18:06:33.992Z,1683914793.992 [controlThread ThreadHandler](INFO): Handler Thread ID is 7025
2023-05-12T18:06:33.992Z,1683914793.992 [controlThread](DEBUG): Initializing ControlThread
2023-05-12T18:06:33.993Z,1683914793.993 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-12T18:06:33.995Z,1683914793.995 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-12T18:06:33.996Z,1683914793.996 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-12T18:06:33.996Z,1683914793.996 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-12T18:06:33.996Z,1683914793.996 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-12T18:06:33.997Z,1683914793.997 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-12T18:06:33.997Z,1683914793.997 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-12T18:06:33.998Z,1683914793.998 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-12T18:06:33.998Z,1683914793.998 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-12T18:06:34.004Z,1683914794.004 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-12T18:06:34.005Z,1683914794.005 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-12T18:06:34.005Z,1683914794.005 [SBIT](INFO): Initialize SBIT Component.
2023-05-12T18:06:34.005Z,1683914794.005 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-12T18:06:34.006Z,1683914794.006 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-12T18:06:34.006Z,1683914794.006 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-12T18:06:34.007Z,1683914794.007 [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:06:34.008Z,1683914794.008 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-12T18:06:34.009Z,1683914794.009 [IBIT](INFO): Initialize IBIT Component.
2023-05-12T18:06:34.010Z,1683914794.010 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-12T18:06:34.011Z,1683914794.011 [logger ThreadHandler](INFO): Handler Thread ID is 7026
2023-05-12T18:06:34.023Z,1683914794.023 [CBIT](DEBUG): Initialized mux pins.
2023-05-12T18:06:34.023Z,1683914794.023 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-12T18:06:34.028Z,1683914794.028 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7027
2023-05-12T18:06:34.036Z,1683914794.036 [DATMMP ThreadHandler](INFO): Handler Thread ID is 7028
2023-05-12T18:06:34.036Z,1683914794.036 [DATMMP](INFO): Start
2023-05-12T18:06:34.036Z,1683914794.036 [DATMMP](INFO): Powering up
2023-05-12T18:06:34.037Z,1683914794.037 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-12T18:06:34.040Z,1683914794.040 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7030
2023-05-12T18:06:34.041Z,1683914794.041 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-12T18:06:34.047Z,1683914794.047 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-12T18:06:34.047Z,1683914794.047 [CBIT](DEBUG): Initializing heartbeat.
2023-05-12T18:06:34.048Z,1683914794.048 [Onboard ThreadHandler](INFO): Handler Thread ID is 7031
2023-05-12T18:06:34.070Z,1683914794.070 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7032
2023-05-12T18:06:34.084Z,1683914794.084 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7033
2023-05-12T18:06:34.084Z,1683914794.084 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-12T18:06:34.088Z,1683914794.088 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7035
2023-05-12T18:06:34.092Z,1683914794.092 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7037
2023-05-12T18:06:34.094Z,1683914794.094 [WetLabsBB2FL](INFO): Powering up
2023-05-12T18:06:34.096Z,1683914794.096 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7039
2023-05-12T18:06:34.098Z,1683914794.098 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-12T18:06:34.099Z,1683914794.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-12T18:06:34.099Z,1683914794.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-12T18:06:34.099Z,1683914794.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-12T18:06:34.099Z,1683914794.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-12T18:06:34.100Z,1683914794.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-12T18:06:34.100Z,1683914794.100 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-12T18:06:34.100Z,1683914794.100 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-12T18:06:34.115Z,1683914794.115 [WetLabsUBAT](INFO): Powering up
2023-05-12T18:06:34.119Z,1683914794.119 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-12T18:06:34.119Z,1683914794.119 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-12T18:06:34.159Z,1683914794.159 [CBIT](DEBUG): Backplane powered.
2023-05-12T18:06:34.164Z,1683914794.164 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-12T18:06:34.193Z,1683914794.193 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T18:06:34.220Z,1683914794.220 [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:06:34.221Z,1683914794.221 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-12T18:06:34.287Z,1683914794.287 [DATMMP](INFO): Starting
2023-05-12T18:06:34.359Z,1683914794.359 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-12T18:06:34.392Z,1683914794.392 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-12T18:06:34.394Z,1683914794.394 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-12T18:06:34.483Z,1683914794.483 [Radio_Surface](INFO): Powering up
2023-05-12T18:06:34.487Z,1683914794.487 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-12T18:06:34.539Z,1683914794.539 [DATMMP](INFO): Starting
2023-05-12T18:06:34.573Z,1683914794.573 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-12T18:06:34.601Z,1683914794.601 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-12T18:06:34.613Z,1683914794.613 [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:06:34.617Z,1683914794.617 [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:06:34.640Z,1683914794.640 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-12T18:06:34.694Z,1683914794.694 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-05-12T18:06:34.694Z,1683914794.694 [Power24vConverter](INFO): Powering up.
2023-05-12T18:06:34.696Z,1683914794.696 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-12T18:06:34.718Z,1683914794.718 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-12T18:06:34.792Z,1683914794.792 [DATMMP](INFO): Starting
2023-05-12T18:06:34.815Z,1683914794.815 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:06:34.831Z,1683914794.831 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-12T18:06:34.832Z,1683914794.832 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:06:34.839Z,1683914794.839 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-12T18:06:34.840Z,1683914794.840 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:06:34.851Z,1683914794.851 [MassServo](DEBUG): Initializing MassServo.
2023-05-12T18:06:34.852Z,1683914794.852 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:06:34.859Z,1683914794.859 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-12T18:06:34.860Z,1683914794.860 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-12T18:06:34.867Z,1683914794.867 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-12T18:06:35.043Z,1683914795.043 [DATMMP](INFO): Starting
2023-05-12T18:06:35.295Z,1683914795.295 [DATMMP](INFO): Starting
2023-05-12T18:06:35.547Z,1683914795.547 [DATMMP](INFO): Starting
2023-05-12T18:06:35.799Z,1683914795.799 [DATMMP](INFO): Starting
2023-05-12T18:06:36.051Z,1683914796.051 [DATMMP](INFO): Starting
2023-05-12T18:06:36.303Z,1683914796.303 [DATMMP](INFO): Starting
2023-05-12T18:06:36.555Z,1683914796.555 [DATMMP](INFO): Starting
2023-05-12T18:06:36.703Z,1683914796.703 [WetLabsBB2FL](INFO): Powering down
2023-05-12T18:06:36.807Z,1683914796.807 [DATMMP](INFO): Starting
2023-05-12T18:06:37.059Z,1683914797.059 [DATMMP](INFO): Starting
2023-05-12T18:06:37.311Z,1683914797.311 [DATMMP](INFO): Starting
2023-05-12T18:06:37.563Z,1683914797.563 [DATMMP](INFO): Starting
2023-05-12T18:06:37.815Z,1683914797.815 [DATMMP](INFO): Starting
2023-05-12T18:06:38.067Z,1683914798.067 [DATMMP](INFO): Starting
2023-05-12T18:06:38.319Z,1683914798.319 [DATMMP](INFO): Starting
2023-05-12T18:06:38.571Z,1683914798.571 [DATMMP](INFO): Starting
2023-05-12T18:06:38.823Z,1683914798.823 [DATMMP](INFO): Starting
2023-05-12T18:06:39.076Z,1683914799.076 [DATMMP](INFO): Starting
2023-05-12T18:06:39.327Z,1683914799.327 [DATMMP](INFO): Starting
2023-05-12T18:06:39.579Z,1683914799.579 [DATMMP](INFO): Starting
2023-05-12T18:06:39.831Z,1683914799.831 [DATMMP](INFO): Starting
2023-05-12T18:06:40.083Z,1683914800.083 [DATMMP](INFO): Starting
2023-05-12T18:06:40.335Z,1683914800.335 [DATMMP](INFO): Starting
2023-05-12T18:06:40.587Z,1683914800.587 [DATMMP](INFO): Starting
2023-05-12T18:06:40.839Z,1683914800.839 [DATMMP](INFO): Starting
2023-05-12T18:06:41.091Z,1683914801.091 [DATMMP](INFO): Starting
2023-05-12T18:06:41.343Z,1683914801.343 [DATMMP](INFO): Starting
2023-05-12T18:06:41.595Z,1683914801.595 [DATMMP](INFO): Starting
2023-05-12T18:06:41.847Z,1683914801.847 [DATMMP](INFO): Starting
2023-05-12T18:06:42.099Z,1683914802.099 [DATMMP](INFO): Starting
2023-05-12T18:06:42.351Z,1683914802.351 [DATMMP](INFO): Starting
2023-05-12T18:06:42.603Z,1683914802.603 [DATMMP](INFO): Starting
2023-05-12T18:06:42.855Z,1683914802.855 [DATMMP](INFO): Starting
2023-05-12T18:06:43.107Z,1683914803.107 [DATMMP](INFO): Starting
2023-05-12T18:06:43.359Z,1683914803.359 [DATMMP](INFO): Starting
2023-05-12T18:06:43.363Z,1683914803.363 [WetLabsUBAT](INFO): Powering down
2023-05-12T18:06:43.611Z,1683914803.611 [DATMMP](INFO): Starting
2023-05-12T18:06:43.863Z,1683914803.863 [DATMMP](INFO): Starting
2023-05-12T18:06:44.115Z,1683914804.115 [DATMMP](INFO): Starting
2023-05-12T18:06:44.367Z,1683914804.367 [DATMMP](INFO): Starting
2023-05-12T18:06:44.619Z,1683914804.619 [DATMMP](INFO): Starting
2023-05-12T18:06:44.871Z,1683914804.871 [DATMMP](INFO): Starting
2023-05-12T18:06:45.123Z,1683914805.123 [DATMMP](INFO): Starting
2023-05-12T18:06:45.375Z,1683914805.375 [DATMMP](INFO): Starting
2023-05-12T18:06:45.627Z,1683914805.627 [DATMMP](INFO): Starting
2023-05-12T18:06:45.880Z,1683914805.880 [DATMMP](INFO): Starting
2023-05-12T18:06:46.131Z,1683914806.131 [DATMMP](INFO): Starting
2023-05-12T18:06:46.132Z,1683914806.132 [DATMMP](INFO): DAT read: 

2023-05-12T18:06:46.133Z,1683914806.133 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-12T18:06:46.383Z,1683914806.383 [DATMMP](INFO): Starting
2023-05-12T18:06:46.635Z,1683914806.635 [DATMMP](INFO): Starting
2023-05-12T18:06:46.887Z,1683914806.887 [DATMMP](INFO): Starting
2023-05-12T18:06:47.139Z,1683914807.139 [DATMMP](INFO): Starting
2023-05-12T18:06:47.391Z,1683914807.391 [DATMMP](INFO): Starting
2023-05-12T18:06:47.643Z,1683914807.643 [DATMMP](INFO): Starting
2023-05-12T18:06:47.895Z,1683914807.895 [DATMMP](INFO): Starting
2023-05-12T18:06:47.896Z,1683914807.896 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-12T18:06:47.897Z,1683914807.897 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-12T18:06:47.898Z,1683914807.898 [DATMMP](INFO): DAT read: Apr 12 2000 17:34:34 

2023-05-12T18:06:48.147Z,1683914808.147 [DATMMP](INFO): Starting
2023-05-12T18:06:48.277Z,1683914808.277 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004576
2023-05-12T18:06:48.399Z,1683914808.399 [DATMMP](INFO): Starting
2023-05-12T18:06:48.651Z,1683914808.651 [DATMMP](INFO): Starting
2023-05-12T18:06:48.903Z,1683914808.903 [DATMMP](INFO): Starting
2023-05-12T18:06:48.904Z,1683914808.904 [DATMMP](INFO): DAT read: Features enabled [Bearing]

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

2023-05-12T18:06:48.906Z,1683914808.906 [DATMMP](INFO): commRate: 800
2023-05-12T18:06:49.155Z,1683914809.155 [DATMMP](INFO): Starting
2023-05-12T18:06:49.407Z,1683914809.407 [DATMMP](INFO): Starting
2023-05-12T18:06:49.659Z,1683914809.659 [DATMMP](INFO): Starting
2023-05-12T18:06:49.911Z,1683914809.911 [DATMMP](INFO): Starting
2023-05-12T18:06:50.164Z,1683914810.164 [DATMMP](INFO): Starting
2023-05-12T18:06:50.415Z,1683914810.415 [DATMMP](INFO): Starting
2023-05-12T18:06:50.667Z,1683914810.667 [DATMMP](INFO): Starting
2023-05-12T18:06:50.919Z,1683914810.919 [DATMMP](INFO): Starting
2023-05-12T18:06:50.971Z,1683914810.971 [DATMMP](INFO): entering command mode
2023-05-12T18:06:51.171Z,1683914811.171 [DATMMP](INFO): Starting
2023-05-12T18:06:51.172Z,1683914811.172 [DATMMP](INFO): DAT read: 

2023-05-12T18:06:51.172Z,1683914811.172 [DATMMP](INFO): DAT read: user:1>

2023-05-12T18:06:51.172Z,1683914811.172 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-12T18:06:51.173Z,1683914811.173 [DATMMP](INFO): entering MMP mode
2023-05-12T18:06:51.424Z,1683914811.424 [DATMMP](INFO): Starting
2023-05-12T18:06:51.425Z,1683914811.425 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:06:51.426Z,1683914811.426 [DATMMP](INFO): status rx: 	 x1
2023-05-12T18:06:51.427Z,1683914811.427 [DATMMP](INFO): MMP status: 1: started
2023-05-12T18:06:51.427Z,1683914811.427 [DATMMP](INFO): Received message type: status
2023-05-12T18:06:51.427Z,1683914811.427 [DATMMP](INFO): Handled
2023-05-12T18:06:51.675Z,1683914811.675 [DATMMP](INFO): Starting
2023-05-12T18:06:51.676Z,1683914811.676 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-12T18:06:51.677Z,1683914811.677 [DATMMP](INFO): Setting local address: 10
2023-05-12T18:06:51.679Z,1683914811.679 [DATMMP](INFO): Setting time to: 18:6:51 And date to:5/12/2023
2023-05-12T18:06:51.927Z,1683914811.927 [DATMMP](INFO): Starting
2023-05-12T18:06:51.928Z,1683914811.928 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:06:51.929Z,1683914811.929 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-12T18:06:51.929Z,1683914811.929 [DATMMP](INFO): Received message type: mdmpower
2023-05-12T18:06:51.929Z,1683914811.929 [DATMMP](INFO): Handled
2023-05-12T18:06:52.180Z,1683914812.180 [DATMMP](INFO): Starting
2023-05-12T18:06:52.181Z,1683914812.181 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-12T18:06:52.184Z,1683914812.184 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-12T18:06:52.185Z,1683914812.185 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-12T18:06:52.185Z,1683914812.185 [DATMMP](INFO): Match for : sreg_txpower
2023-05-12T18:06:52.186Z,1683914812.186 [DATMMP](INFO): ACK
2023-05-12T18:06:52.431Z,1683914812.431 [DATMMP](INFO): Starting
2023-05-12T18:06:52.432Z,1683914812.432 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:06:52.433Z,1683914812.433 [DATMMP](INFO): mdmlocal rx: 	 xA	 x0	 x0	 x0
2023-05-12T18:06:52.433Z,1683914812.433 [DATMMP](INFO): Received message type: mdmlocal
2023-05-12T18:06:52.433Z,1683914812.433 [DATMMP](INFO): Handled
2023-05-12T18:06:52.683Z,1683914812.683 [DATMMP](INFO): Starting
2023-05-12T18:06:52.684Z,1683914812.684 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-12T18:06:52.684Z,1683914812.684 [DATMMP](INFO): sreg_locaddr rx: 	 xA
2023-05-12T18:06:52.684Z,1683914812.684 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-12T18:06:52.685Z,1683914812.685 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-12T18:06:52.685Z,1683914812.685 [DATMMP](INFO): ACK
2023-05-12T18:06:52.935Z,1683914812.935 [DATMMP](INFO): Starting
2023-05-12T18:06:52.936Z,1683914812.936 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-12T18:06:52.937Z,1683914812.937 [DATMMP](INFO): timedate rx: 	 x0	 x12	 x6	 x33	 x4	 xC	 x7B	 x0
2023-05-12T18:06:52.937Z,1683914812.937 [DATMMP](INFO): Received message type: timedate
2023-05-12T18:06:52.937Z,1683914812.937 [DATMMP](INFO): Match for : timedate
2023-05-12T18:06:52.937Z,1683914812.937 [DATMMP](INFO): ACK
2023-05-12T18:06:53.187Z,1683914813.187 [DATMMP](INFO): Starting
2023-05-12T18:06:53.188Z,1683914813.188 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:06:53.189Z,1683914813.189 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-12T18:06:53.189Z,1683914813.189 [DATMMP](INFO): Received message type: sys_verb
2023-05-12T18:06:53.189Z,1683914813.189 [DATMMP](INFO): Handled
2023-05-12T18:06:53.439Z,1683914813.439 [DATMMP](INFO): Starting
2023-05-12T18:06:53.440Z,1683914813.440 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-12T18:06:53.440Z,1683914813.440 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-12T18:06:53.441Z,1683914813.441 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-12T18:06:53.441Z,1683914813.441 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-12T18:06:53.441Z,1683914813.441 [DATMMP](INFO): ACK
2023-05-12T18:06:53.691Z,1683914813.691 [DATMMP](INFO): Starting
2023-05-12T18:06:53.692Z,1683914813.692 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-12T18:06:53.692Z,1683914813.692 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-12T18:06:53.693Z,1683914813.693 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-12T18:06:53.693Z,1683914813.693 [DATMMP](INFO): Received message type: version
2023-05-12T18:06:53.693Z,1683914813.693 [DATMMP](INFO): Match for : version
2023-05-12T18:06:53.693Z,1683914813.693 [DATMMP](INFO): ACK
2023-05-12T18:06:53.693Z,1683914813.693 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-12T18:07:03.312Z,1683914823.312 [NAL9602](INFO): Powering up NAL9602
2023-05-12T18:07:08.056Z,1683914828.056 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:07:08.057Z,1683914828.057 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-12T18:07:08.057Z,1683914828.057 [DATMMP](INFO): Received message type: timesync
2023-05-12T18:07:08.057Z,1683914828.057 [DATMMP](INFO): Handled
2023-05-12T18:07:14.264Z,1683914834.264 [NAL9602](INFO): NAL9602 initialized
2023-05-12T18:07:17.888Z,1683914837.888 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:07:17.889Z,1683914837.889 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-12T18:07:17.889Z,1683914837.889 [DATMMP](INFO): Received message type: timesync
2023-05-12T18:07:17.889Z,1683914837.889 [DATMMP](INFO): Handled
2023-05-12T18:07:25.566Z,1683914845.566 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-12T18:07:25.575Z,1683914845.575 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-12T18:07:36.539Z,1683914856.539 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000778
CHAN A1 (24V): 0.000327
CHAN A2 (12V): -0.007804
CHAN A3 (5V): -0.002371
CHAN B0 (3.3V): 0.000185
CHAN B1 (3.15aV): -0.000079
CHAN B2 (3.15bV): 0.000305
CHAN B3 (GND): 0.001869
OPEN: 0.005043
Full Scale: +/- 1 mA
2023-05-12T18:08:19.685Z,1683914899.685 [SBIT](IMPORTANT): SBIT PASSED
2023-05-12T18:08:19.685Z,1683914899.685 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-12T18:08:19.687Z,1683914899.687 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-12T18:08:19.687Z,1683914899.687 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-12T18:08:19.689Z,1683914899.689 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-12T18:08:19.689Z,1683914899.689 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-12T18:08:19.690Z,1683914899.690 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-12T18:08:19.690Z,1683914899.690 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-12T18:08:19.690Z,1683914899.690 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-12T18:08:20.124Z,1683914900.124 [MissionManager](IMPORTANT): Started mission Startup
2023-05-12T18:08:20.125Z,1683914900.125 [Startup] Running Loop=1
2023-05-12T18:08:20.125Z,1683914900.125 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-12T18:08:20.125Z,1683914900.125 [Startup:A.GoToSurface] Running Loop=1
2023-05-12T18:08:20.125Z,1683914900.125 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T18:08:20.126Z,1683914900.126 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T18:08:20.126Z,1683914900.126 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T18:08:20.126Z,1683914900.126 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T18:08:20.127Z,1683914900.127 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T18:08:20.127Z,1683914900.127 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T18:08:20.129Z,1683914900.129 [Startup:StartupSatComms] Running Loop=1
2023-05-12T18:08:20.129Z,1683914900.129 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-12T18:08:20.129Z,1683914900.129 [Startup:StartupSatComms:A] Running Loop=1
2023-05-12T18:08:20.524Z,1683914900.524 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-12T18:08:43.697Z,1683914923.697 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-12T18:08:43.700Z,1683914923.700 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-12T18:08:43.701Z,1683914923.701 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-12T18:08:43.732Z,1683914923.732 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-12T18:08:43.733Z,1683914923.733 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-12T18:08:43.736Z,1683914923.736 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-12T18:08:43.737Z,1683914923.737 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-12T18:08:43.738Z,1683914923.738 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-12T18:08:43.958Z,1683914923.958 [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:08:44.355Z,1683914924.355 [Startup:StartupSatComms:A] Stopped
2023-05-12T18:08:44.355Z,1683914924.355 [Startup:StartupSatComms:B] Running Loop=1
2023-05-12T18:08:44.741Z,1683914924.741 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T18:08:52.375Z,1683914932.375 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20230512T172055/Courier0000.lzma
2023-05-12T18:08:53.377Z,1683914933.377 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172055/Courier0000.lzma.bak
2023-05-12T18:08:53.377Z,1683914933.377 [DataOverHttps](INFO): SBD MOMSN=18297790
2023-05-12T18:09:09.507Z,1683914949.507 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T172253/Courier0000.lzma
2023-05-12T18:09:10.014Z,1683914950.014 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683914933 second
2023-05-12T18:09:10.509Z,1683914950.509 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172253/Courier0000.lzma.bak
2023-05-12T18:09:10.509Z,1683914950.509 [DataOverHttps](INFO): SBD MOMSN=18297796
2023-05-12T18:09:11.424Z,1683914951.424 [CommandExec](IMPORTANT): got command get depth
2023-05-12T18:09:11.425Z,1683914951.425 [CommandExec](IMPORTANT): depth 0.800000 m
2023-05-12T18:09:26.851Z,1683914966.851 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T172410/Courier0000.lzma
2023-05-12T18:09:27.853Z,1683914967.853 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172410/Courier0000.lzma.bak
2023-05-12T18:09:27.853Z,1683914967.853 [DataOverHttps](INFO): SBD MOMSN=18297802
2023-05-12T18:09:44.095Z,1683914984.095 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T172603/Courier0000.lzma
2023-05-12T18:09:44.554Z,1683914984.554 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T18:08:44.4Z
2023-05-12T18:09:44.554Z,1683914984.554 [Startup:StartupSatComms:B] Stopped
2023-05-12T18:09:44.554Z,1683914984.554 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-12T18:09:44.554Z,1683914984.554 [Startup:StartupSatComms] Stopped
2023-05-12T18:09:44.554Z,1683914984.554 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-12T18:09:44.555Z,1683914984.555 [Startup](INFO): Completed Startup
2023-05-12T18:09:44.555Z,1683914984.555 [MissionManager](INFO): Startup is completed.
2023-05-12T18:09:44.555Z,1683914984.555 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-12T18:09:44.556Z,1683914984.556 [Startup] Stopped
2023-05-12T18:09:44.556Z,1683914984.556 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-12T18:09:44.556Z,1683914984.556 [Startup:A.GoToSurface] Stopped
2023-05-12T18:09:44.556Z,1683914984.556 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T18:09:44.960Z,1683914984.960 [MissionManager](IMPORTANT): Started mission Default
2023-05-12T18:09:44.960Z,1683914984.960 [Default] Running Loop=1
2023-05-12T18:09:44.960Z,1683914984.960 [Default](DEBUG): Aggregate::initialize Default
2023-05-12T18:09:44.960Z,1683914984.960 [Default:B.GoToSurface] Running Loop=1
2023-05-12T18:09:44.960Z,1683914984.960 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-12T18:09:44.961Z,1683914984.961 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-12T18:09:44.961Z,1683914984.961 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-12T18:09:44.961Z,1683914984.961 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-12T18:09:44.962Z,1683914984.962 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-12T18:09:44.962Z,1683914984.962 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-12T18:09:44.962Z,1683914984.962 [Default:A.Wait] Running Loop=1
2023-05-12T18:09:44.962Z,1683914984.962 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-12T18:09:45.097Z,1683914985.097 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172603/Courier0000.lzma.bak
2023-05-12T18:09:45.097Z,1683914985.097 [DataOverHttps](INFO): SBD MOMSN=18297805
2023-05-12T18:09:58.293Z,1683914998.293 [Default:A.Wait](INFO): Done Waiting.
2023-05-12T18:09:58.293Z,1683914998.293 [Default:A.Wait] Stopped
2023-05-12T18:09:58.293Z,1683914998.293 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T18:09:58.677Z,1683914998.677 [Default:CheckIn] Running Loop=1
2023-05-12T18:09:58.677Z,1683914998.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T18:09:58.677Z,1683914998.677 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T18:09:59.070Z,1683914999.070 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-12T18:10:08.592Z,1683915008.592 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:08.592Z,1683915008.592 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:10:08.593Z,1683915008.593 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:10:08.593Z,1683915008.593 [DATMMP](INFO): Handled
2023-05-12T18:10:08.844Z,1683915008.844 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:08.845Z,1683915008.845 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xA	 x6	 x11C7
2023-05-12T18:10:08.846Z,1683915008.846 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915050.464896
2023-05-12T18:10:08.846Z,1683915008.846 [DATMMP](INFO): Rx ping set to:1683915008.845252
2023-05-12T18:10:08.846Z,1683915008.846 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:08.847Z,1683915008.847 [DATMMP](INFO): Handled
2023-05-12T18:10:09.097Z,1683915009.097 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:09.099Z,1683915009.099 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:10:09.099Z,1683915009.099 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:10:09.100Z,1683915009.100 [DATMMP](INFO): Handled
2023-05-12T18:10:09.348Z,1683915009.348 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:09.349Z,1683915009.349 [DATMMP](INFO): directional_debug rx: 	 x18A	 xFC	 x22	 x1E5	 x2960	 x3471	 x5F52	 x5973
2023-05-12T18:10:09.350Z,1683915009.350 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:10:09.351Z,1683915009.351 [DATMMP](INFO): Handled
2023-05-12T18:10:09.600Z,1683915009.600 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:09.609Z,1683915009.609 [DATMMP](INFO): directional rx: 	 x1AC	 xEC	 x452	 xFF04	 xB17	 xFF02	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:10:09.621Z,1683915009.621 [DATMMP](INFO): Received message type: directional
2023-05-12T18:10:09.621Z,1683915009.621 [DATMMP](INFO): Handled
2023-05-12T18:10:09.852Z,1683915009.852 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:09.853Z,1683915009.853 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:10:09.853Z,1683915009.853 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:10:09.854Z,1683915009.854 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:10:09.854Z,1683915009.854 [DATMMP](INFO): Handled
2023-05-12T18:10:10.104Z,1683915010.104 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:10.106Z,1683915010.106 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x81	 xAC	 xAB	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:10:10.106Z,1683915010.106 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:10:10.106Z,1683915010.106 [DATMMP](INFO): Handled
2023-05-12T18:10:10.356Z,1683915010.356 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:10.357Z,1683915010.357 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xA	 x8	 x244
2023-05-12T18:10:10.357Z,1683915010.357 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:10.357Z,1683915010.357 [DATMMP](INFO): Handled
2023-05-12T18:10:23.732Z,1683915023.732 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:23.733Z,1683915023.733 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T18:10:23.733Z,1683915023.733 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:10:23.733Z,1683915023.733 [DATMMP](INFO): Handled
2023-05-12T18:10:23.984Z,1683915023.984 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:23.985Z,1683915023.985 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xA	 x15	 x17A3
2023-05-12T18:10:23.985Z,1683915023.985 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915080.096256
2023-05-12T18:10:23.986Z,1683915023.986 [DATMMP](INFO): Rx ping set to:1683915023.984851
2023-05-12T18:10:23.986Z,1683915023.986 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:23.986Z,1683915023.986 [DATMMP](INFO): Handled
2023-05-12T18:10:24.238Z,1683915024.238 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:24.241Z,1683915024.241 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:10:24.242Z,1683915024.242 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:10:24.242Z,1683915024.242 [DATMMP](INFO): Handled
2023-05-12T18:10:24.488Z,1683915024.488 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:24.489Z,1683915024.489 [DATMMP](INFO): directional_debug rx: 	 x187	 xF4	 x22	 x1BA	 x2A40	 x40D1	 x6872	 x5843
2023-05-12T18:10:24.490Z,1683915024.490 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:10:24.490Z,1683915024.490 [DATMMP](INFO): Handled
2023-05-12T18:10:24.740Z,1683915024.740 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:24.741Z,1683915024.741 [DATMMP](INFO): directional rx: 	 x1A9	 xE5	 x455	 xFF0C	 xB1B	 xFF0B	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:10:24.743Z,1683915024.743 [DATMMP](INFO): Received message type: directional
2023-05-12T18:10:24.743Z,1683915024.743 [DATMMP](INFO): Handled
2023-05-12T18:10:24.996Z,1683915024.996 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:24.997Z,1683915024.997 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:10:24.998Z,1683915024.998 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:10:24.998Z,1683915024.998 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:10:24.998Z,1683915024.998 [DATMMP](INFO): Handled
2023-05-12T18:10:25.248Z,1683915025.248 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:25.250Z,1683915025.250 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x23	 x7B	 xAE	 xA8	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:10:25.250Z,1683915025.250 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:10:25.250Z,1683915025.250 [DATMMP](INFO): Handled
2023-05-12T18:10:25.500Z,1683915025.500 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:25.501Z,1683915025.501 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xA	 x17	 x820
2023-05-12T18:10:25.501Z,1683915025.501 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:25.501Z,1683915025.501 [DATMMP](INFO): Handled
2023-05-12T18:10:39.128Z,1683915039.128 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:39.128Z,1683915039.128 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:10:39.129Z,1683915039.129 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:10:39.129Z,1683915039.129 [DATMMP](INFO): Handled
2023-05-12T18:10:39.380Z,1683915039.380 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:39.381Z,1683915039.381 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xA	 x25	 x33
2023-05-12T18:10:39.381Z,1683915039.381 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915037.522240
2023-05-12T18:10:39.382Z,1683915039.382 [DATMMP](INFO): Rx ping set to:1683915039.380869
2023-05-12T18:10:39.382Z,1683915039.382 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:39.382Z,1683915039.382 [DATMMP](INFO): Handled
2023-05-12T18:10:39.633Z,1683915039.633 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:39.634Z,1683915039.634 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:10:39.635Z,1683915039.635 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:10:39.635Z,1683915039.635 [DATMMP](INFO): Handled
2023-05-12T18:10:39.884Z,1683915039.884 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:39.885Z,1683915039.885 [DATMMP](INFO): directional_debug rx: 	 x19A	 x107	 x22	 x1A8	 x2830	 x3151	 x57F2	 x57F3
2023-05-12T18:10:39.886Z,1683915039.886 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:10:39.886Z,1683915039.886 [DATMMP](INFO): Handled
2023-05-12T18:10:40.136Z,1683915040.136 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:40.137Z,1683915040.137 [DATMMP](INFO): directional rx: 	 x1BE	 xF4	 x442	 xFEF9	 xB07	 xFEF7	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:10:40.139Z,1683915040.139 [DATMMP](INFO): Received message type: directional
2023-05-12T18:10:40.139Z,1683915040.139 [DATMMP](INFO): Handled
2023-05-12T18:10:40.388Z,1683915040.388 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:40.389Z,1683915040.389 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:10:40.390Z,1683915040.390 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:10:40.390Z,1683915040.390 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:10:40.390Z,1683915040.390 [DATMMP](INFO): Handled
2023-05-12T18:10:40.640Z,1683915040.640 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:40.642Z,1683915040.642 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x1AD	 x23	 x82	 xAE	 xAD	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:10:40.642Z,1683915040.642 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:10:40.642Z,1683915040.642 [DATMMP](INFO): Handled
2023-05-12T18:10:40.892Z,1683915040.892 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:40.893Z,1683915040.893 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xA	 x26	 x17C0
2023-05-12T18:10:40.893Z,1683915040.893 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:40.893Z,1683915040.893 [DATMMP](INFO): Handled
2023-05-12T18:10:54.256Z,1683915054.256 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:54.256Z,1683915054.256 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:10:54.257Z,1683915054.257 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:10:54.257Z,1683915054.257 [DATMMP](INFO): Handled
2023-05-12T18:10:54.508Z,1683915054.508 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:54.509Z,1683915054.509 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xA	 x34	 x60E
2023-05-12T18:10:54.509Z,1683915054.509 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915067.143360
2023-05-12T18:10:54.509Z,1683915054.509 [DATMMP](INFO): Rx ping set to:1683915054.508682
2023-05-12T18:10:54.510Z,1683915054.510 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:54.510Z,1683915054.510 [DATMMP](INFO): Handled
2023-05-12T18:10:55.012Z,1683915055.012 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:55.013Z,1683915055.013 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:10:55.013Z,1683915055.013 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:10:55.013Z,1683915055.013 [DATMMP](INFO): Handled
2023-05-12T18:10:55.264Z,1683915055.264 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:55.265Z,1683915055.265 [DATMMP](INFO): directional_debug rx: 	 x178	 x102	 x23	 x1C9	 x3220	 x3B51	 x6162	 x6283
2023-05-12T18:10:55.266Z,1683915055.266 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:10:55.267Z,1683915055.267 [DATMMP](INFO): Handled
2023-05-12T18:10:55.516Z,1683915055.516 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:55.517Z,1683915055.517 [DATMMP](INFO): directional rx: 	 x197	 xF0	 x464	 xFEFE	 xB29	 xFEFC	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:10:55.519Z,1683915055.519 [DATMMP](INFO): Received message type: directional
2023-05-12T18:10:55.519Z,1683915055.519 [DATMMP](INFO): Handled
2023-05-12T18:10:55.768Z,1683915055.768 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:55.769Z,1683915055.769 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:10:55.769Z,1683915055.769 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:10:55.769Z,1683915055.769 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:10:55.770Z,1683915055.770 [DATMMP](INFO): Handled
2023-05-12T18:10:56.020Z,1683915056.020 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:56.022Z,1683915056.022 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x23	 x83	 xB0	 xAD	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:10:56.022Z,1683915056.022 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:10:56.022Z,1683915056.022 [DATMMP](INFO): Handled
2023-05-12T18:10:56.272Z,1683915056.272 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:10:56.273Z,1683915056.273 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xA	 x35	 x1D9B
2023-05-12T18:10:56.273Z,1683915056.273 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:10:56.273Z,1683915056.273 [DATMMP](INFO): Handled
2023-05-12T18:11:09.376Z,1683915069.376 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:09.377Z,1683915069.377 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:11:09.377Z,1683915069.377 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:11:09.377Z,1683915069.377 [DATMMP](INFO): Handled
2023-05-12T18:11:09.628Z,1683915069.628 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:09.629Z,1683915069.629 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xB	 x7	 x9F6
2023-05-12T18:11:09.630Z,1683915069.630 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915091.946176
2023-05-12T18:11:09.630Z,1683915069.630 [DATMMP](INFO): Rx ping set to:1683915069.629046
2023-05-12T18:11:09.630Z,1683915069.630 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:09.630Z,1683915069.630 [DATMMP](INFO): Handled
2023-05-12T18:11:09.893Z,1683915069.893 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:09.895Z,1683915069.895 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:11:09.895Z,1683915069.895 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:11:09.896Z,1683915069.896 [DATMMP](INFO): Handled
2023-05-12T18:11:10.144Z,1683915070.144 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:10.145Z,1683915070.145 [DATMMP](INFO): directional_debug rx: 	 x1BD	 xD6	 x22	 x16D	 x27E0	 x2F41	 x5AC2	 x56E3
2023-05-12T18:11:10.146Z,1683915070.146 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:11:10.147Z,1683915070.147 [DATMMP](INFO): Handled
2023-05-12T18:11:10.408Z,1683915070.408 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:10.410Z,1683915070.410 [DATMMP](INFO): directional rx: 	 x1E9	 xCD	 x41F	 xFF2A	 xAE4	 xFF28	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:11:10.412Z,1683915070.412 [DATMMP](INFO): Received message type: directional
2023-05-12T18:11:10.412Z,1683915070.412 [DATMMP](INFO): Handled
2023-05-12T18:11:10.656Z,1683915070.656 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:10.657Z,1683915070.657 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:11:10.657Z,1683915070.657 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:11:10.658Z,1683915070.658 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:11:10.658Z,1683915070.658 [DATMMP](INFO): Handled
2023-05-12T18:11:10.908Z,1683915070.908 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:10.910Z,1683915070.910 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x22	 x81	 xAF	 xAF	 xD	 xFFFF	 x0	 x24	 x0	 x0
2023-05-12T18:11:10.910Z,1683915070.910 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:11:10.910Z,1683915070.910 [DATMMP](INFO): Handled
2023-05-12T18:11:11.160Z,1683915071.160 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:11.161Z,1683915071.161 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xB	 x8	 x2183
2023-05-12T18:11:11.161Z,1683915071.161 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:11.162Z,1683915071.162 [DATMMP](INFO): Handled
2023-05-12T18:11:24.792Z,1683915084.792 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:24.792Z,1683915084.792 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T18:11:24.793Z,1683915084.793 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:11:24.793Z,1683915084.793 [DATMMP](INFO): Handled
2023-05-12T18:11:25.044Z,1683915085.044 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:25.045Z,1683915085.045 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xB	 x16	 x1995
2023-05-12T18:11:25.046Z,1683915085.046 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915146.001472
2023-05-12T18:11:25.046Z,1683915085.046 [DATMMP](INFO): Rx ping set to:1683915085.045045
2023-05-12T18:11:25.046Z,1683915085.046 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:25.046Z,1683915085.046 [DATMMP](INFO): Handled
2023-05-12T18:11:25.298Z,1683915085.298 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:25.300Z,1683915085.300 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:11:25.300Z,1683915085.300 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:11:25.301Z,1683915085.301 [DATMMP](INFO): Handled
2023-05-12T18:11:25.548Z,1683915085.548 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:25.549Z,1683915085.549 [DATMMP](INFO): directional_debug rx: 	 x1B1	 xCF	 x22	 x155	 x2820	 x30A1	 x5AE2	 x56F3
2023-05-12T18:11:25.550Z,1683915085.550 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:11:25.550Z,1683915085.550 [DATMMP](INFO): Handled
2023-05-12T18:11:25.800Z,1683915085.800 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:25.805Z,1683915085.805 [DATMMP](INFO): directional rx: 	 x1DC	 xC8	 x42B	 xFF31	 xAF0	 xFF2F	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:11:25.808Z,1683915085.808 [DATMMP](INFO): Received message type: directional
2023-05-12T18:11:25.808Z,1683915085.808 [DATMMP](INFO): Handled
2023-05-12T18:11:26.052Z,1683915086.052 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:26.053Z,1683915086.053 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:11:26.053Z,1683915086.053 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:11:26.053Z,1683915086.053 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:11:26.054Z,1683915086.054 [DATMMP](INFO): Handled
2023-05-12T18:11:26.304Z,1683915086.304 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:26.306Z,1683915086.306 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x22	 x80	 xAE	 xAD	 xD	 xFFFF	 x0	 x22	 x0	 x0
2023-05-12T18:11:26.306Z,1683915086.306 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:11:26.306Z,1683915086.306 [DATMMP](INFO): Handled
2023-05-12T18:11:26.556Z,1683915086.556 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:26.557Z,1683915086.557 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xB	 x18	 xA12
2023-05-12T18:11:26.557Z,1683915086.557 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:26.557Z,1683915086.557 [DATMMP](INFO): Handled
2023-05-12T18:11:40.172Z,1683915100.172 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:40.172Z,1683915100.172 [DATMMP](INFO): doppler rx: 	 x0
2023-05-12T18:11:40.173Z,1683915100.173 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:11:40.173Z,1683915100.173 [DATMMP](INFO): Handled
2023-05-12T18:11:40.424Z,1683915100.424 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:40.425Z,1683915100.425 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xB	 x26	 x225
2023-05-12T18:11:40.426Z,1683915100.426 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915103.378880
2023-05-12T18:11:40.426Z,1683915100.426 [DATMMP](INFO): Rx ping set to:1683915100.425239
2023-05-12T18:11:40.426Z,1683915100.426 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:40.426Z,1683915100.426 [DATMMP](INFO): Handled
2023-05-12T18:11:40.677Z,1683915100.677 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:40.694Z,1683915100.694 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:11:40.694Z,1683915100.694 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:11:40.695Z,1683915100.695 [DATMMP](INFO): Handled
2023-05-12T18:11:40.928Z,1683915100.928 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:40.929Z,1683915100.929 [DATMMP](INFO): directional_debug rx: 	 x1B9	 xD3	 x22	 x16F	 x27C0	 x2F71	 x5AB2	 x5793
2023-05-12T18:11:40.930Z,1683915100.930 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:11:40.930Z,1683915100.930 [DATMMP](INFO): Handled
2023-05-12T18:11:41.180Z,1683915101.180 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:41.182Z,1683915101.182 [DATMMP](INFO): directional rx: 	 x1E5	 xCB	 x423	 xFF2D	 xAE8	 xFF2B	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:11:41.184Z,1683915101.184 [DATMMP](INFO): Received message type: directional
2023-05-12T18:11:41.184Z,1683915101.184 [DATMMP](INFO): Handled
2023-05-12T18:11:41.432Z,1683915101.432 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:41.433Z,1683915101.433 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:11:41.433Z,1683915101.433 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:11:41.434Z,1683915101.434 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:11:41.434Z,1683915101.434 [DATMMP](INFO): Handled
2023-05-12T18:11:41.684Z,1683915101.684 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:41.686Z,1683915101.686 [DATMMP](INFO): acstats rx: 	 xC	 x0	 x0	 x2D0	 x22	 x81	 xAF	 xAF	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:11:41.686Z,1683915101.686 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:11:41.686Z,1683915101.686 [DATMMP](INFO): Handled
2023-05-12T18:11:41.936Z,1683915101.936 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:41.937Z,1683915101.937 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xB	 x27	 x19B2
2023-05-12T18:11:41.937Z,1683915101.937 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:41.937Z,1683915101.937 [DATMMP](INFO): Handled
2023-05-12T18:11:55.552Z,1683915115.552 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:55.552Z,1683915115.552 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-12T18:11:55.553Z,1683915115.553 [DATMMP](INFO): Received message type: doppler
2023-05-12T18:11:55.553Z,1683915115.553 [DATMMP](INFO): Handled
2023-05-12T18:11:55.804Z,1683915115.804 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:55.805Z,1683915115.805 [DATMMP](INFO): timestamp rx: 	 x1	 x12	 xB	 x35	 x11C4
2023-05-12T18:11:55.805Z,1683915115.805 [DATMMP](INFO): Rx dataTimestamp_ set to:1683915157.434176
2023-05-12T18:11:55.806Z,1683915115.806 [DATMMP](INFO): Rx ping set to:1683915115.804792
2023-05-12T18:11:55.806Z,1683915115.806 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:55.806Z,1683915115.806 [DATMMP](INFO): Handled
2023-05-12T18:11:56.308Z,1683915116.308 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:56.309Z,1683915116.309 [DATMMP](INFO): nav_stat rx: 	 x0	 x0	 x0
2023-05-12T18:11:56.309Z,1683915116.309 [DATMMP](INFO): Received message type: nav_stat
2023-05-12T18:11:56.309Z,1683915116.309 [DATMMP](INFO): Handled
2023-05-12T18:11:56.560Z,1683915116.560 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:56.561Z,1683915116.561 [DATMMP](INFO): directional_debug rx: 	 x17D	 x10B	 x23	 x219	 x3210	 x3F61	 x6112	 x68F3
2023-05-12T18:11:56.562Z,1683915116.562 [DATMMP](INFO): Received message type: directional_debug
2023-05-12T18:11:56.562Z,1683915116.562 [DATMMP](INFO): Handled
2023-05-12T18:11:56.812Z,1683915116.812 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:56.813Z,1683915116.813 [DATMMP](INFO): directional rx: 	 x19C	 xF7	 x45F	 xFEF5	 xB24	 xFEF3	 x0	 x6C7	 x3	 x1	 x27B5966	 x-43569E0
2023-05-12T18:11:56.815Z,1683915116.815 [DATMMP](INFO): Received message type: directional
2023-05-12T18:11:56.815Z,1683915116.815 [DATMMP](INFO): Handled
2023-05-12T18:11:57.064Z,1683915117.064 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:57.065Z,1683915117.065 [DATMMP](INFO): remote_header rx: 	 x0	 x62	 x1	 xA1	 x1	 xA	 xC	 x0
2023-05-12T18:11:57.066Z,1683915117.066 [DATMMP](INFO): DATA Src=12, Dst=10
2023-05-12T18:11:57.066Z,1683915117.066 [DATMMP](INFO): Received message type: remote_header
2023-05-12T18:11:57.066Z,1683915117.066 [DATMMP](INFO): Handled
2023-05-12T18:11:57.316Z,1683915117.316 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:57.318Z,1683915117.318 [DATMMP](INFO): acstats rx: 	 xC	 x0	 xFFFF	 x2D0	 x22	 x80	 xAF	 xAF	 xD	 xFFFF	 x0	 x23	 x0	 x0
2023-05-12T18:11:57.318Z,1683915117.318 [DATMMP](INFO): Received message type: acstats
2023-05-12T18:11:57.318Z,1683915117.318 [DATMMP](INFO): Handled
2023-05-12T18:11:57.568Z,1683915117.568 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-12T18:11:57.569Z,1683915117.569 [DATMMP](INFO): timestamp rx: 	 x0	 x12	 xB	 x37	 x241
2023-05-12T18:11:57.569Z,1683915117.569 [DATMMP](INFO): Received message type: timestamp
2023-05-12T18:11:57.569Z,1683915117.569 [DATMMP](INFO): Handled
2023-05-12T18:12:17.631Z,1683915137.631 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T18:14:02.693Z,1683915242.693 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-12T18:14:02.704Z,1683915242.704 [BPC1](INFO): Received data from all battery sticks.
2023-05-12T18:14:58.864Z,1683915298.864 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T18:09:58.7Z
2023-05-12T18:14:58.864Z,1683915298.864 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T18:14:58.865Z,1683915298.865 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T18:14:59.259Z,1683915299.259 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-12T18:15:06.618Z,1683915306.618 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T180624/Courier0000.lzma
2023-05-12T18:15:07.621Z,1683915307.621 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Courier0000.lzma.bak
2023-05-12T18:15:07.621Z,1683915307.621 [DataOverHttps](INFO): SBD MOMSN=18297855
2023-05-12T18:15:24.171Z,1683915324.171 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20230512T180624/Courier0004.lzma
2023-05-12T18:15:25.173Z,1683915325.173 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Courier0004.lzma.bak
2023-05-12T18:15:25.173Z,1683915325.173 [DataOverHttps](INFO): SBD MOMSN=18297858
2023-05-12T18:15:31.556Z,1683915331.556 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-12T18:15:41.359Z,1683915341.359 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20230512T171459/Express0008.lzma
2023-05-12T18:15:42.361Z,1683915342.361 [DataOverHttps](INFO): Moved sent file to Logs/20230512T171459/Express0008.lzma.bak
2023-05-12T18:15:42.361Z,1683915342.361 [DataOverHttps](INFO): SBD MOMSN=18297860
2023-05-12T18:15:58.388Z,1683915358.388 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20230512T172055/Express0001.lzma
2023-05-12T18:15:59.389Z,1683915359.389 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172055/Express0001.lzma.bak
2023-05-12T18:15:59.389Z,1683915359.389 [DataOverHttps](INFO): SBD MOMSN=18297863
2023-05-12T18:16:15.331Z,1683915375.331 [DataOverHttps](INFO): Sending 417 bytes from file Logs/20230512T172253/Express0001.lzma
2023-05-12T18:16:16.333Z,1683915376.333 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172253/Express0001.lzma.bak
2023-05-12T18:16:16.333Z,1683915376.333 [DataOverHttps](INFO): SBD MOMSN=18297872
2023-05-12T18:16:31.451Z,1683915391.451 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20230512T172410/Express0001.lzma
2023-05-12T18:16:32.453Z,1683915392.453 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172410/Express0001.lzma.bak
2023-05-12T18:16:32.453Z,1683915392.453 [DataOverHttps](INFO): SBD MOMSN=18297881
2023-05-12T18:16:49.015Z,1683915409.015 [DataOverHttps](INFO): Sending 531 bytes from file Logs/20230512T172603/Express0001.lzma
2023-05-12T18:16:50.017Z,1683915410.017 [DataOverHttps](INFO): Moved sent file to Logs/20230512T172603/Express0001.lzma.bak
2023-05-12T18:16:50.017Z,1683915410.017 [DataOverHttps](INFO): SBD MOMSN=18297893
2023-05-12T18:17:06.212Z,1683915426.212 [DataOverHttps](INFO): Sending 1075 bytes from file Logs/20230512T180624/Express0001.lzma
2023-05-12T18:17:07.205Z,1683915427.205 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Express0001.lzma.bak
2023-05-12T18:17:07.205Z,1683915427.205 [DataOverHttps](INFO): SBD MOMSN=18297909
2023-05-12T18:17:23.175Z,1683915443.175 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20230512T180624/Express0005.lzma
2023-05-12T18:17:24.177Z,1683915444.177 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Express0005.lzma.bak
2023-05-12T18:17:24.177Z,1683915444.177 [DataOverHttps](INFO): SBD MOMSN=18297936
2023-05-12T18:17:25.528Z,1683915445.528 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T18:17:25.528Z,1683915445.528 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T18:17:25.528Z,1683915445.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T18:22:26.097Z,1683915746.097 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-12T18:22:26.097Z,1683915746.097 [Default:CheckIn:C.Wait] Stopped
2023-05-12T18:22:26.097Z,1683915746.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T18:22:26.097Z,1683915746.097 [Default:CheckIn:D] Running Loop=1
2023-05-12T18:22:26.527Z,1683915746.527 [Default:CheckIn:D] Stopped
2023-05-12T18:22:26.527Z,1683915746.527 [Default:CheckIn:E] Running Loop=1
2023-05-12T18:22:26.923Z,1683915746.923 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.692778 min 
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn:E] Stopped
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn] Stopped
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn](INFO): Running loop #2
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn] Running Loop=2
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-12T18:22:26.924Z,1683915746.924 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-12T18:22:28.516Z,1683915748.516 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-12T18:22:28.516Z,1683915748.516 [NAL9602] Data Fault, FailCount= 1
2023-05-12T18:22:28.516Z,1683915748.516 [NAL9602](ERROR): Data Fault
2023-05-12T18:22:28.601Z,1683915748.601 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-12T18:22:28.920Z,1683915748.920 [NAL9602](INFO): Powering down
2023-05-12T18:22:29.748Z,1683915749.748 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-12T18:22:29.748Z,1683915749.748 [NAL9602] No Fault, FailCount= 1
2023-05-12T18:22:59.225Z,1683915779.225 [NAL9602](INFO): Powering up NAL9602
2023-05-12T18:23:10.133Z,1683915790.133 [NAL9602](INFO): NAL9602 initialized
2023-05-12T18:27:27.097Z,1683916047.097 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T18:22:26.9Z
2023-05-12T18:27:27.097Z,1683916047.097 [Default:CheckIn:Read_GPS] Stopped
2023-05-12T18:27:27.097Z,1683916047.097 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-12T18:27:34.626Z,1683916054.626 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T180624/Courier0007.lzma
2023-05-12T18:27:35.629Z,1683916055.629 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Courier0007.lzma.bak
2023-05-12T18:27:35.629Z,1683916055.629 [DataOverHttps](INFO): SBD MOMSN=18297958
2023-05-12T18:27:51.795Z,1683916071.795 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20230512T180624/Express0008.lzma
2023-05-12T18:27:52.797Z,1683916072.797 [DataOverHttps](INFO): Moved sent file to Logs/20230512T180624/Express0008.lzma.bak
2023-05-12T18:27:52.797Z,1683916072.797 [DataOverHttps](INFO): SBD MOMSN=18297960
2023-05-12T18:27:54.172Z,1683916074.172 [Default:CheckIn:Read_Iridium] Stopped
2023-05-12T18:27:54.172Z,1683916074.172 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-12T18:27:54.172Z,1683916074.172 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-12T18:28:13.531Z,1683916093.531 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-12T18:28:44.253Z,1683916124.253 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-12T18:29:42.210Z,1683916182.210 [CommandExec](IMPORTANT): got command restart application
2023-05-12T18:29:43.215Z,1683916183.215 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-12T18:29:43.215Z,1683916183.215 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:43.216Z,1683916183.216 [CommandExec](INFO): Uninitializing the command executive.
2023-05-12T18:29:43.216Z,1683916183.216 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-12T18:29:43.216Z,1683916183.216 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:43.351Z,1683916183.351 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-12T18:29:43.351Z,1683916183.351 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-12T18:29:43.351Z,1683916183.351 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:43.352Z,1683916183.352 [NavChartDb](INFO): Join timeout helper Thread ID is 7071
2023-05-12T18:29:43.403Z,1683916183.403 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:43.404Z,1683916183.404 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:43.404Z,1683916183.404 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-12T18:29:43.404Z,1683916183.404 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:43.405Z,1683916183.405 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7072
2023-05-12T18:29:43.767Z,1683916183.767 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:43.768Z,1683916183.768 [WetLabsUBAT](INFO): Powering down
2023-05-12T18:29:43.768Z,1683916183.768 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:43.783Z,1683916183.783 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-12T18:29:43.783Z,1683916183.783 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:43.784Z,1683916183.784 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7073
2023-05-12T18:29:44.035Z,1683916184.035 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:44.036Z,1683916184.036 [WetLabsBB2FL](INFO): Powering down
2023-05-12T18:29:44.040Z,1683916184.040 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:44.043Z,1683916184.043 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-12T18:29:44.043Z,1683916184.043 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:44.044Z,1683916184.044 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7074
2023-05-12T18:29:44.851Z,1683916184.851 [CTD_Seabird](INFO): Powering down
2023-05-12T18:29:44.863Z,1683916184.863 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:44.863Z,1683916184.863 [CTD_Seabird](INFO): Powering down
2023-05-12T18:29:44.875Z,1683916184.875 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:44.895Z,1683916184.895 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-12T18:29:44.895Z,1683916184.895 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:44.896Z,1683916184.896 [Radio_Surface](INFO): Join timeout helper Thread ID is 7075
2023-05-12T18:29:45.075Z,1683916185.075 [Radio_Surface](INFO): Powering down
2023-05-12T18:29:45.076Z,1683916185.076 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:45.077Z,1683916185.077 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:45.080Z,1683916185.080 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-12T18:29:45.080Z,1683916185.080 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:45.081Z,1683916185.081 [Onboard](INFO): Join timeout helper Thread ID is 7076
2023-05-12T18:29:45.212Z,1683916185.212 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:45.212Z,1683916185.212 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:45.224Z,1683916185.224 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-12T18:29:45.224Z,1683916185.224 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:45.224Z,1683916185.224 [DataOverHttps](INFO): Join timeout helper Thread ID is 7077
2023-05-12T18:29:46.817Z,1683916186.817 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:46.820Z,1683916186.820 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.832Z,1683916186.832 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-12T18:29:46.832Z,1683916186.832 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.832Z,1683916186.832 [DATMMP](INFO): Join timeout helper Thread ID is 7078
2023-05-12T18:29:46.859Z,1683916186.859 [DATMMP](INFO): Stop
2023-05-12T18:29:46.859Z,1683916186.859 [DATMMP](INFO): uninitialize
2023-05-12T18:29:46.859Z,1683916186.859 [DATMMP](INFO): Powering down
2023-05-12T18:29:46.932Z,1683916186.932 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:46.932Z,1683916186.932 [DATMMP](INFO): uninitialize
2023-05-12T18:29:46.932Z,1683916186.932 [DATMMP](INFO): Powering down
2023-05-12T18:29:46.933Z,1683916186.933 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.936Z,1683916186.936 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-12T18:29:46.936Z,1683916186.936 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.936Z,1683916186.936 [BackseatComponent](INFO): Join timeout helper Thread ID is 7079
2023-05-12T18:29:46.964Z,1683916186.964 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:46.965Z,1683916186.965 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.967Z,1683916186.967 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-12T18:29:46.968Z,1683916186.968 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.968Z,1683916186.968 [logger](INFO): Join timeout helper Thread ID is 7080
2023-05-12T18:29:46.977Z,1683916186.977 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:46.978Z,1683916186.978 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.988Z,1683916186.988 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-12T18:29:46.988Z,1683916186.988 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:46.988Z,1683916186.988 [CommandLine](INFO): Join timeout helper Thread ID is 7081
2023-05-12T18:29:47.087Z,1683916187.087 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:47.088Z,1683916187.088 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.092Z,1683916187.092 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-12T18:29:47.092Z,1683916187.092 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.092Z,1683916187.092 [CommandExec](INFO): Join timeout helper Thread ID is 7082
2023-05-12T18:29:47.094Z,1683916187.094 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-12T18:29:47.094Z,1683916187.094 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.094Z,1683916187.094 [controlThread](INFO): Join timeout helper Thread ID is 7083
2023-05-12T18:29:47.244Z,1683916187.244 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-12T18:29:47.244Z,1683916187.244 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-12T18:29:47.244Z,1683916187.244 [AHRS_M2](INFO): Powering down
2023-05-12T18:29:47.316Z,1683916187.316 [NAL9602](INFO): Powering down
2023-05-12T18:29:47.387Z,1683916187.387 [RDI_Pathfinder](INFO): Powering down
2023-05-12T18:29:47.389Z,1683916187.389 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-12T18:29:47.390Z,1683916187.390 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-12T18:29:47.390Z,1683916187.390 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-12T18:29:47.391Z,1683916187.391 [MissionManager](INFO): Uninitializing Mission Default
2023-05-12T18:29:47.391Z,1683916187.391 [Default] Stopped
2023-05-12T18:29:47.391Z,1683916187.391 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-12T18:29:47.391Z,1683916187.391 [Default:B.GoToSurface] Stopped
2023-05-12T18:29:47.391Z,1683916187.391 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-12T18:29:47.391Z,1683916187.391 [Default:CheckIn] Stopped
2023-05-12T18:29:47.391Z,1683916187.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-12T18:29:47.391Z,1683916187.391 [Default:CheckIn:C.Wait] Stopped
2023-05-12T18:29:47.392Z,1683916187.392 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-12T18:29:47.394Z,1683916187.394 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-12T18:29:47.395Z,1683916187.395 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-12T18:29:47.395Z,1683916187.395 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-12T18:29:47.395Z,1683916187.395 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-12T18:29:47.396Z,1683916187.396 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-12T18:29:47.396Z,1683916187.396 [BuoyancyServo](INFO): Powering down
2023-05-12T18:29:47.407Z,1683916187.407 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-12T18:29:47.407Z,1683916187.407 [ElevatorServo](INFO): Powering down
2023-05-12T18:29:47.408Z,1683916187.408 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-12T18:29:47.408Z,1683916187.408 [MassServo](INFO): Powering down
2023-05-12T18:29:47.409Z,1683916187.409 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-12T18:29:47.409Z,1683916187.409 [RudderServo](INFO): Powering down
2023-05-12T18:29:47.410Z,1683916187.410 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-12T18:29:47.410Z,1683916187.410 [ThrusterServo](INFO): Powering down
2023-05-12T18:29:47.411Z,1683916187.411 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-12T18:29:47.411Z,1683916187.411 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-12T18:29:47.412Z,1683916187.412 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-12T18:29:47.412Z,1683916187.412 [CBIT](DEBUG): Powering off loads.
2023-05-12T18:29:47.423Z,1683916187.423 [CBIT](DEBUG): Disabling WDT.
2023-05-12T18:29:47.435Z,1683916187.435 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-12T18:29:47.436Z,1683916187.436 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.498Z,1683916187.498 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.501Z,1683916187.501 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.506Z,1683916187.506 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.512Z,1683916187.512 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.552Z,1683916187.552 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.617Z,1683916187.617 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.619Z,1683916187.619 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.622Z,1683916187.622 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.631Z,1683916187.631 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-12T18:29:47.693Z,1683916187.693 [logger ThreadHandler](INFO): Thread cancelled.