2023-05-12T16:17:39.958Z,1683908259.958 [Supervisor](DEBUG): Initializing supervisor. 2023-05-12T16:17:39.964Z,1683908259.964 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-05-12T16:17:39.964Z,1683908259.964 [SyncHandler](INFO): Protected caller Thread ID is 6937 2023-05-12T16:17:39.965Z,1683908259.965 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-05-12T16:17:39.966Z,1683908259.966 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-05-12T16:17:39.966Z,1683908259.966 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6938 2023-05-12T16:17:39.971Z,1683908259.971 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-05-12T16:17:39.988Z,1683908259.988 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-05-12T16:17:39.989Z,1683908259.989 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-05-12T16:17:39.990Z,1683908259.990 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6939 2023-05-12T16:17:39.994Z,1683908259.994 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-05-12T16:17:39.995Z,1683908259.995 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-05-12T16:17:39.995Z,1683908259.995 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6940 2023-05-12T16:17:39.997Z,1683908259.997 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-05-12T16:17:39.998Z,1683908259.998 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-05-12T16:17:39.998Z,1683908259.998 [logger ThreadHandler](INFO): Protected caller Thread ID is 6941 2023-05-12T16:17:40.002Z,1683908260.002 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-05-12T16:17:40.002Z,1683908260.002 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-05-12T16:17:40.004Z,1683908260.004 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-05-12T16:17:40.101Z,1683908260.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-05-12T16:17:40.102Z,1683908260.102 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-05-12T16:17:40.750Z,1683908260.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-05-12T16:17:40.750Z,1683908260.750 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-05-12T16:17:40.970Z,1683908260.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-05-12T16:17:40.970Z,1683908260.970 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-05-12T16:17:41.050Z,1683908261.050 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-05-12T16:17:41.180Z,1683908261.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-05-12T16:17:41.181Z,1683908261.181 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-05-12T16:17:41.263Z,1683908261.263 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-05-12T16:17:41.370Z,1683908261.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-05-12T16:17:41.371Z,1683908261.371 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-05-12T16:17:41.736Z,1683908261.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-05-12T16:17:41.737Z,1683908261.737 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-05-12T16:17:42.200Z,1683908262.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-05-12T16:17:42.201Z,1683908262.201 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-05-12T16:17:42.415Z,1683908262.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-05-12T16:17:42.416Z,1683908262.416 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-05-12T16:17:42.595Z,1683908262.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-05-12T16:17:42.596Z,1683908262.596 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-05-12T16:17:43.080Z,1683908263.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-05-12T16:17:43.081Z,1683908263.081 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-05-12T16:17:43.393Z,1683908263.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-05-12T16:17:43.394Z,1683908263.394 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-05-12T16:17:43.884Z,1683908263.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-05-12T16:17:43.884Z,1683908263.884 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-05-12T16:17:44.676Z,1683908264.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-05-12T16:17:44.676Z,1683908264.676 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-05-12T16:17:44.996Z,1683908264.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-05-12T16:17:44.998Z,1683908264.998 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2023-05-12T16:17:44.999Z,1683908264.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2023-05-12T16:17:45.084Z,1683908265.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2023-05-12T16:17:45.236Z,1683908265.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2023-05-12T16:17:45.343Z,1683908265.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2023-05-12T16:17:45.429Z,1683908265.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2023-05-12T16:17:45.523Z,1683908265.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2023-05-12T16:17:45.697Z,1683908265.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2023-05-12T16:17:45.955Z,1683908265.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-05-12T16:17:45.956Z,1683908265.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2023-05-12T16:17:46.049Z,1683908266.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2023-05-12T16:17:46.147Z,1683908266.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2023-05-12T16:17:46.283Z,1683908266.283 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2023-05-12T16:17:46.382Z,1683908266.382 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2023-05-12T16:17:46.383Z,1683908266.383 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-05-12T16:17:46.390Z,1683908266.390 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-05-12T16:17:46.485Z,1683908266.485 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-05-12T16:17:46.486Z,1683908266.486 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-05-12T16:17:46.535Z,1683908266.535 [VerticalControl](DEBUG): Construct VerticalControl. 2023-05-12T16:17:46.593Z,1683908266.593 [VerticalControl] Loaded 2023-05-12T16:17:46.593Z,1683908266.593 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-05-12T16:17:46.596Z,1683908266.596 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-05-12T16:17:46.636Z,1683908266.636 [HorizontalControl] Loaded 2023-05-12T16:17:46.637Z,1683908266.637 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-05-12T16:17:46.639Z,1683908266.639 [SpeedControl](DEBUG): Construct SpeedControl. 2023-05-12T16:17:46.642Z,1683908266.642 [SpeedControl] Loaded 2023-05-12T16:17:46.642Z,1683908266.642 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-05-12T16:17:46.645Z,1683908266.645 [LoopControl](DEBUG): Construct LoopControl. 2023-05-12T16:17:46.645Z,1683908266.645 [LoopControl] Loaded 2023-05-12T16:17:46.646Z,1683908266.646 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-05-12T16:17:46.646Z,1683908266.646 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-05-12T16:17:46.646Z,1683908266.646 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-05-12T16:17:46.693Z,1683908266.693 [DepthRateCalculator] Loaded 2023-05-12T16:17:46.694Z,1683908266.694 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-05-12T16:17:46.698Z,1683908266.698 [PitchRateCalculator] Loaded 2023-05-12T16:17:46.699Z,1683908266.699 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-05-12T16:17:46.708Z,1683908266.708 [SpeedCalculator] Loaded 2023-05-12T16:17:46.709Z,1683908266.709 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-05-12T16:17:46.713Z,1683908266.713 [YawRateCalculator] Loaded 2023-05-12T16:17:46.713Z,1683908266.713 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-05-12T16:17:46.730Z,1683908266.730 [ElevatorOffsetCalculator] Loaded 2023-05-12T16:17:46.731Z,1683908266.731 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-05-12T16:17:46.731Z,1683908266.731 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-05-12T16:17:46.732Z,1683908266.732 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-05-12T16:17:46.758Z,1683908266.758 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-05-12T16:17:46.758Z,1683908266.758 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-05-12T16:17:46.825Z,1683908266.825 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-05-12T16:17:46.826Z,1683908266.826 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-05-12T16:17:47.118Z,1683908267.118 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-05-12T16:17:47.119Z,1683908267.119 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-05-12T16:17:47.258Z,1683908267.258 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-05-12T16:17:47.259Z,1683908267.259 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-05-12T16:17:47.725Z,1683908267.725 [AHRS_M2] Loaded 2023-05-12T16:17:47.725Z,1683908267.725 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-05-12T16:17:47.760Z,1683908267.760 [BackseatComponent] Loaded 2023-05-12T16:17:47.760Z,1683908267.760 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-05-12T16:17:47.761Z,1683908267.761 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409994E0 2023-05-12T16:17:47.761Z,1683908267.761 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7023 2023-05-12T16:17:47.764Z,1683908267.764 [LcmUniversalReporter] Loaded 2023-05-12T16:17:47.764Z,1683908267.764 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-05-12T16:17:48.554Z,1683908268.554 [BPC1] Loaded 2023-05-12T16:17:48.555Z,1683908268.555 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-05-12T16:17:48.640Z,1683908268.640 [DATMMP] Loaded 2023-05-12T16:17:48.642Z,1683908268.642 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2023-05-12T16:17:48.681Z,1683908268.681 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0 2023-05-12T16:17:48.682Z,1683908268.682 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 7024 2023-05-12T16:17:49.002Z,1683908269.002 [DataOverHttps] Loaded 2023-05-12T16:17:49.002Z,1683908269.002 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-05-12T16:17:49.004Z,1683908269.004 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409FA4E0 2023-05-12T16:17:49.005Z,1683908269.005 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7025 2023-05-12T16:17:49.086Z,1683908269.086 [Depth_Keller] Loaded 2023-05-12T16:17:49.086Z,1683908269.086 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-05-12T16:17:49.180Z,1683908269.180 [NAL9602] Loaded 2023-05-12T16:17:49.181Z,1683908269.181 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-05-12T16:17:49.209Z,1683908269.209 [Onboard] Loaded 2023-05-12T16:17:49.210Z,1683908269.210 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-05-12T16:17:49.211Z,1683908269.211 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0 2023-05-12T16:17:49.211Z,1683908269.211 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7026 2023-05-12T16:17:49.223Z,1683908269.223 [Power24vConverter] Loaded 2023-05-12T16:17:49.223Z,1683908269.223 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-05-12T16:17:49.256Z,1683908269.256 [Radio_Surface] Loaded 2023-05-12T16:17:49.257Z,1683908269.257 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-05-12T16:17:49.258Z,1683908269.258 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0 2023-05-12T16:17:49.258Z,1683908269.258 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7027 2023-05-12T16:17:49.432Z,1683908269.432 [RDI_Pathfinder] Loaded 2023-05-12T16:17:49.433Z,1683908269.433 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2023-05-12T16:17:49.434Z,1683908269.434 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-05-12T16:17:49.434Z,1683908269.434 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-05-12T16:17:49.540Z,1683908269.540 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-05-12T16:17:49.541Z,1683908269.541 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-05-12T16:17:49.554Z,1683908269.554 [NavChart] Loaded 2023-05-12T16:17:49.554Z,1683908269.554 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-05-12T16:17:49.560Z,1683908269.560 [UniversalFixResidualReporter] Loaded 2023-05-12T16:17:49.560Z,1683908269.560 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-05-12T16:17:49.560Z,1683908269.560 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-05-12T16:17:49.561Z,1683908269.561 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-05-12T16:17:49.695Z,1683908269.695 [SBIT](DEBUG): Construct Startup Built In Test. 2023-05-12T16:17:49.703Z,1683908269.703 [SBIT] Loaded 2023-05-12T16:17:49.704Z,1683908269.704 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-05-12T16:17:49.707Z,1683908269.707 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-05-12T16:17:49.719Z,1683908269.719 [IBIT] Loaded 2023-05-12T16:17:49.719Z,1683908269.719 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-05-12T16:17:49.725Z,1683908269.725 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-05-12T16:17:49.826Z,1683908269.826 [CBIT] Loaded 2023-05-12T16:17:49.826Z,1683908269.826 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-05-12T16:17:49.827Z,1683908269.827 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-05-12T16:17:49.827Z,1683908269.827 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-05-12T16:17:49.955Z,1683908269.955 [BuoyancyServo] Loaded 2023-05-12T16:17:49.955Z,1683908269.955 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-05-12T16:17:49.975Z,1683908269.975 [ElevatorServo] Loaded 2023-05-12T16:17:49.975Z,1683908269.975 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-05-12T16:17:49.994Z,1683908269.994 [MassServo] Loaded 2023-05-12T16:17:49.995Z,1683908269.995 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-05-12T16:17:50.013Z,1683908270.013 [RudderServo] Loaded 2023-05-12T16:17:50.013Z,1683908270.013 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-05-12T16:17:50.030Z,1683908270.030 [ThrusterServo] Loaded 2023-05-12T16:17:50.030Z,1683908270.030 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2023-05-12T16:17:50.031Z,1683908270.031 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-05-12T16:17:50.031Z,1683908270.031 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-05-12T16:17:50.041Z,1683908270.041 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-05-12T16:17:50.042Z,1683908270.042 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-05-12T16:17:50.211Z,1683908270.211 [CTD_Seabird] Loaded 2023-05-12T16:17:50.212Z,1683908270.212 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-05-12T16:17:50.213Z,1683908270.213 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BA24E0 2023-05-12T16:17:50.213Z,1683908270.213 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7028 2023-05-12T16:17:50.233Z,1683908270.233 [PAR_Licor] Loaded 2023-05-12T16:17:50.233Z,1683908270.233 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-05-12T16:17:50.263Z,1683908270.263 [WetLabsBB2FL] Loaded 2023-05-12T16:17:50.264Z,1683908270.264 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-05-12T16:17:50.265Z,1683908270.265 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BD24E0 2023-05-12T16:17:50.265Z,1683908270.265 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7029 2023-05-12T16:17:50.284Z,1683908270.284 [WetLabsUBAT] Loaded 2023-05-12T16:17:50.285Z,1683908270.285 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2023-05-12T16:17:50.286Z,1683908270.286 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C024E0 2023-05-12T16:17:50.286Z,1683908270.286 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7030 2023-05-12T16:17:50.287Z,1683908270.287 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-05-12T16:17:50.293Z,1683908270.293 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-05-12T16:17:50.296Z,1683908270.296 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-05-12T16:17:50.307Z,1683908270.307 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-05-12T16:17:50.308Z,1683908270.308 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C324E0 2023-05-12T16:17:50.308Z,1683908270.308 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7031 2023-05-12T16:17:50.313Z,1683908270.313 [Supervisor](INFO): Main Thread ID is 6776 2023-05-12T16:17:50.313Z,1683908270.313 [Supervisor](DEBUG): Running supervisor. 2023-05-12T16:17:50.313Z,1683908270.313 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7032 2023-05-12T16:17:50.314Z,1683908270.314 [CommandExec](INFO): Initializing the command executive. 2023-05-12T16:17:50.315Z,1683908270.315 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7033 2023-05-12T16:17:50.318Z,1683908270.318 [controlThread ThreadHandler](INFO): Handler Thread ID is 7034 2023-05-12T16:17:50.318Z,1683908270.318 [controlThread](DEBUG): Initializing ControlThread 2023-05-12T16:17:50.319Z,1683908270.319 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-05-12T16:17:50.321Z,1683908270.321 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-05-12T16:17:50.321Z,1683908270.321 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-05-12T16:17:50.322Z,1683908270.322 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-05-12T16:17:50.322Z,1683908270.322 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-05-12T16:17:50.323Z,1683908270.323 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-05-12T16:17:50.323Z,1683908270.323 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-05-12T16:17:50.323Z,1683908270.323 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-05-12T16:17:50.324Z,1683908270.324 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-05-12T16:17:50.329Z,1683908270.329 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-05-12T16:17:50.329Z,1683908270.329 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-05-12T16:17:50.330Z,1683908270.330 [SBIT](INFO): Initialize SBIT Component. 2023-05-12T16:17:50.330Z,1683908270.330 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973 2023-05-12T16:17:50.330Z,1683908270.330 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c 2023-05-12T16:17:50.331Z,1683908270.331 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-05-12T16:17:50.332Z,1683908270.332 [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-12T16:17:50.333Z,1683908270.333 [SBIT](INFO): Beginning SBIT in 51.000000 seconds. 2023-05-12T16:17:50.333Z,1683908270.333 [IBIT](INFO): Initialize IBIT Component. 2023-05-12T16:17:50.334Z,1683908270.334 [CBIT](DEBUG): Initialize CBIT Component. 2023-05-12T16:17:50.335Z,1683908270.335 [logger ThreadHandler](INFO): Handler Thread ID is 7035 2023-05-12T16:17:50.348Z,1683908270.348 [CBIT](DEBUG): Initialized mux pins. 2023-05-12T16:17:50.348Z,1683908270.348 [CBIT](DEBUG): Initializing the watchdog timer. 2023-05-12T16:17:50.352Z,1683908270.352 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7036 2023-05-12T16:17:50.360Z,1683908270.360 [DATMMP ThreadHandler](INFO): Handler Thread ID is 7037 2023-05-12T16:17:50.361Z,1683908270.361 [DATMMP](INFO): Start 2023-05-12T16:17:50.361Z,1683908270.361 [DATMMP](INFO): Powering up 2023-05-12T16:17:50.361Z,1683908270.361 [DATMMP](DEBUG): Initializing DATMMP. 2023-05-12T16:17:50.365Z,1683908270.365 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7039 2023-05-12T16:17:50.366Z,1683908270.366 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-05-12T16:17:50.372Z,1683908270.372 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-05-12T16:17:50.372Z,1683908270.372 [CBIT](DEBUG): Initializing heartbeat. 2023-05-12T16:17:50.380Z,1683908270.380 [Onboard ThreadHandler](INFO): Handler Thread ID is 7040 2023-05-12T16:17:50.398Z,1683908270.398 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7041 2023-05-12T16:17:50.424Z,1683908270.424 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7042 2023-05-12T16:17:50.425Z,1683908270.425 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-05-12T16:17:50.429Z,1683908270.429 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7044 2023-05-12T16:17:50.432Z,1683908270.432 [WetLabsBB2FL](INFO): Powering up 2023-05-12T16:17:50.433Z,1683908270.433 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7046 2023-05-12T16:17:50.436Z,1683908270.436 [WetLabsUBAT](INFO): Powering up 2023-05-12T16:17:50.437Z,1683908270.437 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7048 2023-05-12T16:17:50.441Z,1683908270.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-05-12T16:17:50.441Z,1683908270.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-05-12T16:17:50.441Z,1683908270.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-05-12T16:17:50.441Z,1683908270.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-05-12T16:17:50.442Z,1683908270.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-05-12T16:17:50.442Z,1683908270.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-05-12T16:17:50.442Z,1683908270.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-05-12T16:17:50.442Z,1683908270.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-05-12T16:17:50.444Z,1683908270.444 [CBIT](DEBUG): Deactivating GF circuits. 2023-05-12T16:17:50.444Z,1683908270.444 [CBIT](DEBUG): Deactivating emergency mode. 2023-05-12T16:17:50.484Z,1683908270.484 [CBIT](DEBUG): Backplane powered. 2023-05-12T16:17:50.489Z,1683908270.489 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-05-12T16:17:50.514Z,1683908270.514 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-12T16:17:50.554Z,1683908270.554 [MissionManager](DEBUG): 2023-05-12T16:17:50.555Z,1683908270.555 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-05-12T16:17:50.612Z,1683908270.612 [DATMMP](INFO): Starting 2023-05-12T16:17:50.638Z,1683908270.638 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-05-12T16:17:50.639Z,1683908270.639 [Default:A.Wait](DEBUG): Construct Wait. 2023-05-12T16:17:50.653Z,1683908270.653 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-05-12T16:17:50.716Z,1683908270.716 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-05-12T16:17:50.719Z,1683908270.719 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-05-12T16:17:50.745Z,1683908270.745 [Default:E.Execute](DEBUG): Construct Execute. 2023-05-12T16:17:50.749Z,1683908270.749 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-05-12T16:17:50.760Z,1683908270.760 [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-12T16:17:50.772Z,1683908270.772 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-05-12T16:17:50.804Z,1683908270.804 [Radio_Surface](INFO): Powering up 2023-05-12T16:17:50.853Z,1683908270.853 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1650.297119 decibar, 0.000000 m 2023-05-12T16:17:50.853Z,1683908270.853 [Power24vConverter](INFO): Powering up. 2023-05-12T16:17:50.854Z,1683908270.854 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2023-05-12T16:17:50.864Z,1683908270.864 [DATMMP](INFO): Starting 2023-05-12T16:17:50.890Z,1683908270.890 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-05-12T16:17:50.937Z,1683908270.937 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-05-12T16:17:50.952Z,1683908270.952 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-05-12T16:17:50.953Z,1683908270.953 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-05-12T16:17:50.960Z,1683908270.960 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-05-12T16:17:50.961Z,1683908270.961 [MassServo](DEBUG): Initializing EZServoServo. 2023-05-12T16:17:50.968Z,1683908270.968 [MassServo](DEBUG): Initializing MassServo. 2023-05-12T16:17:50.969Z,1683908270.969 [RudderServo](DEBUG): Initializing EZServoServo. 2023-05-12T16:17:50.976Z,1683908270.976 [RudderServo](DEBUG): Initializing RudderServo. 2023-05-12T16:17:50.977Z,1683908270.977 [ThrusterServo](DEBUG): Initializing EZServoServo. 2023-05-12T16:17:50.984Z,1683908270.984 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2023-05-12T16:17:51.116Z,1683908271.116 [DATMMP](INFO): Starting 2023-05-12T16:17:51.368Z,1683908271.368 [DATMMP](INFO): Starting 2023-05-12T16:17:51.620Z,1683908271.620 [DATMMP](INFO): Starting 2023-05-12T16:17:51.872Z,1683908271.872 [DATMMP](INFO): Starting 2023-05-12T16:17:52.125Z,1683908272.125 [DATMMP](INFO): Starting 2023-05-12T16:17:52.376Z,1683908272.376 [DATMMP](INFO): Starting 2023-05-12T16:17:52.628Z,1683908272.628 [DATMMP](INFO): Starting 2023-05-12T16:17:52.880Z,1683908272.880 [DATMMP](INFO): Starting 2023-05-12T16:17:53.044Z,1683908273.044 [WetLabsBB2FL](INFO): Powering down 2023-05-12T16:17:53.132Z,1683908273.132 [DATMMP](INFO): Starting 2023-05-12T16:17:53.384Z,1683908273.384 [DATMMP](INFO): Starting 2023-05-12T16:17:53.636Z,1683908273.636 [DATMMP](INFO): Starting 2023-05-12T16:17:53.888Z,1683908273.888 [DATMMP](INFO): Starting 2023-05-12T16:17:54.140Z,1683908274.140 [DATMMP](INFO): Starting 2023-05-12T16:17:54.392Z,1683908274.392 [DATMMP](INFO): Starting 2023-05-12T16:17:54.644Z,1683908274.644 [DATMMP](INFO): Starting 2023-05-12T16:17:54.896Z,1683908274.896 [DATMMP](INFO): Starting 2023-05-12T16:17:55.148Z,1683908275.148 [DATMMP](INFO): Starting 2023-05-12T16:17:55.400Z,1683908275.400 [DATMMP](INFO): Starting 2023-05-12T16:17:55.652Z,1683908275.652 [DATMMP](INFO): Starting 2023-05-12T16:17:55.904Z,1683908275.904 [DATMMP](INFO): Starting 2023-05-12T16:17:56.156Z,1683908276.156 [DATMMP](INFO): Starting 2023-05-12T16:17:56.408Z,1683908276.408 [DATMMP](INFO): Starting 2023-05-12T16:17:56.660Z,1683908276.660 [DATMMP](INFO): Starting 2023-05-12T16:17:56.912Z,1683908276.912 [DATMMP](INFO): Starting 2023-05-12T16:17:57.164Z,1683908277.164 [DATMMP](INFO): Starting 2023-05-12T16:17:57.416Z,1683908277.416 [DATMMP](INFO): Starting 2023-05-12T16:17:57.668Z,1683908277.668 [DATMMP](INFO): Starting 2023-05-12T16:17:57.920Z,1683908277.920 [DATMMP](INFO): Starting 2023-05-12T16:17:58.172Z,1683908278.172 [DATMMP](INFO): Starting 2023-05-12T16:17:58.424Z,1683908278.424 [DATMMP](INFO): Starting 2023-05-12T16:17:58.676Z,1683908278.676 [DATMMP](INFO): Starting 2023-05-12T16:17:58.928Z,1683908278.928 [DATMMP](INFO): Starting 2023-05-12T16:17:59.180Z,1683908279.180 [DATMMP](INFO): Starting 2023-05-12T16:17:59.432Z,1683908279.432 [DATMMP](INFO): Starting 2023-05-12T16:17:59.684Z,1683908279.684 [DATMMP](INFO): Starting 2023-05-12T16:17:59.688Z,1683908279.688 [WetLabsUBAT](INFO): Powering down 2023-05-12T16:17:59.936Z,1683908279.936 [DATMMP](INFO): Starting 2023-05-12T16:18:00.188Z,1683908280.188 [DATMMP](INFO): Starting 2023-05-12T16:18:00.440Z,1683908280.440 [DATMMP](INFO): Starting 2023-05-12T16:18:00.692Z,1683908280.692 [DATMMP](INFO): Starting 2023-05-12T16:18:00.944Z,1683908280.944 [DATMMP](INFO): Starting 2023-05-12T16:18:01.196Z,1683908281.196 [DATMMP](INFO): Starting 2023-05-12T16:18:01.448Z,1683908281.448 [DATMMP](INFO): Starting 2023-05-12T16:18:01.700Z,1683908281.700 [DATMMP](INFO): Starting 2023-05-12T16:18:01.952Z,1683908281.952 [DATMMP](INFO): Starting 2023-05-12T16:18:02.204Z,1683908282.204 [DATMMP](INFO): Starting 2023-05-12T16:18:02.456Z,1683908282.456 [DATMMP](INFO): Starting 2023-05-12T16:18:02.456Z,1683908282.456 [DATMMP](INFO): DAT read: 2023-05-12T16:18:02.457Z,1683908282.457 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-05-12T16:18:02.708Z,1683908282.708 [DATMMP](INFO): Starting 2023-05-12T16:18:02.960Z,1683908282.960 [DATMMP](INFO): Starting 2023-05-12T16:18:03.212Z,1683908283.212 [DATMMP](INFO): Starting 2023-05-12T16:18:03.464Z,1683908283.464 [DATMMP](INFO): Starting 2023-05-12T16:18:03.716Z,1683908283.716 [DATMMP](INFO): Starting 2023-05-12T16:18:03.968Z,1683908283.968 [DATMMP](INFO): Starting 2023-05-12T16:18:04.220Z,1683908284.220 [DATMMP](INFO): Starting 2023-05-12T16:18:04.221Z,1683908284.221 [DATMMP](INFO): DAT read: MF Frequency Band 2023-05-12T16:18:04.222Z,1683908284.222 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2023-05-12T16:18:04.222Z,1683908284.222 [DATMMP](INFO): DAT read: Apr 12 2000 16:17:58 2023-05-12T16:18:04.472Z,1683908284.472 [DATMMP](INFO): Starting 2023-05-12T16:18:04.724Z,1683908284.724 [DATMMP](INFO): Starting 2023-05-12T16:18:04.977Z,1683908284.977 [DATMMP](INFO): Starting 2023-05-12T16:18:05.228Z,1683908285.228 [DATMMP](INFO): Starting 2023-05-12T16:18:05.229Z,1683908285.229 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2023-05-12T16:18:05.230Z,1683908285.230 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-05-12T16:18:05.230Z,1683908285.230 [DATMMP](INFO): commRate: 800 2023-05-12T16:18:05.480Z,1683908285.480 [DATMMP](INFO): Starting 2023-05-12T16:18:05.732Z,1683908285.732 [DATMMP](INFO): Starting 2023-05-12T16:18:05.984Z,1683908285.984 [DATMMP](INFO): Starting 2023-05-12T16:18:06.236Z,1683908286.236 [DATMMP](INFO): Starting 2023-05-12T16:18:06.488Z,1683908286.488 [DATMMP](INFO): Starting 2023-05-12T16:18:06.740Z,1683908286.740 [DATMMP](INFO): Starting 2023-05-12T16:18:06.992Z,1683908286.992 [DATMMP](INFO): Starting 2023-05-12T16:18:07.244Z,1683908287.244 [DATMMP](INFO): Starting 2023-05-12T16:18:07.296Z,1683908287.296 [DATMMP](INFO): entering command mode 2023-05-12T16:18:07.496Z,1683908287.496 [DATMMP](INFO): Starting 2023-05-12T16:18:07.496Z,1683908287.496 [DATMMP](INFO): DAT read: 2023-05-12T16:18:07.497Z,1683908287.497 [DATMMP](INFO): DAT read: user:1> 2023-05-12T16:18:07.497Z,1683908287.497 [DATMMP](DEBUG): read user prompt 1: user:1> 2023-05-12T16:18:07.497Z,1683908287.497 [DATMMP](INFO): entering MMP mode 2023-05-12T16:18:07.748Z,1683908287.748 [DATMMP](INFO): Starting 2023-05-12T16:18:07.749Z,1683908287.749 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:18:07.750Z,1683908287.750 [DATMMP](INFO): status rx: x1 2023-05-12T16:18:07.751Z,1683908287.751 [DATMMP](INFO): MMP status: 1: started 2023-05-12T16:18:07.751Z,1683908287.751 [DATMMP](INFO): Received message type: status 2023-05-12T16:18:07.751Z,1683908287.751 [DATMMP](INFO): Handled 2023-05-12T16:18:07.000Z,1683908288.000 [DATMMP](INFO): Starting 2023-05-12T16:18:08.001Z,1683908288.001 [DATMMP](INFO): Sent Tx Power Config 8 2023-05-12T16:18:08.001Z,1683908288.001 [DATMMP](INFO): Setting local address: 10 2023-05-12T16:18:08.005Z,1683908288.005 [DATMMP](INFO): Setting time to: 16:18:8 And date to:5/12/2023 2023-05-12T16:18:08.252Z,1683908288.252 [DATMMP](INFO): Starting 2023-05-12T16:18:08.252Z,1683908288.252 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:18:08.253Z,1683908288.253 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2023-05-12T16:18:08.253Z,1683908288.253 [DATMMP](INFO): Received message type: mdmpower 2023-05-12T16:18:08.254Z,1683908288.254 [DATMMP](INFO): Handled 2023-05-12T16:18:08.504Z,1683908288.504 [DATMMP](INFO): Starting 2023-05-12T16:18:08.504Z,1683908288.504 [DATMMP](INFO): GSXN notify for xid: 10 2023-05-12T16:18:08.505Z,1683908288.505 [DATMMP](INFO): sreg_txpower rx: x8 2023-05-12T16:18:08.505Z,1683908288.505 [DATMMP](INFO): Received message type: sreg_txpower 2023-05-12T16:18:08.505Z,1683908288.505 [DATMMP](INFO): Match for : sreg_txpower 2023-05-12T16:18:08.506Z,1683908288.506 [DATMMP](INFO): ACK 2023-05-12T16:18:08.756Z,1683908288.756 [DATMMP](INFO): Starting 2023-05-12T16:18:08.756Z,1683908288.756 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:18:08.757Z,1683908288.757 [DATMMP](INFO): mdmlocal rx: xA x0 x0 x0 2023-05-12T16:18:08.757Z,1683908288.757 [DATMMP](INFO): Received message type: mdmlocal 2023-05-12T16:18:08.757Z,1683908288.757 [DATMMP](INFO): Handled 2023-05-12T16:18:09.008Z,1683908289.008 [DATMMP](INFO): Starting 2023-05-12T16:18:09.009Z,1683908289.009 [DATMMP](INFO): GSXN notify for xid: 11 2023-05-12T16:18:09.010Z,1683908289.010 [DATMMP](INFO): sreg_locaddr rx: xA 2023-05-12T16:18:09.010Z,1683908289.010 [DATMMP](INFO): Received message type: sreg_locaddr 2023-05-12T16:18:09.010Z,1683908289.010 [DATMMP](INFO): Match for : sreg_locaddr 2023-05-12T16:18:09.010Z,1683908289.010 [DATMMP](INFO): ACK 2023-05-12T16:18:09.260Z,1683908289.260 [DATMMP](INFO): Starting 2023-05-12T16:18:09.260Z,1683908289.260 [DATMMP](INFO): GSXN notify for xid: 12 2023-05-12T16:18:09.262Z,1683908289.262 [DATMMP](INFO): timedate rx: x0 x10 x12 x8 x4 xC x7B x0 2023-05-12T16:18:09.262Z,1683908289.262 [DATMMP](INFO): Received message type: timedate 2023-05-12T16:18:09.262Z,1683908289.262 [DATMMP](INFO): Match for : timedate 2023-05-12T16:18:09.262Z,1683908289.262 [DATMMP](INFO): ACK 2023-05-12T16:18:09.512Z,1683908289.512 [DATMMP](INFO): Starting 2023-05-12T16:18:09.512Z,1683908289.512 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:18:09.513Z,1683908289.513 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2023-05-12T16:18:09.513Z,1683908289.513 [DATMMP](INFO): Received message type: sys_verb 2023-05-12T16:18:09.513Z,1683908289.513 [DATMMP](INFO): Handled 2023-05-12T16:18:09.764Z,1683908289.764 [DATMMP](INFO): Starting 2023-05-12T16:18:09.764Z,1683908289.764 [DATMMP](INFO): GSXN notify for xid: 13 2023-05-12T16:18:09.765Z,1683908289.765 [DATMMP](INFO): sreg_verbosity rx: x0 2023-05-12T16:18:09.765Z,1683908289.765 [DATMMP](INFO): Received message type: sreg_verbosity 2023-05-12T16:18:09.765Z,1683908289.765 [DATMMP](INFO): Match for : sreg_verbosity 2023-05-12T16:18:09.765Z,1683908289.765 [DATMMP](INFO): ACK 2023-05-12T16:18:10.016Z,1683908290.016 [DATMMP](INFO): Starting 2023-05-12T16:18:10.016Z,1683908290.016 [DATMMP](INFO): GSXN notify for xid: 14 2023-05-12T16:18:10.017Z,1683908290.017 [DATMMP](INFO): version rx: x2 x5 2023-05-12T16:18:10.017Z,1683908290.017 [DATMMP](INFO): DatMMPVersion: 2.5 2023-05-12T16:18:10.018Z,1683908290.018 [DATMMP](INFO): Received message type: version 2023-05-12T16:18:10.018Z,1683908290.018 [DATMMP](INFO): Match for : version 2023-05-12T16:18:10.018Z,1683908290.018 [DATMMP](INFO): ACK 2023-05-12T16:18:10.018Z,1683908290.018 [DATMMP](INFO): in MMP mode: config complete going to runnable 2023-05-12T16:18:19.457Z,1683908299.457 [NAL9602](INFO): Powering up NAL9602 2023-05-12T16:18:24.137Z,1683908304.137 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:18:24.137Z,1683908304.137 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2023-05-12T16:18:24.138Z,1683908304.138 [DATMMP](INFO): Received message type: timesync 2023-05-12T16:18:24.138Z,1683908304.138 [DATMMP](INFO): Handled 2023-05-12T16:18:30.409Z,1683908310.409 [NAL9602](INFO): NAL9602 initialized 2023-05-12T16:18:34.216Z,1683908314.216 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:18:34.217Z,1683908314.217 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2023-05-12T16:18:34.217Z,1683908314.217 [DATMMP](INFO): Received message type: timesync 2023-05-12T16:18:34.218Z,1683908314.218 [DATMMP](INFO): Handled 2023-05-12T16:18:42.100Z,1683908322.100 [SBIT](IMPORTANT): Beginning Startup BIT 2023-05-12T16:18:42.104Z,1683908322.104 [CBIT](IMPORTANT): Beginning ground fault scan 2023-05-12T16:18:53.066Z,1683908333.066 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.001639 CHAN A1 (24V): 0.000665 CHAN A2 (12V): -0.007612 CHAN A3 (5V): -0.002402 CHAN B0 (3.3V): -0.000298 CHAN B1 (3.15aV): -0.000870 CHAN B2 (3.15bV): 0.000101 CHAN B3 (GND): 0.001980 OPEN: 0.005164 Full Scale: +/- 1 mA 2023-05-12T16:19:10.767Z,1683908350.767 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2023-05-12T16:19:35.831Z,1683908375.831 [SBIT](IMPORTANT): SBIT PASSED 2023-05-12T16:19:35.831Z,1683908375.831 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-05-12T16:19:35.834Z,1683908375.834 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2023-05-12T16:19:35.834Z,1683908375.834 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2023-05-12T16:19:35.835Z,1683908375.835 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2023-05-12T16:19:35.835Z,1683908375.835 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2023-05-12T16:19:35.835Z,1683908375.835 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter; 2023-05-12T16:19:35.837Z,1683908375.837 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter; 2023-05-12T16:19:35.837Z,1683908375.837 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2023-05-12T16:19:36.261Z,1683908376.261 [MissionManager](IMPORTANT): Started mission Startup 2023-05-12T16:19:36.261Z,1683908376.261 [Startup] Running Loop=1 2023-05-12T16:19:36.262Z,1683908376.262 [Startup](DEBUG): Aggregate::initialize Startup 2023-05-12T16:19:36.262Z,1683908376.262 [Startup:A.GoToSurface] Running Loop=1 2023-05-12T16:19:36.262Z,1683908376.262 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-12T16:19:36.262Z,1683908376.262 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-12T16:19:36.263Z,1683908376.263 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-12T16:19:36.263Z,1683908376.263 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-12T16:19:36.264Z,1683908376.264 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-12T16:19:36.264Z,1683908376.264 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-12T16:19:36.265Z,1683908376.265 [Startup:StartupSatComms] Running Loop=1 2023-05-12T16:19:36.266Z,1683908376.266 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-05-12T16:19:36.266Z,1683908376.266 [Startup:StartupSatComms:A] Running Loop=1 2023-05-12T16:19:36.670Z,1683908376.670 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-05-12T16:20:18.434Z,1683908418.434 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004475 2023-05-12T16:20:36.466Z,1683908436.466 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-12T16:19:36.3Z 2023-05-12T16:20:36.466Z,1683908436.466 [Startup:StartupSatComms:A] Stopped 2023-05-12T16:20:36.466Z,1683908436.466 [Startup:StartupSatComms:B] Running Loop=1 2023-05-12T16:20:36.829Z,1683908436.829 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-05-12T16:20:44.051Z,1683908444.051 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230512T155536/Courier0010.lzma 2023-05-12T16:20:45.054Z,1683908445.054 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Courier0010.lzma.bak 2023-05-12T16:20:45.054Z,1683908445.054 [DataOverHttps](INFO): SBD MOMSN=18295223 2023-05-12T16:20:50.634Z,1683908450.634 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-05-12T16:20:50.635Z,1683908450.635 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-05-12T16:20:50.658Z,1683908450.658 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-05-12T16:20:51.030Z,1683908451.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-05-12T16:20:51.030Z,1683908451.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-05-12T16:21:03.099Z,1683908463.099 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230512T161739/Courier0000.lzma 2023-05-12T16:21:04.102Z,1683908464.102 [DataOverHttps](INFO): Moved sent file to Logs/20230512T161739/Courier0000.lzma.bak 2023-05-12T16:21:04.102Z,1683908464.102 [DataOverHttps](INFO): SBD MOMSN=18295227 2023-05-12T16:21:22.084Z,1683908482.084 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20230512T155536/Express0011.lzma 2023-05-12T16:21:23.085Z,1683908483.085 [DataOverHttps](INFO): Moved sent file to Logs/20230512T155536/Express0011.lzma.bak 2023-05-12T16:21:23.086Z,1683908483.086 [DataOverHttps](INFO): SBD MOMSN=18295274 2023-05-12T16:21:36.764Z,1683908496.764 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-12T16:20:36.5Z 2023-05-12T16:21:36.764Z,1683908496.764 [Startup:StartupSatComms:B] Stopped 2023-05-12T16:21:36.764Z,1683908496.764 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-05-12T16:21:36.764Z,1683908496.764 [Startup:StartupSatComms] Stopped 2023-05-12T16:21:36.764Z,1683908496.764 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-05-12T16:21:36.765Z,1683908496.765 [Startup](INFO): Completed Startup 2023-05-12T16:21:36.765Z,1683908496.765 [MissionManager](INFO): Startup is completed. 2023-05-12T16:21:36.765Z,1683908496.765 [MissionManager](INFO): Uninitializing Mission Startup 2023-05-12T16:21:36.765Z,1683908496.765 [Startup] Stopped 2023-05-12T16:21:36.765Z,1683908496.765 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-05-12T16:21:36.765Z,1683908496.765 [Startup:A.GoToSurface] Stopped 2023-05-12T16:21:36.765Z,1683908496.765 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-12T16:21:37.445Z,1683908497.445 [MissionManager](IMPORTANT): Started mission Default 2023-05-12T16:21:37.445Z,1683908497.445 [Default] Running Loop=1 2023-05-12T16:21:37.445Z,1683908497.445 [Default](DEBUG): Aggregate::initialize Default 2023-05-12T16:21:37.445Z,1683908497.445 [Default:B.GoToSurface] Running Loop=1 2023-05-12T16:21:37.445Z,1683908497.445 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-05-12T16:21:37.446Z,1683908497.446 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-05-12T16:21:37.446Z,1683908497.446 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-05-12T16:21:37.446Z,1683908497.446 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-05-12T16:21:37.446Z,1683908497.446 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-05-12T16:21:37.447Z,1683908497.447 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-05-12T16:21:37.447Z,1683908497.447 [Default:A.Wait] Running Loop=1 2023-05-12T16:21:37.447Z,1683908497.447 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-05-12T16:21:41.355Z,1683908501.355 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree 2023-05-12T16:21:41.363Z,1683908501.363 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2023-05-12T16:21:41.363Z,1683908501.363 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree 2023-05-12T16:21:41.371Z,1683908501.371 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2023-05-12T16:21:41.371Z,1683908501.371 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter 2023-05-12T16:21:41.415Z,1683908501.415 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2023-05-12T16:21:41.415Z,1683908501.415 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range 2023-05-12T16:21:41.420Z,1683908501.420 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range 2023-05-12T16:21:41.626Z,1683908501.626 [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-12T16:21:42.917Z,1683908502.917 [DataOverHttps](INFO): Sending 856 bytes from file Logs/20230512T161739/Express0001.lzma 2023-05-12T16:21:43.917Z,1683908503.917 [DataOverHttps](INFO): Moved sent file to Logs/20230512T161739/Express0001.lzma.bak 2023-05-12T16:21:43.918Z,1683908503.918 [DataOverHttps](INFO): SBD MOMSN=18295278 2023-05-12T16:21:50.780Z,1683908510.780 [Default:A.Wait](INFO): Done Waiting. 2023-05-12T16:21:50.780Z,1683908510.780 [Default:A.Wait] Stopped 2023-05-12T16:21:50.780Z,1683908510.780 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-05-12T16:21:51.313Z,1683908511.313 [Default:CheckIn] Running Loop=1 2023-05-12T16:21:51.313Z,1683908511.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-12T16:21:51.313Z,1683908511.313 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-12T16:21:51.516Z,1683908511.516 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-05-12T16:21:58.863Z,1683908518.863 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683908503 second 2023-05-12T16:21:59.068Z,1683908519.068 [Default:CheckIn:Read_GPS] Stopped 2023-05-12T16:21:59.068Z,1683908519.068 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-12T16:21:59.476Z,1683908519.476 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-05-12T16:22:06.398Z,1683908526.398 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230512T161739/Courier0004.lzma 2023-05-12T16:22:07.394Z,1683908527.394 [DataOverHttps](INFO): Moved sent file to Logs/20230512T161739/Courier0004.lzma.bak 2023-05-12T16:22:07.394Z,1683908527.394 [DataOverHttps](INFO): SBD MOMSN=18295340 2023-05-12T16:22:23.484Z,1683908543.484 [DataOverHttps](INFO): Sending 428 bytes from file Logs/20230512T161739/Express0005.lzma 2023-05-12T16:22:24.486Z,1683908544.486 [DataOverHttps](INFO): Moved sent file to Logs/20230512T161739/Express0005.lzma.bak 2023-05-12T16:22:24.486Z,1683908544.486 [DataOverHttps](INFO): SBD MOMSN=18295342 2023-05-12T16:22:25.775Z,1683908545.775 [Default:CheckIn:Read_Iridium] Stopped 2023-05-12T16:22:25.775Z,1683908545.775 [Default:CheckIn:C.Wait] Running Loop=1 2023-05-12T16:22:25.775Z,1683908545.775 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-05-12T16:23:05.961Z,1683908585.961 [CommandExec](IMPORTANT): got command show stack 2023-05-12T16:23:05.961Z,1683908585.961 [CommandExec](IMPORTANT): Behavior Stack: 2023-05-12T16:23:05.962Z,1683908585.962 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2023-05-12T16:23:05.962Z,1683908585.962 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait 2023-05-12T16:23:08.475Z,1683908588.475 [CommandExec](IMPORTANT): got command get latitude 2023-05-12T16:23:08.476Z,1683908588.476 [CommandExec](IMPORTANT): latitude 36.799999 arcdeg 2023-05-12T16:23:12.448Z,1683908592.448 [CommandExec](IMPORTANT): got command get depth 2023-05-12T16:23:12.448Z,1683908592.448 [CommandExec](IMPORTANT): depth 0.800000 m 2023-05-12T16:23:33.613Z,1683908613.613 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-05-12T16:24:04.450Z,1683908644.450 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-12T16:24:05.924Z,1683908645.924 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:05.925Z,1683908645.925 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:24:05.925Z,1683908645.925 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:24:05.925Z,1683908645.925 [DATMMP](INFO): Handled 2023-05-12T16:24:06.176Z,1683908646.176 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:06.177Z,1683908646.177 [DATMMP](INFO): timestamp rx: x1 x10 x18 x4 x14EB 2023-05-12T16:24:06.178Z,1683908646.178 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908696.309248 2023-05-12T16:24:06.178Z,1683908646.178 [DATMMP](INFO): Rx ping set to:1683908646.177434 2023-05-12T16:24:06.179Z,1683908646.179 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:06.179Z,1683908646.179 [DATMMP](INFO): Handled 2023-05-12T16:24:06.680Z,1683908646.680 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:06.681Z,1683908646.681 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:24:06.681Z,1683908646.681 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:24:06.681Z,1683908646.681 [DATMMP](INFO): Handled 2023-05-12T16:24:06.932Z,1683908646.932 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:06.934Z,1683908646.934 [DATMMP](INFO): directional_debug rx: x193 xFF x22 x1EC x2AD0 x37C1 x3622 x5993 2023-05-12T16:24:06.935Z,1683908646.935 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:24:06.935Z,1683908646.935 [DATMMP](INFO): Handled 2023-05-12T16:24:07.184Z,1683908647.184 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:07.186Z,1683908647.186 [DATMMP](INFO): directional rx: x1B7 xEE x449 xFF01 xAFC xFF03 x0 x6B4 x0 xFFFE x27B5966 x-43569E0 2023-05-12T16:24:07.188Z,1683908647.188 [DATMMP](INFO): Received message type: directional 2023-05-12T16:24:07.188Z,1683908647.188 [DATMMP](INFO): Handled 2023-05-12T16:24:07.436Z,1683908647.436 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:07.438Z,1683908647.438 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:24:07.438Z,1683908647.438 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:24:07.438Z,1683908647.438 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:24:07.439Z,1683908647.439 [DATMMP](INFO): Handled 2023-05-12T16:24:07.688Z,1683908647.688 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:07.690Z,1683908647.690 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x22 x85 xAE xAB xD xFFFF x0 x1E x0 x0 2023-05-12T16:24:07.691Z,1683908647.691 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:24:07.691Z,1683908647.691 [DATMMP](INFO): Handled 2023-05-12T16:24:07.940Z,1683908647.940 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:07.941Z,1683908647.941 [DATMMP](INFO): timestamp rx: x0 x10 x18 x6 x567 2023-05-12T16:24:07.941Z,1683908647.941 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:07.942Z,1683908647.942 [DATMMP](INFO): Handled 2023-05-12T16:24:21.312Z,1683908661.312 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:21.313Z,1683908661.313 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:24:21.313Z,1683908661.313 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:24:21.313Z,1683908661.313 [DATMMP](INFO): Handled 2023-05-12T16:24:21.564Z,1683908661.564 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:21.565Z,1683908661.565 [DATMMP](INFO): timestamp rx: x1 x10 x18 x13 x2489 2023-05-12T16:24:21.566Z,1683908661.566 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908686.354304 2023-05-12T16:24:21.566Z,1683908661.566 [DATMMP](INFO): Rx ping set to:1683908661.565305 2023-05-12T16:24:21.566Z,1683908661.566 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:21.566Z,1683908661.566 [DATMMP](INFO): Handled 2023-05-12T16:24:22.068Z,1683908662.068 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:22.069Z,1683908662.069 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:24:22.069Z,1683908662.069 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:24:22.069Z,1683908662.069 [DATMMP](INFO): Handled 2023-05-12T16:24:22.322Z,1683908662.322 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:22.324Z,1683908662.324 [DATMMP](INFO): directional_debug rx: x194 xFD x21 x1DD x2AF0 x42D1 x69F2 x6093 2023-05-12T16:24:22.325Z,1683908662.325 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:24:22.325Z,1683908662.325 [DATMMP](INFO): Handled 2023-05-12T16:24:22.572Z,1683908662.572 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:22.574Z,1683908662.574 [DATMMP](INFO): directional rx: x1B8 xED x448 xFF03 xAFA xFF04 x0 x6B3 x1 xFFFF x27B5966 x-43569E0 2023-05-12T16:24:22.576Z,1683908662.576 [DATMMP](INFO): Received message type: directional 2023-05-12T16:24:22.576Z,1683908662.576 [DATMMP](INFO): Handled 2023-05-12T16:24:22.824Z,1683908662.824 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:22.825Z,1683908662.825 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:24:22.826Z,1683908662.826 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:24:22.826Z,1683908662.826 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:24:22.826Z,1683908662.826 [DATMMP](INFO): Handled 2023-05-12T16:24:23.076Z,1683908663.076 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:23.078Z,1683908663.078 [DATMMP](INFO): acstats rx: xC x0 x0 x1AA x23 x7D xAD xAC xD xFFFF x0 x1E x0 x0 2023-05-12T16:24:23.078Z,1683908663.078 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:24:23.078Z,1683908663.078 [DATMMP](INFO): Handled 2023-05-12T16:24:23.328Z,1683908663.328 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:23.329Z,1683908663.329 [DATMMP](INFO): timestamp rx: x0 x10 x18 x15 x1505 2023-05-12T16:24:23.329Z,1683908663.329 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:23.330Z,1683908663.330 [DATMMP](INFO): Handled 2023-05-12T16:24:36.700Z,1683908676.700 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:36.701Z,1683908676.701 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:24:36.701Z,1683908676.701 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:24:36.701Z,1683908676.701 [DATMMP](INFO): Handled 2023-05-12T16:24:36.952Z,1683908676.952 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:36.953Z,1683908676.953 [DATMMP](INFO): timestamp rx: x1 x10 x18 x23 xB25 2023-05-12T16:24:36.954Z,1683908676.954 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908702.903168 2023-05-12T16:24:36.954Z,1683908676.954 [DATMMP](INFO): Rx ping set to:1683908676.953351 2023-05-12T16:24:36.954Z,1683908676.954 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:36.954Z,1683908676.954 [DATMMP](INFO): Handled 2023-05-12T16:24:37.456Z,1683908677.456 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:37.457Z,1683908677.457 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:24:37.457Z,1683908677.457 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:24:37.457Z,1683908677.457 [DATMMP](INFO): Handled 2023-05-12T16:24:37.708Z,1683908677.708 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:37.710Z,1683908677.710 [DATMMP](INFO): directional_debug rx: x1A3 x10B x22 x1DE x29A0 x35F1 x5CE2 x5A33 2023-05-12T16:24:37.711Z,1683908677.711 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:24:37.711Z,1683908677.711 [DATMMP](INFO): Handled 2023-05-12T16:24:37.960Z,1683908677.960 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:37.962Z,1683908677.962 [DATMMP](INFO): directional rx: x1C7 xF7 x439 xFEF5 xAEA xFEF5 x0 x6B3 x2 x0 x27B5966 x-43569E0 2023-05-12T16:24:37.964Z,1683908677.964 [DATMMP](INFO): Received message type: directional 2023-05-12T16:24:37.964Z,1683908677.964 [DATMMP](INFO): Handled 2023-05-12T16:24:38.212Z,1683908678.212 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:38.214Z,1683908678.214 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:24:38.214Z,1683908678.214 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:24:38.214Z,1683908678.214 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:24:38.214Z,1683908678.214 [DATMMP](INFO): Handled 2023-05-12T16:24:38.464Z,1683908678.464 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:38.466Z,1683908678.466 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x23 x82 xAC xA8 xD xFFFF x0 x20 x0 x0 2023-05-12T16:24:38.466Z,1683908678.466 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:24:38.466Z,1683908678.466 [DATMMP](INFO): Handled 2023-05-12T16:24:38.716Z,1683908678.716 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:38.717Z,1683908678.717 [DATMMP](INFO): timestamp rx: x0 x10 x18 x24 x22B1 2023-05-12T16:24:38.718Z,1683908678.718 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:38.718Z,1683908678.718 [DATMMP](INFO): Handled 2023-05-12T16:24:51.824Z,1683908691.824 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:51.825Z,1683908691.825 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:24:51.825Z,1683908691.825 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:24:51.825Z,1683908691.825 [DATMMP](INFO): Handled 2023-05-12T16:24:52.077Z,1683908692.077 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:52.078Z,1683908692.078 [DATMMP](INFO): timestamp rx: x1 x10 x18 x32 x12E8 2023-05-12T16:24:52.078Z,1683908692.078 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908737.278528 2023-05-12T16:24:52.078Z,1683908692.078 [DATMMP](INFO): Rx ping set to:1683908692.077627 2023-05-12T16:24:52.079Z,1683908692.079 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:52.079Z,1683908692.079 [DATMMP](INFO): Handled 2023-05-12T16:24:52.592Z,1683908692.592 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:52.593Z,1683908692.593 [DATMMP](INFO): remote_header rx: xF x4 x5 x22 x0 xA xC x0 2023-05-12T16:24:52.594Z,1683908692.594 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:24:52.594Z,1683908692.594 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:24:52.594Z,1683908692.594 [DATMMP](INFO): Handled 2023-05-12T16:24:53.096Z,1683908693.096 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:53.097Z,1683908693.097 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:24:53.097Z,1683908693.097 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:24:53.097Z,1683908693.097 [DATMMP](INFO): Handled 2023-05-12T16:24:53.348Z,1683908693.348 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:53.350Z,1683908693.350 [DATMMP](INFO): directional_debug rx: x1A5 x10C x22 x1D5 x2910 x34D1 x5752 x59A3 2023-05-12T16:24:53.351Z,1683908693.351 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:24:53.351Z,1683908693.351 [DATMMP](INFO): Handled 2023-05-12T16:24:53.600Z,1683908693.600 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:53.602Z,1683908693.602 [DATMMP](INFO): directional rx: x1CA xF8 x437 xFEF4 xAE7 xFEF3 x0 x6B1 x1 x1 x27B5966 x-43569E0 2023-05-12T16:24:53.604Z,1683908693.604 [DATMMP](INFO): Received message type: directional 2023-05-12T16:24:53.604Z,1683908693.604 [DATMMP](INFO): Handled 2023-05-12T16:24:53.852Z,1683908693.852 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:53.854Z,1683908693.854 [DATMMP](INFO): acstats rx: xC x5 x0 x2D0 x22 x84 xB7 xBE xD xFFFF xA9 x22 xC x0 2023-05-12T16:24:53.854Z,1683908693.854 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:24:53.855Z,1683908693.855 [DATMMP](INFO): Handled 2023-05-12T16:24:54.104Z,1683908694.104 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:54.105Z,1683908694.105 [DATMMP](INFO): data_rx rx: xC x0 x0 x22 2023-05-12T16:24:54.105Z,1683908694.105 [DATMMP](INFO): Check CRC 2023-05-12T16:24:54.106Z,1683908694.106 [DATMMP](INFO): data rx parseDataResponse 34 2023-05-12T16:24:54.106Z,1683908694.106 [DATMMP](INFO): Got DATA: len 34 2023-05-12T16:24:54.106Z,1683908694.106 [DATMMP](INFO): Received command: set _.pressure 0.745784 atmosphere 2023-05-12T16:24:54.107Z,1683908694.107 [DATMMP](INFO): #Outgoing data=1 2023-05-12T16:24:54.107Z,1683908694.107 [DATMMP](INFO): Sending ack 2023-05-12T16:24:54.107Z,1683908694.107 [DATMMP](INFO): Received message type: data_rx 2023-05-12T16:24:54.107Z,1683908694.107 [DATMMP](INFO): Handled 2023-05-12T16:24:54.107Z,1683908694.107 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T16:24:54.108Z,1683908694.108 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T16:24:54.358Z,1683908694.358 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:54.360Z,1683908694.360 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:24:54.360Z,1683908694.360 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:24:54.361Z,1683908694.361 [DATMMP](INFO): Handled 2023-05-12T16:24:54.361Z,1683908694.361 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T16:24:54.362Z,1683908694.362 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T16:24:54.609Z,1683908694.609 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:54.613Z,1683908694.613 [DATMMP](INFO): timestamp rx: x1 x10 x18 x34 x6AF 2023-05-12T16:24:54.615Z,1683908694.615 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908708.743424 2023-05-12T16:24:54.615Z,1683908694.615 [DATMMP](INFO): Rx ping set to:1683908694.613230 2023-05-12T16:24:54.615Z,1683908694.615 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:54.615Z,1683908694.615 [DATMMP](INFO): Handled 2023-05-12T16:24:54.615Z,1683908694.615 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T16:24:54.616Z,1683908694.616 [DATMMP](INFO): Tx to 12 len 4 xid 15 2023-05-12T16:24:54.617Z,1683908694.617 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T16:24:54.617Z,1683908694.617 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:24:54.860Z,1683908694.860 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:54.861Z,1683908694.861 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:24:54.861Z,1683908694.861 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:24:54.862Z,1683908694.862 [DATMMP](INFO): Handled 2023-05-12T16:24:55.112Z,1683908695.112 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:55.114Z,1683908695.114 [DATMMP](INFO): directional_debug rx: x19E x107 x22 x1E4 x28C0 x3421 x5F52 x5943 2023-05-12T16:24:55.115Z,1683908695.115 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:24:55.115Z,1683908695.115 [DATMMP](INFO): Handled 2023-05-12T16:24:55.115Z,1683908695.115 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:24:55.364Z,1683908695.364 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:55.366Z,1683908695.366 [DATMMP](INFO): directional rx: x1C2 xF4 x43E xFEF9 xAEF xFEF8 x0 x6B1 x1 x1 x27B5966 x-43569E0 2023-05-12T16:24:55.368Z,1683908695.368 [DATMMP](INFO): Received message type: directional 2023-05-12T16:24:55.368Z,1683908695.368 [DATMMP](INFO): Handled 2023-05-12T16:24:55.368Z,1683908695.368 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:24:55.616Z,1683908695.616 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:55.618Z,1683908695.618 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:24:55.618Z,1683908695.618 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:24:55.618Z,1683908695.618 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:24:55.618Z,1683908695.618 [DATMMP](INFO): Handled 2023-05-12T16:24:55.619Z,1683908695.619 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:24:55.868Z,1683908695.868 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:55.870Z,1683908695.870 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x22 x82 xAE xA9 xD xFFFF x0 x21 x0 x0 2023-05-12T16:24:55.870Z,1683908695.870 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:24:55.871Z,1683908695.871 [DATMMP](INFO): Handled 2023-05-12T16:24:55.871Z,1683908695.871 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:24:56.101Z,1683908696.101 [CommandExec](IMPORTANT): got command set _.pressure 0.745784 atmosphere 2023-05-12T16:24:56.120Z,1683908696.120 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:56.121Z,1683908696.121 [DATMMP](INFO): timestamp rx: x0 x10 x18 x35 x1E3C 2023-05-12T16:24:56.121Z,1683908696.121 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:56.122Z,1683908696.122 [DATMMP](INFO): Handled 2023-05-12T16:24:56.122Z,1683908696.122 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:24:56.375Z,1683908696.375 [DATMMP](INFO): GSXN notify for xid: 15 2023-05-12T16:24:56.377Z,1683908696.377 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T16:24:56.378Z,1683908696.378 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-12T16:24:56.378Z,1683908696.378 [DATMMP](DEBUG): Command complete send 2023-05-12T16:24:56.379Z,1683908696.379 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T16:24:56.380Z,1683908696.380 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T16:24:56.380Z,1683908696.380 [DATMMP](INFO): Handled 2023-05-12T16:24:56.381Z,1683908696.381 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T16:24:56.381Z,1683908696.381 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T16:24:56.625Z,1683908696.625 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:24:56.626Z,1683908696.626 [DATMMP](INFO): timestamp rx: x0 x10 x18 x35 x1FF9 2023-05-12T16:24:56.626Z,1683908696.626 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:24:56.626Z,1683908696.626 [DATMMP](INFO): Handled 2023-05-12T16:24:59.299Z,1683908699.299 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2023-05-12T16:25:01.164Z,1683908701.164 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:01.165Z,1683908701.165 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:25:01.165Z,1683908701.165 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:25:01.165Z,1683908701.165 [DATMMP](INFO): Handled 2023-05-12T16:25:01.416Z,1683908701.416 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:01.417Z,1683908701.417 [DATMMP](INFO): timestamp rx: x1 x10 x18 x3B x1E9F 2023-05-12T16:25:01.418Z,1683908701.418 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908711.579584 2023-05-12T16:25:01.418Z,1683908701.418 [DATMMP](INFO): Rx ping set to:1683908701.417287 2023-05-12T16:25:01.418Z,1683908701.418 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:01.418Z,1683908701.418 [DATMMP](INFO): Handled 2023-05-12T16:25:01.920Z,1683908701.920 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:01.922Z,1683908701.922 [DATMMP](INFO): remote_header rx: xF x4 x5 x23 x0 xA xC x0 2023-05-12T16:25:01.922Z,1683908701.922 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:25:01.922Z,1683908701.922 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:25:01.922Z,1683908701.922 [DATMMP](INFO): Handled 2023-05-12T16:25:02.424Z,1683908702.424 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:02.425Z,1683908702.425 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:25:02.425Z,1683908702.425 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:25:02.425Z,1683908702.425 [DATMMP](INFO): Handled 2023-05-12T16:25:02.676Z,1683908702.676 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:02.677Z,1683908702.677 [DATMMP](INFO): directional_debug rx: x1A3 x108 x23 x1D2 x27E0 x34A1 x2D12 x56A3 2023-05-12T16:25:02.679Z,1683908702.679 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:25:02.679Z,1683908702.679 [DATMMP](INFO): Handled 2023-05-12T16:25:02.928Z,1683908702.928 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:02.930Z,1683908702.930 [DATMMP](INFO): directional rx: x1C7 xF5 x439 xFEF8 xAE9 xFEF6 x0 x6B1 x2 x2 x27B5966 x-43569E0 2023-05-12T16:25:02.932Z,1683908702.932 [DATMMP](INFO): Received message type: directional 2023-05-12T16:25:02.932Z,1683908702.932 [DATMMP](INFO): Handled 2023-05-12T16:25:03.180Z,1683908703.180 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:03.182Z,1683908703.182 [DATMMP](INFO): acstats rx: xC x5 x0 x1D9 x22 x8A xA7 xC0 xD xFFFF xA4 x21 xB x0 2023-05-12T16:25:03.182Z,1683908703.182 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:25:03.182Z,1683908703.182 [DATMMP](INFO): Handled 2023-05-12T16:25:03.433Z,1683908703.433 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:03.434Z,1683908703.434 [DATMMP](INFO): data_rx rx: xC x0 x0 x23 2023-05-12T16:25:03.434Z,1683908703.434 [DATMMP](INFO): Check CRC 2023-05-12T16:25:03.434Z,1683908703.434 [DATMMP](INFO): data rx parseDataResponse 35 2023-05-12T16:25:03.434Z,1683908703.434 [DATMMP](INFO): Got DATA: len 35 2023-05-12T16:25:03.434Z,1683908703.434 [DATMMP](INFO): Received command: set _.temperature 24.340143 celsius 2023-05-12T16:25:03.435Z,1683908703.435 [DATMMP](INFO): #Outgoing data=1 2023-05-12T16:25:03.435Z,1683908703.435 [DATMMP](INFO): Sending ack 2023-05-12T16:25:03.435Z,1683908703.435 [DATMMP](INFO): Received message type: data_rx 2023-05-12T16:25:03.435Z,1683908703.435 [DATMMP](INFO): Handled 2023-05-12T16:25:03.435Z,1683908703.435 [DATMMP](INFO): *** SENDING_FILL_BUFFER *** 2023-05-12T16:25:03.436Z,1683908703.436 [DATMMP](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_NAV_DATA 2023-05-12T16:25:03.684Z,1683908703.684 [DATMMP](INFO): *** SENDING_NAV_DATA *** 2023-05-12T16:25:03.684Z,1683908703.684 [DATMMP](DEBUG): In sendingNavData, set commsState_ = SENDING_TRANSMIT 2023-05-12T16:25:03.936Z,1683908703.936 [DATMMP](INFO): *** SENDING_TRANSMIT *** 2023-05-12T16:25:03.936Z,1683908703.936 [DATMMP](INFO): Tx to 12 len 4 xid 16 2023-05-12T16:25:03.937Z,1683908703.937 [DATMMP](INFO): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2023-05-12T16:25:03.937Z,1683908703.937 [DATMMP](INFO): *** SENDING_TRANSMIT_VERIFY *** 2023-05-12T16:25:04.188Z,1683908704.188 [DATMMP](INFO): GSXN notify for xid: 16 2023-05-12T16:25:04.189Z,1683908704.189 [DATMMP](INFO): cmd_result rx: x1 x7 x0 x1 2023-05-12T16:25:04.189Z,1683908704.189 [DATMMP](DEBUG): In rxCmdResult, sent ack so set commsState_ = SENDING_VERIFIED 2023-05-12T16:25:04.189Z,1683908704.189 [DATMMP](DEBUG): Command complete send 2023-05-12T16:25:04.190Z,1683908704.190 [DATMMP](ERROR): 1:COMPLETE 2023-05-12T16:25:04.190Z,1683908704.190 [DATMMP](INFO): Received message type: cmd_result 2023-05-12T16:25:04.190Z,1683908704.190 [DATMMP](INFO): Handled 2023-05-12T16:25:04.190Z,1683908704.190 [DATMMP](INFO): *** SENDING_VERIFIED *** 2023-05-12T16:25:04.190Z,1683908704.190 [DATMMP](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2023-05-12T16:25:04.441Z,1683908704.441 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:04.442Z,1683908704.442 [DATMMP](INFO): timestamp rx: x0 x10 x19 x3 x696 2023-05-12T16:25:04.442Z,1683908704.442 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:04.442Z,1683908704.442 [DATMMP](INFO): Handled 2023-05-12T16:25:04.840Z,1683908704.840 [CommandExec](IMPORTANT): got command set _.temperature 24.340143 celsius 2023-05-12T16:25:07.468Z,1683908707.468 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:07.469Z,1683908707.469 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:25:07.469Z,1683908707.469 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:25:07.469Z,1683908707.469 [DATMMP](INFO): Handled 2023-05-12T16:25:07.720Z,1683908707.720 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:07.721Z,1683908707.721 [DATMMP](INFO): timestamp rx: x1 x10 x19 x6 x353 2023-05-12T16:25:07.722Z,1683908707.722 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908714.325632 2023-05-12T16:25:07.722Z,1683908707.722 [DATMMP](INFO): Rx ping set to:1683908707.721302 2023-05-12T16:25:07.722Z,1683908707.722 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:07.722Z,1683908707.722 [DATMMP](INFO): Handled 2023-05-12T16:25:08.224Z,1683908708.224 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:08.225Z,1683908708.225 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:25:08.225Z,1683908708.225 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:25:08.225Z,1683908708.225 [DATMMP](INFO): Handled 2023-05-12T16:25:08.476Z,1683908708.476 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:08.478Z,1683908708.478 [DATMMP](INFO): directional_debug rx: x199 x102 x21 x1EA x2A70 x4231 x6BB2 x7FF3 2023-05-12T16:25:08.479Z,1683908708.479 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:25:08.479Z,1683908708.479 [DATMMP](INFO): Handled 2023-05-12T16:25:08.728Z,1683908708.728 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:08.730Z,1683908708.730 [DATMMP](INFO): directional rx: x1BC xF0 x443 xFEFE xAF1 xFEFD x0 x6AF x2 x1 x27B5966 x-43569E0 2023-05-12T16:25:08.732Z,1683908708.732 [DATMMP](INFO): Received message type: directional 2023-05-12T16:25:08.732Z,1683908708.732 [DATMMP](INFO): Handled 2023-05-12T16:25:08.980Z,1683908708.980 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:08.982Z,1683908708.982 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:25:08.982Z,1683908708.982 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:25:08.982Z,1683908708.982 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:25:08.982Z,1683908708.982 [DATMMP](INFO): Handled 2023-05-12T16:25:09.232Z,1683908709.232 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:09.234Z,1683908709.234 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x22 x7B xAE xA8 xD xFFFF x0 x20 x0 x0 2023-05-12T16:25:09.234Z,1683908709.234 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:25:09.235Z,1683908709.235 [DATMMP](INFO): Handled 2023-05-12T16:25:09.484Z,1683908709.484 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:09.485Z,1683908709.485 [DATMMP](INFO): timestamp rx: x0 x10 x19 x7 x1ADF 2023-05-12T16:25:09.485Z,1683908709.485 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:09.486Z,1683908709.486 [DATMMP](INFO): Handled 2023-05-12T16:25:18.687Z,1683908718.687 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-05-12T16:25:18.690Z,1683908718.690 [BPC1](INFO): Received data from all battery sticks. 2023-05-12T16:25:22.856Z,1683908722.856 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:22.857Z,1683908722.857 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:25:22.857Z,1683908722.857 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:25:22.857Z,1683908722.857 [DATMMP](INFO): Handled 2023-05-12T16:25:23.110Z,1683908723.110 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:23.111Z,1683908723.111 [DATMMP](INFO): timestamp rx: x1 x10 x19 x15 x10FE 2023-05-12T16:25:23.112Z,1683908723.112 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908763.503808 2023-05-12T16:25:23.112Z,1683908723.112 [DATMMP](INFO): Rx ping set to:1683908723.110990 2023-05-12T16:25:23.112Z,1683908723.112 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:23.112Z,1683908723.112 [DATMMP](INFO): Handled 2023-05-12T16:25:23.612Z,1683908723.612 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:23.613Z,1683908723.613 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:25:23.613Z,1683908723.613 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:25:23.613Z,1683908723.613 [DATMMP](INFO): Handled 2023-05-12T16:25:23.864Z,1683908723.864 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:23.865Z,1683908723.865 [DATMMP](INFO): directional_debug rx: x192 xFC x22 x1EE x2A60 x4231 x6952 x5933 2023-05-12T16:25:23.867Z,1683908723.867 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:25:23.867Z,1683908723.867 [DATMMP](INFO): Handled 2023-05-12T16:25:24.116Z,1683908724.116 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:24.118Z,1683908724.118 [DATMMP](INFO): directional rx: x1B6 xEC x44A xFF04 xAF9 xFF05 x0 x6B0 x1 xFFFF x27B5966 x-43569E0 2023-05-12T16:25:24.120Z,1683908724.120 [DATMMP](INFO): Received message type: directional 2023-05-12T16:25:24.120Z,1683908724.120 [DATMMP](INFO): Handled 2023-05-12T16:25:24.370Z,1683908724.370 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:24.373Z,1683908724.373 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:25:24.374Z,1683908724.374 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:25:24.374Z,1683908724.374 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:25:24.375Z,1683908724.375 [DATMMP](INFO): Handled 2023-05-12T16:25:24.621Z,1683908724.621 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:24.623Z,1683908724.623 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x22 x79 xAE xA8 xD xFFFF x0 x1F x0 x0 2023-05-12T16:25:24.624Z,1683908724.624 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:25:24.624Z,1683908724.624 [DATMMP](INFO): Handled 2023-05-12T16:25:24.872Z,1683908724.872 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:24.873Z,1683908724.873 [DATMMP](INFO): timestamp rx: x0 x10 x19 x17 x17A 2023-05-12T16:25:24.874Z,1683908724.874 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:24.874Z,1683908724.874 [DATMMP](INFO): Handled 2023-05-12T16:25:38.236Z,1683908738.236 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:38.237Z,1683908738.237 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:25:38.237Z,1683908738.237 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:25:38.237Z,1683908738.237 [DATMMP](INFO): Handled 2023-05-12T16:25:38.488Z,1683908738.488 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:38.489Z,1683908738.489 [DATMMP](INFO): timestamp rx: x1 x10 x19 x24 x209D 2023-05-12T16:25:38.490Z,1683908738.490 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908753.559104 2023-05-12T16:25:38.490Z,1683908738.490 [DATMMP](INFO): Rx ping set to:1683908738.489475 2023-05-12T16:25:38.490Z,1683908738.490 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:38.491Z,1683908738.491 [DATMMP](INFO): Handled 2023-05-12T16:25:38.992Z,1683908738.992 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:38.993Z,1683908738.993 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:25:38.993Z,1683908738.993 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:25:38.993Z,1683908738.993 [DATMMP](INFO): Handled 2023-05-12T16:25:39.245Z,1683908739.245 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:39.246Z,1683908739.246 [DATMMP](INFO): directional_debug rx: x189 xE5 x21 x238 x28F0 x32F1 x59C2 x5033 2023-05-12T16:25:39.247Z,1683908739.247 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:25:39.247Z,1683908739.247 [DATMMP](INFO): Handled 2023-05-12T16:25:39.496Z,1683908739.496 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:39.498Z,1683908739.498 [DATMMP](INFO): directional rx: x1AD xDA x453 xFF1B xB05 xFF1C x0 x6B2 x0 xFFFF x27B5966 x-43569E0 2023-05-12T16:25:39.500Z,1683908739.500 [DATMMP](INFO): Received message type: directional 2023-05-12T16:25:39.500Z,1683908739.500 [DATMMP](INFO): Handled 2023-05-12T16:25:39.748Z,1683908739.748 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:39.750Z,1683908739.750 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:25:39.750Z,1683908739.750 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:25:39.750Z,1683908739.750 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:25:39.750Z,1683908739.750 [DATMMP](INFO): Handled 2023-05-12T16:25:40.000Z,1683908740.000 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:40.002Z,1683908740.002 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x23 x80 xAF xAC xD xFFFF x0 x1F x0 x0 2023-05-12T16:25:40.002Z,1683908740.002 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:25:40.002Z,1683908740.002 [DATMMP](INFO): Handled 2023-05-12T16:25:40.252Z,1683908740.252 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:40.253Z,1683908740.253 [DATMMP](INFO): timestamp rx: x0 x10 x19 x26 x1119 2023-05-12T16:25:40.254Z,1683908740.254 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:40.254Z,1683908740.254 [DATMMP](INFO): Handled 2023-05-12T16:25:53.608Z,1683908753.608 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:53.609Z,1683908753.609 [DATMMP](INFO): doppler rx: x0 2023-05-12T16:25:53.609Z,1683908753.609 [DATMMP](INFO): Received message type: doppler 2023-05-12T16:25:53.609Z,1683908753.609 [DATMMP](INFO): Handled 2023-05-12T16:25:53.860Z,1683908753.860 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:53.861Z,1683908753.861 [DATMMP](INFO): timestamp rx: x1 x10 x19 x34 x738 2023-05-12T16:25:53.862Z,1683908753.862 [DATMMP](INFO): Rx dataTimestamp_ set to:1683908770.049152 2023-05-12T16:25:53.862Z,1683908753.862 [DATMMP](INFO): Rx ping set to:1683908753.861379 2023-05-12T16:25:53.862Z,1683908753.862 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:53.862Z,1683908753.862 [DATMMP](INFO): Handled 2023-05-12T16:25:54.366Z,1683908754.366 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:54.370Z,1683908754.370 [DATMMP](INFO): nav_stat rx: x0 x1 x1 2023-05-12T16:25:54.370Z,1683908754.370 [DATMMP](INFO): Received message type: nav_stat 2023-05-12T16:25:54.370Z,1683908754.370 [DATMMP](INFO): Handled 2023-05-12T16:25:54.627Z,1683908754.627 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:54.629Z,1683908754.629 [DATMMP](INFO): directional_debug rx: x191 xF9 x22 x1D6 x28C0 x34F1 x5B72 x5963 2023-05-12T16:25:54.630Z,1683908754.630 [DATMMP](INFO): Received message type: directional_debug 2023-05-12T16:25:54.630Z,1683908754.630 [DATMMP](INFO): Handled 2023-05-12T16:25:54.876Z,1683908754.876 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:54.878Z,1683908754.878 [DATMMP](INFO): directional rx: x1B5 xEA x44B xFF07 xAFD xFF08 x0 x6B3 x1 xFFFF x27B5966 x-43569E0 2023-05-12T16:25:54.880Z,1683908754.880 [DATMMP](INFO): Received message type: directional 2023-05-12T16:25:54.880Z,1683908754.880 [DATMMP](INFO): Handled 2023-05-12T16:25:55.128Z,1683908755.128 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:55.130Z,1683908755.130 [DATMMP](INFO): remote_header rx: x0 x62 x1 xA1 x1 xA xC x0 2023-05-12T16:25:55.130Z,1683908755.130 [DATMMP](INFO): DATA Src=12, Dst=10 2023-05-12T16:25:55.130Z,1683908755.130 [DATMMP](INFO): Received message type: remote_header 2023-05-12T16:25:55.130Z,1683908755.130 [DATMMP](INFO): Handled 2023-05-12T16:25:55.380Z,1683908755.380 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:55.382Z,1683908755.382 [DATMMP](INFO): acstats rx: xC x0 x0 x2D0 x23 x80 xAD xA8 xD xFFFF x0 x1F x0 x0 2023-05-12T16:25:55.382Z,1683908755.382 [DATMMP](INFO): Received message type: acstats 2023-05-12T16:25:55.382Z,1683908755.382 [DATMMP](INFO): Handled 2023-05-12T16:25:55.632Z,1683908755.632 [DATMMP](INFO): GSXN notify for xid: 0 2023-05-12T16:25:55.633Z,1683908755.633 [DATMMP](INFO): timestamp rx: x0 x10 x19 x35 x1EC4 2023-05-12T16:25:55.633Z,1683908755.633 [DATMMP](INFO): Received message type: timestamp 2023-05-12T16:25:55.634Z,1683908755.634 [DATMMP](INFO): Handled 2023-05-12T16:27:26.391Z,1683908846.391 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-05-12T16:27:26.391Z,1683908846.391 [Default:CheckIn:C.Wait] Stopped 2023-05-12T16:27:26.391Z,1683908846.391 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-05-12T16:27:26.391Z,1683908846.391 [Default:CheckIn:D] Running Loop=1 2023-05-12T16:27:26.774Z,1683908846.774 [Default:CheckIn:D] Stopped 2023-05-12T16:27:26.774Z,1683908846.774 [Default:CheckIn:E] Running Loop=1 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.822152 min 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn:E] Stopped 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn] Stopped 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn](INFO): Running loop #2 2023-05-12T16:27:27.163Z,1683908847.163 [Default:CheckIn] Running Loop=2 2023-05-12T16:27:27.164Z,1683908847.164 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-05-12T16:27:27.164Z,1683908847.164 [Default:CheckIn:Read_GPS] Running Loop=1 2023-05-12T16:32:27.351Z,1683909147.351 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-12T16:27:27.2Z 2023-05-12T16:32:27.352Z,1683909147.352 [Default:CheckIn:Read_GPS] Stopped 2023-05-12T16:32:27.352Z,1683909147.352 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-05-12T16:32:31.778Z,1683909151.778 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-05-12T16:32:34.707Z,1683909154.707 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230512T161739/Courier0007.lzma 2023-05-12T16:32:35.709Z,1683909155.709 [DataOverHttps](INFO): Moved sent file to Logs/20230512T161739/Courier0007.lzma.bak 2023-05-12T16:32:35.710Z,1683909155.710 [DataOverHttps](INFO): SBD MOMSN=18295400 2023-05-12T16:32:51.636Z,1683909171.636 [DataOverHttps](INFO): Sending 473 bytes from file Logs/20230512T161739/Express0008.lzma 2023-05-12T16:32:52.637Z,1683909172.637 [DataOverHttps](INFO): Moved sent file to Logs/20230512T161739/Express0008.lzma.bak 2023-05-12T16:32:52.638Z,1683909172.638 [DataOverHttps](INFO): SBD MOMSN=18295402 2023-05-12T16:33:02.476Z,1683909182.476 [NAL9602](INFO): Not Powering down - fast GPS 2023-05-12T16:38:17.012Z,1683909497.012 [CommandExec](IMPORTANT): got command quit 2023-05-12T16:38:18.016Z,1683909498.016 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2023-05-12T16:38:18.016Z,1683909498.016 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:18.016Z,1683909498.016 [CommandExec](INFO): Uninitializing the command executive. 2023-05-12T16:38:18.016Z,1683909498.016 [CommandExec](INFO): Uninitializing the command scheduler. 2023-05-12T16:38:18.016Z,1683909498.016 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.068Z,1683909498.068 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-05-12T16:38:18.068Z,1683909498.068 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-05-12T16:38:18.069Z,1683909498.069 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.069Z,1683909498.069 [NavChartDb](INFO): Join timeout helper Thread ID is 7084 2023-05-12T16:38:18.204Z,1683909498.204 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:18.204Z,1683909498.204 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.220Z,1683909498.220 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-05-12T16:38:18.220Z,1683909498.220 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.220Z,1683909498.220 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7085 2023-05-12T16:38:18.604Z,1683909498.604 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:18.604Z,1683909498.604 [WetLabsUBAT](INFO): Powering down 2023-05-12T16:38:18.605Z,1683909498.605 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.612Z,1683909498.612 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-05-12T16:38:18.612Z,1683909498.612 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.612Z,1683909498.612 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7086 2023-05-12T16:38:18.844Z,1683909498.844 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:18.844Z,1683909498.844 [WetLabsBB2FL](INFO): Powering down 2023-05-12T16:38:18.845Z,1683909498.845 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.857Z,1683909498.857 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-05-12T16:38:18.857Z,1683909498.857 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:18.857Z,1683909498.857 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7087 2023-05-12T16:38:19.568Z,1683909499.568 [CTD_Seabird](INFO): Powering down 2023-05-12T16:38:19.580Z,1683909499.580 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:19.580Z,1683909499.580 [CTD_Seabird](INFO): Powering down 2023-05-12T16:38:19.592Z,1683909499.592 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:19.592Z,1683909499.592 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-05-12T16:38:19.592Z,1683909499.592 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:19.593Z,1683909499.593 [Radio_Surface](INFO): Join timeout helper Thread ID is 7088 2023-05-12T16:38:19.812Z,1683909499.812 [Radio_Surface](INFO): Powering down 2023-05-12T16:38:19.813Z,1683909499.813 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:19.813Z,1683909499.813 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:19.817Z,1683909499.817 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-05-12T16:38:19.817Z,1683909499.817 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:19.817Z,1683909499.817 [Onboard](INFO): Join timeout helper Thread ID is 7089 2023-05-12T16:38:21.404Z,1683909501.404 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:21.404Z,1683909501.404 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:21.408Z,1683909501.408 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-05-12T16:38:21.409Z,1683909501.409 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:21.409Z,1683909501.409 [DataOverHttps](INFO): Join timeout helper Thread ID is 7090 2023-05-12T16:38:23.936Z,1683909503.936 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:23.939Z,1683909503.939 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:23.952Z,1683909503.952 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2023-05-12T16:38:23.952Z,1683909503.952 [DATMMP ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:23.953Z,1683909503.953 [DATMMP](INFO): Join timeout helper Thread ID is 7091 2023-05-12T16:38:24.128Z,1683909504.128 [DATMMP](INFO): Stop 2023-05-12T16:38:24.128Z,1683909504.128 [DATMMP](INFO): uninitialize 2023-05-12T16:38:24.128Z,1683909504.128 [DATMMP](INFO): Powering down 2023-05-12T16:38:24.200Z,1683909504.200 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:24.200Z,1683909504.200 [DATMMP](INFO): uninitialize 2023-05-12T16:38:24.201Z,1683909504.201 [DATMMP](INFO): Powering down 2023-05-12T16:38:24.201Z,1683909504.201 [DATMMP ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.204Z,1683909504.204 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-05-12T16:38:24.205Z,1683909504.205 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.205Z,1683909504.205 [BackseatComponent](INFO): Join timeout helper Thread ID is 7092 2023-05-12T16:38:24.220Z,1683909504.220 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:24.220Z,1683909504.220 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.225Z,1683909504.225 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-05-12T16:38:24.225Z,1683909504.225 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.225Z,1683909504.225 [logger](INFO): Join timeout helper Thread ID is 7093 2023-05-12T16:38:24.240Z,1683909504.240 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:24.240Z,1683909504.240 [logger ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.244Z,1683909504.244 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-05-12T16:38:24.244Z,1683909504.244 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.245Z,1683909504.245 [CommandLine](INFO): Join timeout helper Thread ID is 7094 2023-05-12T16:38:24.284Z,1683909504.284 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:24.284Z,1683909504.284 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.304Z,1683909504.304 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-05-12T16:38:24.304Z,1683909504.304 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.305Z,1683909504.305 [CommandExec](INFO): Join timeout helper Thread ID is 7095 2023-05-12T16:38:24.306Z,1683909504.306 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-05-12T16:38:24.306Z,1683909504.306 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.306Z,1683909504.306 [controlThread](INFO): Join timeout helper Thread ID is 7096 2023-05-12T16:38:24.508Z,1683909504.508 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-05-12T16:38:24.508Z,1683909504.508 [controlThread](DEBUG): Uninitializing ControlThread 2023-05-12T16:38:24.508Z,1683909504.508 [AHRS_M2](INFO): Powering down 2023-05-12T16:38:24.580Z,1683909504.580 [NAL9602](INFO): Powering down 2023-05-12T16:38:24.652Z,1683909504.652 [RDI_Pathfinder](INFO): Powering down 2023-05-12T16:38:24.654Z,1683909504.654 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-05-12T16:38:24.654Z,1683909504.654 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-05-12T16:38:24.655Z,1683909504.655 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-05-12T16:38:24.655Z,1683909504.655 [MissionManager](INFO): Uninitializing Mission Default 2023-05-12T16:38:24.656Z,1683909504.656 [Default] Stopped 2023-05-12T16:38:24.656Z,1683909504.656 [Default](DEBUG): Aggregate::uninitialize Default 2023-05-12T16:38:24.656Z,1683909504.656 [Default:B.GoToSurface] Stopped 2023-05-12T16:38:24.656Z,1683909504.656 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-05-12T16:38:24.656Z,1683909504.656 [Default:CheckIn] Stopped 2023-05-12T16:38:24.656Z,1683909504.656 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-05-12T16:38:24.656Z,1683909504.656 [Default:CheckIn:Read_Iridium] Stopped 2023-05-12T16:38:24.659Z,1683909504.659 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-05-12T16:38:24.659Z,1683909504.659 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-05-12T16:38:24.659Z,1683909504.659 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-05-12T16:38:24.660Z,1683909504.660 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-05-12T16:38:24.660Z,1683909504.660 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-05-12T16:38:24.660Z,1683909504.660 [BuoyancyServo](INFO): Powering down 2023-05-12T16:38:24.672Z,1683909504.672 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-05-12T16:38:24.672Z,1683909504.672 [ElevatorServo](INFO): Powering down 2023-05-12T16:38:24.673Z,1683909504.673 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-05-12T16:38:24.673Z,1683909504.673 [MassServo](INFO): Powering down 2023-05-12T16:38:24.674Z,1683909504.674 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-05-12T16:38:24.674Z,1683909504.674 [RudderServo](INFO): Powering down 2023-05-12T16:38:24.675Z,1683909504.675 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-05-12T16:38:24.675Z,1683909504.675 [ThrusterServo](INFO): Powering down 2023-05-12T16:38:24.676Z,1683909504.676 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-05-12T16:38:24.676Z,1683909504.676 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-05-12T16:38:24.676Z,1683909504.676 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-05-12T16:38:24.676Z,1683909504.676 [CBIT](DEBUG): Powering off loads. 2023-05-12T16:38:24.687Z,1683909504.687 [CBIT](DEBUG): Disabling WDT. 2023-05-12T16:38:24.699Z,1683909504.699 [CBIT](DEBUG): Opening all GF detection circuits. 2023-05-12T16:38:24.701Z,1683909504.701 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.749Z,1683909504.749 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.752Z,1683909504.752 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.757Z,1683909504.757 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.764Z,1683909504.764 [DATMMP ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.805Z,1683909504.805 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.864Z,1683909504.864 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.866Z,1683909504.866 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.869Z,1683909504.869 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.878Z,1683909504.878 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-05-12T16:38:24.940Z,1683909504.940 [logger ThreadHandler](INFO): Thread cancelled.