2023-02-22T18:48:48.538Z,1677091728.538 [Supervisor](DEBUG): Initializing supervisor. 2023-02-22T18:48:48.546Z,1677091728.546 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-02-22T18:48:48.546Z,1677091728.546 [SyncHandler](INFO): Protected caller Thread ID is 7441 2023-02-22T18:48:48.547Z,1677091728.547 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-02-22T18:48:48.548Z,1677091728.548 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-02-22T18:48:48.548Z,1677091728.548 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7442 2023-02-22T18:48:48.553Z,1677091728.553 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-02-22T18:48:48.570Z,1677091728.570 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-02-22T18:48:48.571Z,1677091728.571 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-02-22T18:48:48.571Z,1677091728.571 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7443 2023-02-22T18:48:48.575Z,1677091728.575 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-02-22T18:48:48.576Z,1677091728.576 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-02-22T18:48:48.577Z,1677091728.577 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7444 2023-02-22T18:48:48.579Z,1677091728.579 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-02-22T18:48:48.580Z,1677091728.580 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-02-22T18:48:48.580Z,1677091728.580 [logger ThreadHandler](INFO): Protected caller Thread ID is 7445 2023-02-22T18:48:48.584Z,1677091728.584 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-02-22T18:48:48.584Z,1677091728.584 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-02-22T18:48:48.586Z,1677091728.586 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-02-22T18:48:49.187Z,1677091729.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-02-22T18:48:49.187Z,1677091729.187 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-02-22T18:48:49.393Z,1677091729.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-02-22T18:48:49.394Z,1677091729.394 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-02-22T18:48:49.475Z,1677091729.475 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-02-22T18:48:49.685Z,1677091729.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-02-22T18:48:49.686Z,1677091729.686 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-02-22T18:48:49.764Z,1677091729.764 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-02-22T18:48:49.859Z,1677091729.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-02-22T18:48:49.860Z,1677091729.860 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-02-22T18:48:50.185Z,1677091730.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-02-22T18:48:50.186Z,1677091730.186 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-02-22T18:48:50.315Z,1677091730.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-02-22T18:48:50.315Z,1677091730.315 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-02-22T18:48:50.793Z,1677091730.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-02-22T18:48:50.794Z,1677091730.794 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-02-22T18:48:50.972Z,1677091730.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-02-22T18:48:50.973Z,1677091730.973 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-02-22T18:48:51.357Z,1677091731.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-02-22T18:48:51.358Z,1677091731.358 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-02-22T18:48:51.632Z,1677091731.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-02-22T18:48:51.632Z,1677091731.632 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-02-22T18:48:51.742Z,1677091731.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-02-22T18:48:51.743Z,1677091731.743 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-02-22T18:48:52.494Z,1677091732.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-02-22T18:48:52.495Z,1677091732.495 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-02-22T18:48:53.625Z,1677091733.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-02-22T18:48:53.625Z,1677091733.625 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-02-22T18:48:53.840Z,1677091733.840 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-02-22T18:48:53.842Z,1677091733.842 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2023-02-22T18:48:53.843Z,1677091733.843 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2023-02-22T18:48:54.023Z,1677091734.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2023-02-22T18:48:54.105Z,1677091734.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2023-02-22T18:48:54.235Z,1677091734.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2023-02-22T18:48:54.318Z,1677091734.318 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2023-02-22T18:48:54.584Z,1677091734.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-02-22T18:48:54.584Z,1677091734.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2023-02-22T18:48:54.685Z,1677091734.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2023-02-22T18:48:54.793Z,1677091734.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2023-02-22T18:48:54.900Z,1677091734.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2023-02-22T18:48:55.037Z,1677091735.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2023-02-22T18:48:55.133Z,1677091735.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2023-02-22T18:48:55.224Z,1677091735.224 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2023-02-22T18:48:55.225Z,1677091735.225 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-02-22T18:48:55.241Z,1677091735.241 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-02-22T18:48:55.257Z,1677091735.257 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-02-22T18:48:55.258Z,1677091735.258 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-02-22T18:48:55.394Z,1677091735.394 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-02-22T18:48:55.395Z,1677091735.395 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-02-22T18:48:55.468Z,1677091735.468 [VerticalControl](DEBUG): Construct VerticalControl. 2023-02-22T18:48:55.527Z,1677091735.527 [VerticalControl] Loaded 2023-02-22T18:48:55.527Z,1677091735.527 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-02-22T18:48:55.530Z,1677091735.530 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-02-22T18:48:55.570Z,1677091735.570 [HorizontalControl] Loaded 2023-02-22T18:48:55.570Z,1677091735.570 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-02-22T18:48:55.573Z,1677091735.573 [SpeedControl](DEBUG): Construct SpeedControl. 2023-02-22T18:48:55.576Z,1677091735.576 [SpeedControl] Loaded 2023-02-22T18:48:55.576Z,1677091735.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-02-22T18:48:55.579Z,1677091735.579 [LoopControl](DEBUG): Construct LoopControl. 2023-02-22T18:48:55.579Z,1677091735.579 [LoopControl] Loaded 2023-02-22T18:48:55.579Z,1677091735.579 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-02-22T18:48:55.580Z,1677091735.580 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-02-22T18:48:55.581Z,1677091735.581 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-02-22T18:48:55.754Z,1677091735.754 [BuoyancyServo] Loaded 2023-02-22T18:48:55.754Z,1677091735.754 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-02-22T18:48:55.774Z,1677091735.774 [ElevatorServo] Loaded 2023-02-22T18:48:55.775Z,1677091735.775 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-02-22T18:48:55.794Z,1677091735.794 [MassServo] Loaded 2023-02-22T18:48:55.794Z,1677091735.794 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-02-22T18:48:55.813Z,1677091735.813 [RudderServo] Loaded 2023-02-22T18:48:55.813Z,1677091735.813 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-02-22T18:48:55.828Z,1677091735.828 [ThrusterHE] Loaded 2023-02-22T18:48:55.828Z,1677091735.828 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-02-22T18:48:55.828Z,1677091735.828 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-02-22T18:48:55.829Z,1677091735.829 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-02-22T18:48:55.903Z,1677091735.903 [DepthRateCalculator] Loaded 2023-02-22T18:48:55.903Z,1677091735.903 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-02-22T18:48:55.908Z,1677091735.908 [PitchRateCalculator] Loaded 2023-02-22T18:48:55.908Z,1677091735.908 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-02-22T18:48:55.918Z,1677091735.918 [SpeedCalculator] Loaded 2023-02-22T18:48:55.918Z,1677091735.918 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-02-22T18:48:55.922Z,1677091735.922 [YawRateCalculator] Loaded 2023-02-22T18:48:55.923Z,1677091735.923 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-02-22T18:48:55.941Z,1677091735.941 [ElevatorOffsetCalculator] Loaded 2023-02-22T18:48:55.941Z,1677091735.941 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-02-22T18:48:55.941Z,1677091735.941 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-02-22T18:48:55.942Z,1677091735.942 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-02-22T18:48:56.089Z,1677091736.089 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-02-22T18:48:56.089Z,1677091736.089 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-02-22T18:48:56.103Z,1677091736.103 [NavChart] Loaded 2023-02-22T18:48:56.103Z,1677091736.103 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-02-22T18:48:56.109Z,1677091736.109 [UniversalFixResidualReporter] Loaded 2023-02-22T18:48:56.109Z,1677091736.109 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-02-22T18:48:56.110Z,1677091736.110 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-02-22T18:48:56.110Z,1677091736.110 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-02-22T18:48:56.852Z,1677091736.852 [AHRS_M2] Loaded 2023-02-22T18:48:56.852Z,1677091736.852 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-02-22T18:48:57.092Z,1677091737.092 [BackseatComponent] Loaded 2023-02-22T18:48:57.092Z,1677091737.092 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-02-22T18:48:57.096Z,1677091737.096 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0 2023-02-22T18:48:57.097Z,1677091737.097 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7528 2023-02-22T18:48:57.100Z,1677091737.100 [LcmUniversalReporter] Loaded 2023-02-22T18:48:57.100Z,1677091737.100 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-02-22T18:48:58.457Z,1677091738.457 [BPC1] Loaded 2023-02-22T18:48:58.457Z,1677091738.457 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-02-22T18:48:58.550Z,1677091738.550 [DataOverHttps] Loaded 2023-02-22T18:48:58.551Z,1677091738.551 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-02-22T18:48:58.555Z,1677091738.555 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409104E0 2023-02-22T18:48:58.555Z,1677091738.555 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7529 2023-02-22T18:48:58.577Z,1677091738.577 [Depth_Keller] Loaded 2023-02-22T18:48:58.577Z,1677091738.577 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-02-22T18:48:58.582Z,1677091738.582 [DropWeight] Loaded 2023-02-22T18:48:58.582Z,1677091738.582 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-02-22T18:48:58.601Z,1677091738.601 [MultiRay] Loaded 2023-02-22T18:48:58.601Z,1677091738.601 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2023-02-22T18:48:58.664Z,1677091738.664 [NAL9602] Loaded 2023-02-22T18:48:58.664Z,1677091738.664 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-02-22T18:48:58.695Z,1677091738.695 [Onboard] Loaded 2023-02-22T18:48:58.695Z,1677091738.695 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-02-22T18:48:58.696Z,1677091738.696 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409404E0 2023-02-22T18:48:58.697Z,1677091738.697 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7530 2023-02-22T18:48:58.709Z,1677091738.709 [Power24vConverter] Loaded 2023-02-22T18:48:58.709Z,1677091738.709 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-02-22T18:48:58.723Z,1677091738.723 [Radio_Surface] Loaded 2023-02-22T18:48:58.723Z,1677091738.723 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-02-22T18:48:58.724Z,1677091738.724 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409704E0 2023-02-22T18:48:58.725Z,1677091738.725 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7531 2023-02-22T18:48:58.818Z,1677091738.818 [DAT] Loaded 2023-02-22T18:48:58.818Z,1677091738.818 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-02-22T18:48:58.819Z,1677091738.819 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0 2023-02-22T18:48:58.820Z,1677091738.820 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7532 2023-02-22T18:48:58.820Z,1677091738.820 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-02-22T18:48:58.821Z,1677091738.821 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-02-22T18:48:58.909Z,1677091738.909 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-02-22T18:48:58.910Z,1677091738.910 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-02-22T18:48:59.114Z,1677091739.114 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-02-22T18:48:59.115Z,1677091739.115 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-02-22T18:48:59.161Z,1677091739.161 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-02-22T18:48:59.162Z,1677091739.162 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-02-22T18:48:59.388Z,1677091739.388 [CTD_Seabird] Loaded 2023-02-22T18:48:59.388Z,1677091739.388 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-02-22T18:48:59.395Z,1677091739.395 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0 2023-02-22T18:48:59.395Z,1677091739.395 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7533 2023-02-22T18:48:59.429Z,1677091739.429 [ESPComponent] Loaded 2023-02-22T18:48:59.429Z,1677091739.429 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-02-22T18:48:59.449Z,1677091739.449 [PAR_Licor] Loaded 2023-02-22T18:48:59.449Z,1677091739.449 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-02-22T18:48:59.480Z,1677091739.480 [WetLabsBB2FL] Loaded 2023-02-22T18:48:59.481Z,1677091739.481 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-02-22T18:48:59.482Z,1677091739.482 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0 2023-02-22T18:48:59.482Z,1677091739.482 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7534 2023-02-22T18:48:59.483Z,1677091739.483 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-02-22T18:48:59.484Z,1677091739.484 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-02-22T18:48:59.880Z,1677091739.880 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-02-22T18:48:59.882Z,1677091739.882 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-02-22T18:49:00.066Z,1677091740.066 [SBIT](DEBUG): Construct Startup Built In Test. 2023-02-22T18:49:00.076Z,1677091740.076 [SBIT] Loaded 2023-02-22T18:49:00.076Z,1677091740.076 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-02-22T18:49:00.079Z,1677091740.079 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-02-22T18:49:00.091Z,1677091740.091 [IBIT] Loaded 2023-02-22T18:49:00.092Z,1677091740.092 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-02-22T18:49:00.098Z,1677091740.098 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-02-22T18:49:00.202Z,1677091740.202 [CBIT] Loaded 2023-02-22T18:49:00.202Z,1677091740.202 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-02-22T18:49:00.203Z,1677091740.203 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-02-22T18:49:00.209Z,1677091740.209 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-02-22T18:49:00.212Z,1677091740.212 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-02-22T18:49:00.222Z,1677091740.222 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-02-22T18:49:00.231Z,1677091740.231 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0 2023-02-22T18:49:00.231Z,1677091740.231 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7535 2023-02-22T18:49:00.236Z,1677091740.236 [Supervisor](INFO): Main Thread ID is 7440 2023-02-22T18:49:00.236Z,1677091740.236 [Supervisor](DEBUG): Running supervisor. 2023-02-22T18:49:00.236Z,1677091740.236 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7536 2023-02-22T18:49:00.237Z,1677091740.237 [CommandExec](INFO): Initializing the command executive. 2023-02-22T18:49:00.238Z,1677091740.238 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7537 2023-02-22T18:49:00.241Z,1677091740.241 [controlThread ThreadHandler](INFO): Handler Thread ID is 7538 2023-02-22T18:49:00.241Z,1677091740.241 [controlThread](DEBUG): Initializing ControlThread 2023-02-22T18:49:00.242Z,1677091740.242 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-02-22T18:49:00.244Z,1677091740.244 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-02-22T18:49:00.244Z,1677091740.244 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-02-22T18:49:00.245Z,1677091740.245 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-02-22T18:49:00.246Z,1677091740.246 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-02-22T18:49:00.247Z,1677091740.247 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-02-22T18:49:00.247Z,1677091740.247 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-02-22T18:49:00.247Z,1677091740.247 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-02-22T18:49:00.248Z,1677091740.248 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-02-22T18:49:00.249Z,1677091740.249 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-02-22T18:49:00.249Z,1677091740.249 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-02-22T18:49:00.257Z,1677091740.257 [SBIT](INFO): Initialize SBIT Component. 2023-02-22T18:49:00.257Z,1677091740.257 [SBIT](IMPORTANT): git: 2023-02-10 2023-02-22T18:49:00.257Z,1677091740.257 [SBIT](INFO): git hash: c406eb0a7e3d06ad2f4805df3abb847da8df30bc 2023-02-22T18:49:00.258Z,1677091740.258 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-02-22T18:49:00.259Z,1677091740.259 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-02-22T18:49:00.260Z,1677091740.260 [SBIT](INFO): Beginning SBIT in 81.000000 seconds. 2023-02-22T18:49:00.261Z,1677091740.261 [IBIT](INFO): Initialize IBIT Component. 2023-02-22T18:49:00.261Z,1677091740.261 [CBIT](DEBUG): Initialize CBIT Component. 2023-02-22T18:49:00.262Z,1677091740.262 [logger ThreadHandler](INFO): Handler Thread ID is 7539 2023-02-22T18:49:00.272Z,1677091740.272 [CBIT](DEBUG): Initialized mux pins. 2023-02-22T18:49:00.272Z,1677091740.272 [CBIT](DEBUG): Initializing the watchdog timer. 2023-02-22T18:49:00.281Z,1677091740.281 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7540 2023-02-22T18:49:00.293Z,1677091740.293 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7541 2023-02-22T18:49:00.294Z,1677091740.294 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-02-22T18:49:00.297Z,1677091740.297 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-02-22T18:49:00.297Z,1677091740.297 [CBIT](DEBUG): Initializing heartbeat. 2023-02-22T18:49:00.305Z,1677091740.305 [Onboard ThreadHandler](INFO): Handler Thread ID is 7542 2023-02-22T18:49:00.322Z,1677091740.322 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7543 2023-02-22T18:49:00.341Z,1677091740.341 [DAT ThreadHandler](INFO): Handler Thread ID is 7544 2023-02-22T18:49:00.342Z,1677091740.342 [DAT](INFO): Powering up 2023-02-22T18:49:00.342Z,1677091740.342 [DAT](DEBUG): Initializing DAT. 2023-02-22T18:49:00.346Z,1677091740.346 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7546 2023-02-22T18:49:00.346Z,1677091740.346 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-02-22T18:49:00.350Z,1677091740.350 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7548 2023-02-22T18:49:00.353Z,1677091740.353 [WetLabsBB2FL](INFO): Powering up 2023-02-22T18:49:00.354Z,1677091740.354 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7550 2023-02-22T18:49:00.362Z,1677091740.362 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-02-22T18:49:00.363Z,1677091740.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-02-22T18:49:00.368Z,1677091740.368 [CBIT](DEBUG): Deactivating GF circuits. 2023-02-22T18:49:00.368Z,1677091740.368 [CBIT](DEBUG): Deactivating emergency mode. 2023-02-22T18:49:00.404Z,1677091740.404 [CBIT](DEBUG): Backplane powered. 2023-02-22T18:49:00.405Z,1677091740.405 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-02-22T18:49:00.441Z,1677091740.441 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-22T18:49:00.452Z,1677091740.452 [MissionManager](DEBUG): 2023-02-22T18:49:00.453Z,1677091740.453 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-02-22T18:49:00.529Z,1677091740.529 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-02-22T18:49:00.530Z,1677091740.530 [Default:A.Wait](DEBUG): Construct Wait. 2023-02-22T18:49:00.556Z,1677091740.556 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-22T18:49:00.594Z,1677091740.594 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-02-22T18:49:00.609Z,1677091740.609 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-02-22T18:49:00.614Z,1677091740.614 [Default:E.Execute](DEBUG): Construct Execute. 2023-02-22T18:49:00.634Z,1677091740.634 [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-02-22T18:49:00.639Z,1677091740.639 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-02-22T18:49:00.661Z,1677091740.661 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-02-22T18:49:00.728Z,1677091740.728 [Radio_Surface](INFO): Powering up 2023-02-22T18:49:00.731Z,1677091740.731 [MultiRay](INFO): Powering up MultiRay Lights 2023-02-22T18:49:00.765Z,1677091740.765 [Power24vConverter](INFO): Powering up. 2023-02-22T18:49:00.818Z,1677091740.818 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-02-22T18:49:00.825Z,1677091740.825 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-02-22T18:49:00.826Z,1677091740.826 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-22T18:49:00.833Z,1677091740.833 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-22T18:49:00.834Z,1677091740.834 [MassServo](DEBUG): Initializing EZServoServo. 2023-02-22T18:49:00.841Z,1677091740.841 [MassServo](DEBUG): Initializing MassServo. 2023-02-22T18:49:00.842Z,1677091740.842 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-22T18:49:00.849Z,1677091740.849 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-22T18:49:00.850Z,1677091740.850 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-02-22T18:49:00.857Z,1677091740.857 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-02-22T18:49:01.784Z,1677091741.784 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2023-02-22T18:49:01.785Z,1677091741.785 [RudderServo](FAULT): Rudder failed to initialize 2023-02-22T18:49:01.785Z,1677091741.785 [RudderServo] Communications Fault, FailCount= 1 2023-02-22T18:49:01.785Z,1677091741.785 [RudderServo](ERROR): Communications Fault 2023-02-22T18:49:01.789Z,1677091741.789 [CBIT](ERROR): Communications Fault in component: RudderServo 2023-02-22T18:49:02.265Z,1677091742.265 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-22T18:49:02.265Z,1677091742.265 [RudderServo](INFO): Powering down 2023-02-22T18:49:02.461Z,1677091742.461 [WetLabsBB2FL](INFO): Powering down 2023-02-22T18:49:02.729Z,1677091742.729 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-22T18:49:02.849Z,1677091742.849 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-22T18:49:02.853Z,1677091742.853 [CBIT](INFO): Clearing failed state for component RudderServo 2023-02-22T18:49:02.853Z,1677091742.853 [RudderServo] No Fault, FailCount= 1 2023-02-22T18:49:06.490Z,1677091746.490 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-02-22T18:49:14.159Z,1677091754.159 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004895 2023-02-22T18:49:15.211Z,1677091755.211 [DAT](INFO): commRate: 800 2023-02-22T18:49:17.276Z,1677091757.276 [DAT](INFO): entering command mode 2023-02-22T18:49:17.478Z,1677091757.478 [DAT](INFO): setting verbose to 3 2023-02-22T18:49:17.730Z,1677091757.730 [DAT](INFO): set verbose to 3 2023-02-22T18:49:17.731Z,1677091757.731 [DAT](INFO): setting DatVerbose to 27440 2023-02-22T18:49:17.982Z,1677091757.982 [DAT](INFO): set DatVerbose to 27440 2023-02-22T18:49:17.982Z,1677091757.982 [DAT](INFO): setting transmit power to 8 2023-02-22T18:49:18.234Z,1677091758.234 [DAT](INFO): set transmit power to 8 2023-02-22T18:49:18.234Z,1677091758.234 [DAT](INFO): setting local address to 5 2023-02-22T18:49:18.497Z,1677091758.497 [DAT](INFO): set local address to 5 2023-02-22T18:49:18.498Z,1677091758.498 [DAT](INFO): Setting time to: 18:49:18 And date to:2/22/2023 2023-02-22T18:49:18.738Z,1677091758.738 [DAT](INFO): Local DAT time set to Wed Feb 22, 2023 18:49:18 2023-02-22T18:49:28.994Z,1677091768.994 [NAL9602](INFO): Powering up NAL9602 2023-02-22T18:49:33.670Z,1677091773.670 [CommandExec](IMPORTANT): got command show variable sigQual 2023-02-22T18:49:33.984Z,1677091773.984 [CommandExec](IMPORTANT): NAL9602.sigQuality (count) 2023-02-22T18:49:39.899Z,1677091779.899 [NAL9602](INFO): NAL9602 initialized 2023-02-22T18:49:43.755Z,1677091783.755 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2023-02-22T18:49:43.983Z,1677091783.983 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:49:48.419Z,1677091788.419 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:49:49.242Z,1677091789.242 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:49:53.691Z,1677091793.691 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:49:54.499Z,1677091794.499 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:49:55.580Z,1677091795.580 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree 2023-02-22T18:49:55.583Z,1677091795.583 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread. 2023-02-22T18:49:55.584Z,1677091795.584 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.0 degree 2023-02-22T18:49:55.587Z,1677091795.587 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread. 2023-02-22T18:49:55.690Z,1677091795.690 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-02-22T18:49:58.938Z,1677091798.938 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:49:59.738Z,1677091799.738 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:04.192Z,1677091804.192 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:05.003Z,1677091805.003 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:05.801Z,1677091805.801 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1677091798 second 2023-02-22T18:50:09.450Z,1677091809.450 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:10.242Z,1677091810.242 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:14.723Z,1677091814.723 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:15.514Z,1677091815.514 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:19.330Z,1677091819.330 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadB6 2023-02-22T18:50:19.939Z,1677091819.939 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:20.743Z,1677091820.743 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:21.979Z,1677091821.979 [SBIT](IMPORTANT): Beginning Startup BIT 2023-02-22T18:50:21.987Z,1677091821.987 [CBIT](IMPORTANT): Beginning ground fault scan 2023-02-22T18:50:25.314Z,1677091825.314 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:26.145Z,1677091826.145 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:30.491Z,1677091830.491 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:31.365Z,1677091831.365 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:32.851Z,1677091832.851 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003279 CHAN A1 (24V): -0.008431 CHAN A2 (12V): -0.005157 CHAN A3 (5V): -0.003392 CHAN B0 (3.3V): -0.000948 CHAN B1 (3.15aV): -0.001096 CHAN B2 (3.15bV): -0.001471 CHAN B3 (GND): -0.000007 OPEN: 0.004834 Full Scale: +/- 1 mA 2023-02-22T18:50:35.729Z,1677091835.729 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:36.498Z,1677091836.498 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:41.054Z,1677091841.054 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:41.854Z,1677091841.854 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:46.230Z,1677091846.230 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:47.044Z,1677091847.044 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:51.447Z,1677091851.447 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:52.257Z,1677091852.257 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:56.696Z,1677091856.696 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:50:57.513Z,1677091857.513 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:02.042Z,1677091862.042 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:02.790Z,1677091862.790 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:07.246Z,1677091867.246 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:08.058Z,1677091868.058 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:12.456Z,1677091872.456 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:13.286Z,1677091873.286 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:15.736Z,1677091875.736 [SBIT](IMPORTANT): SBIT PASSED 2023-02-22T18:51:15.736Z,1677091875.736 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-02-22T18:51:15.737Z,1677091875.737 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2023-02-22T18:51:15.737Z,1677091875.737 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool; 2023-02-22T18:51:15.737Z,1677091875.737 [SBIT](IMPORTANT): DAT.verbosity=1 count; 2023-02-22T18:51:15.737Z,1677091875.737 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2023-02-22T18:51:15.737Z,1677091875.737 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree; 2023-02-22T18:51:15.738Z,1677091875.738 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter; 2023-02-22T18:51:16.102Z,1677091876.102 [MissionManager](IMPORTANT): Started mission Startup 2023-02-22T18:51:16.102Z,1677091876.102 [Startup] Running Loop=1 2023-02-22T18:51:16.102Z,1677091876.102 [Startup](DEBUG): Aggregate::initialize Startup 2023-02-22T18:51:16.102Z,1677091876.102 [Startup:A.GoToSurface] Running Loop=1 2023-02-22T18:51:16.102Z,1677091876.102 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-22T18:51:16.103Z,1677091876.103 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-22T18:51:16.103Z,1677091876.103 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-22T18:51:16.104Z,1677091876.104 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-22T18:51:16.109Z,1677091876.109 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-22T18:51:16.109Z,1677091876.109 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-22T18:51:16.110Z,1677091876.110 [Startup:StartupSatComms] Running Loop=1 2023-02-22T18:51:16.110Z,1677091876.110 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-02-22T18:51:16.111Z,1677091876.111 [Startup:StartupSatComms:A] Running Loop=1 2023-02-22T18:51:16.486Z,1677091876.486 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-02-22T18:51:16.486Z,1677091876.486 [Startup:StartupSatComms:A] Stopped 2023-02-22T18:51:16.486Z,1677091876.486 [Startup:StartupSatComms:B] Running Loop=1 2023-02-22T18:51:16.888Z,1677091876.888 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-22T18:51:17.532Z,1677091877.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:51:17.723Z,1677091877.723 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:18.516Z,1677091878.516 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:19.237Z,1677091879.237 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251728 2023-02-22T18:51:22.974Z,1677091882.974 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:23.757Z,1677091883.757 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:28.243Z,1677091888.243 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:29.119Z,1677091889.119 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:29.400Z,1677091889.400 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:51:33.463Z,1677091893.463 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:34.275Z,1677091894.275 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:38.716Z,1677091898.716 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:39.539Z,1677091899.539 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:42.315Z,1677091902.315 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:51:43.964Z,1677091903.964 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:44.794Z,1677091904.794 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:49.223Z,1677091909.223 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:50.021Z,1677091910.021 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:50.369Z,1677091910.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:51:54.478Z,1677091914.478 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:55.408Z,1677091915.408 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:51:55.662Z,1677091915.662 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:51:59.734Z,1677091919.734 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:00.568Z,1677091920.568 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:04.986Z,1677091924.986 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:05.790Z,1677091925.790 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:09.395Z,1677091929.395 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:52:10.262Z,1677091930.262 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:11.024Z,1677091931.024 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:15.530Z,1677091935.530 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:16.298Z,1677091936.298 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:16.697Z,1677091936.697 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-22T18:51:16.5Z 2023-02-22T18:52:16.697Z,1677091936.697 [Startup:StartupSatComms:B] Stopped 2023-02-22T18:52:16.697Z,1677091936.697 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-02-22T18:52:16.697Z,1677091936.697 [Startup:StartupSatComms] Stopped 2023-02-22T18:52:16.697Z,1677091936.697 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-02-22T18:52:16.698Z,1677091936.698 [Startup](INFO): Completed Startup 2023-02-22T18:52:16.698Z,1677091936.698 [MissionManager](INFO): Startup is completed. 2023-02-22T18:52:16.698Z,1677091936.698 [MissionManager](INFO): Uninitializing Mission Startup 2023-02-22T18:52:16.698Z,1677091936.698 [Startup] Stopped 2023-02-22T18:52:16.699Z,1677091936.699 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-02-22T18:52:16.699Z,1677091936.699 [Startup:A.GoToSurface] Stopped 2023-02-22T18:52:16.699Z,1677091936.699 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-22T18:52:17.071Z,1677091937.071 [MissionManager](IMPORTANT): Started mission Default 2023-02-22T18:52:17.072Z,1677091937.072 [Default] Running Loop=1 2023-02-22T18:52:17.072Z,1677091937.072 [Default](DEBUG): Aggregate::initialize Default 2023-02-22T18:52:17.072Z,1677091937.072 [Default:B.GoToSurface] Running Loop=1 2023-02-22T18:52:17.072Z,1677091937.072 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-22T18:52:17.072Z,1677091937.072 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-22T18:52:17.073Z,1677091937.073 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-22T18:52:17.073Z,1677091937.073 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-22T18:52:17.073Z,1677091937.073 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-22T18:52:17.074Z,1677091937.074 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-22T18:52:17.074Z,1677091937.074 [Default:A.Wait] Running Loop=1 2023-02-22T18:52:17.074Z,1677091937.074 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-02-22T18:52:20.739Z,1677091940.739 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:21.551Z,1677091941.551 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:22.716Z,1677091942.716 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:52:25.517Z,1677091945.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:52:25.992Z,1677091945.992 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:26.818Z,1677091946.818 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:30.406Z,1677091950.406 [Default:A.Wait](INFO): Done Waiting. 2023-02-22T18:52:30.406Z,1677091950.406 [Default:A.Wait] Stopped 2023-02-22T18:52:30.406Z,1677091950.406 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-02-22T18:52:30.821Z,1677091950.821 [Default:CheckIn] Running Loop=1 2023-02-22T18:52:30.821Z,1677091950.821 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-22T18:52:30.821Z,1677091950.821 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-22T18:52:31.236Z,1677091951.236 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-02-22T18:52:31.247Z,1677091951.247 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:32.032Z,1677091952.032 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:36.167Z,1677091956.167 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:36.459Z,1677091956.459 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:52:36.927Z,1677091956.927 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:41.349Z,1677091961.349 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:42.151Z,1677091962.151 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:46.587Z,1677091966.587 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:47.399Z,1677091967.399 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:50.194Z,1677091970.194 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:52:51.866Z,1677091971.866 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:52.656Z,1677091972.656 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:57.091Z,1677091977.091 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:52:57.907Z,1677091977.907 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:00.657Z,1677091980.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:53:02.333Z,1677091982.333 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:03.176Z,1677091983.176 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:03.930Z,1677091983.930 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:53:07.599Z,1677091987.599 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:08.419Z,1677091988.419 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:12.852Z,1677091992.852 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:13.683Z,1677091993.683 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:16.859Z,1677091996.859 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:53:17.730Z,1677091997.730 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:18.514Z,1677091998.514 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:22.941Z,1677092002.941 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:23.776Z,1677092003.776 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:28.204Z,1677092008.204 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:29.027Z,1677092009.027 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:30.589Z,1677092010.589 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:53:33.449Z,1677092013.449 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:34.284Z,1677092014.284 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:35.797Z,1677092015.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:53:38.710Z,1677092018.710 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:39.515Z,1677092019.515 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:44.040Z,1677092024.040 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:44.330Z,1677092024.330 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-22T18:53:44.798Z,1677092024.798 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:49.231Z,1677092029.231 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:50.030Z,1677092030.030 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:54.458Z,1677092034.458 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:55.281Z,1677092035.281 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:53:59.712Z,1677092039.712 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:00.543Z,1677092040.543 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:04.978Z,1677092044.978 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:05.781Z,1677092045.781 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:10.215Z,1677092050.215 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:10.937Z,1677092050.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:54:11.056Z,1677092051.056 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:15.468Z,1677092055.468 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:16.284Z,1677092056.284 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:20.744Z,1677092060.744 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:21.536Z,1677092061.536 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:25.971Z,1677092065.971 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:26.782Z,1677092066.782 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:31.220Z,1677092071.220 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:32.043Z,1677092072.043 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:36.482Z,1677092076.482 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:37.353Z,1677092077.353 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:41.734Z,1677092081.734 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:42.536Z,1677092082.536 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:54:43.714Z,1677092083.714 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-22T18:54:46.085Z,1677092086.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:55:21.233Z,1677092121.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:55:56.373Z,1677092156.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:56:25.519Z,1677092185.519 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5. 2023-02-22T18:56:25.538Z,1677092185.538 [BPC1](INFO): Received data from all battery sticks. 2023-02-22T18:56:31.541Z,1677092191.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:57:06.681Z,1677092226.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:57:31.010Z,1677092251.010 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-22T18:52:30.8Z 2023-02-22T18:57:31.010Z,1677092251.010 [Default:CheckIn:Read_GPS] Stopped 2023-02-22T18:57:31.010Z,1677092251.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-22T18:57:31.394Z,1677092251.394 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-22T18:57:41.821Z,1677092261.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:57:43.239Z,1677092263.239 [CommandExec](IMPORTANT): got command report clear 2023-02-22T18:57:48.796Z,1677092268.796 [CommandExec](IMPORTANT): got command report mod NAL9602.sigQuality 2023-02-22T18:57:49.161Z,1677092269.161 [Reporter](INFO): NAL9602.sigQuality 0 count 2023-02-22T18:58:01.671Z,1677092281.671 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2023-02-22T18:58:01.744Z,1677092281.744 [NAL9602](ERROR): received: +CSQ:0 OK 2023-02-22T18:58:16.960Z,1677092296.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:58:52.101Z,1677092332.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:59:27.241Z,1677092367.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-22T18:59:32.030Z,1677092372.030 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1677092332 second