2022-03-18T16:41:49.506Z,1647621709.506 [Supervisor](DEBUG): Initializing supervisor. 2022-03-18T16:41:49.510Z,1647621709.510 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-03-18T16:41:49.511Z,1647621709.511 [SyncHandler](INFO): Protected caller Thread ID is 836 2022-03-18T16:41:49.511Z,1647621709.511 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-03-18T16:41:49.512Z,1647621709.512 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-03-18T16:41:49.513Z,1647621709.513 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837 2022-03-18T16:41:49.517Z,1647621709.517 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-03-18T16:41:49.535Z,1647621709.535 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-03-18T16:41:49.536Z,1647621709.536 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-03-18T16:41:49.536Z,1647621709.536 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838 2022-03-18T16:41:49.540Z,1647621709.540 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-03-18T16:41:49.541Z,1647621709.541 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-03-18T16:41:49.542Z,1647621709.542 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839 2022-03-18T16:41:49.544Z,1647621709.544 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-03-18T16:41:49.545Z,1647621709.545 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-03-18T16:41:49.545Z,1647621709.545 [logger ThreadHandler](INFO): Protected caller Thread ID is 840 2022-03-18T16:41:49.549Z,1647621709.549 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-03-18T16:41:49.549Z,1647621709.549 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-03-18T16:41:49.553Z,1647621709.553 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-03-18T16:41:49.984Z,1647621709.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-03-18T16:41:49.986Z,1647621709.986 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-03-18T16:41:50.200Z,1647621710.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-03-18T16:41:50.202Z,1647621710.202 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-03-18T16:41:50.395Z,1647621710.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-03-18T16:41:50.397Z,1647621710.397 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-03-18T16:41:50.505Z,1647621710.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-03-18T16:41:50.507Z,1647621710.507 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-03-18T16:41:50.869Z,1647621710.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-03-18T16:41:50.870Z,1647621710.870 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-03-18T16:41:51.197Z,1647621711.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-03-18T16:41:51.197Z,1647621711.197 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-03-18T16:41:51.294Z,1647621711.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-03-18T16:41:51.294Z,1647621711.294 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-03-18T16:41:51.506Z,1647621711.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-03-18T16:41:51.508Z,1647621711.508 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-03-18T16:41:52.088Z,1647621712.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-03-18T16:41:52.090Z,1647621712.090 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-03-18T16:41:52.445Z,1647621712.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-03-18T16:41:52.446Z,1647621712.446 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-03-18T16:41:52.528Z,1647621712.528 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-03-18T16:41:52.740Z,1647621712.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-03-18T16:41:52.741Z,1647621712.741 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-03-18T16:41:52.908Z,1647621712.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-03-18T16:41:52.909Z,1647621712.909 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-03-18T16:41:54.243Z,1647621714.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-03-18T16:41:54.244Z,1647621714.244 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-03-18T16:41:54.325Z,1647621714.325 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-03-18T16:41:54.614Z,1647621714.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-03-18T16:41:54.616Z,1647621714.616 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2022-03-18T16:41:54.620Z,1647621714.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2022-03-18T16:41:54.803Z,1647621714.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2022-03-18T16:41:54.897Z,1647621714.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2022-03-18T16:41:55.012Z,1647621715.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2022-03-18T16:41:55.121Z,1647621715.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2022-03-18T16:41:55.205Z,1647621715.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2022-03-18T16:41:55.292Z,1647621715.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2022-03-18T16:41:55.410Z,1647621715.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2022-03-18T16:41:55.511Z,1647621715.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2022-03-18T16:41:55.670Z,1647621715.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2022-03-18T16:41:55.938Z,1647621715.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-03-18T16:41:55.939Z,1647621715.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2022-03-18T16:41:56.033Z,1647621716.033 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2022-03-18T16:41:56.037Z,1647621716.037 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-03-18T16:41:56.400Z,1647621716.400 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-03-18T16:41:56.401Z,1647621716.401 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-03-18T16:41:56.537Z,1647621716.537 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-03-18T16:41:56.538Z,1647621716.538 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-03-18T16:41:56.576Z,1647621716.576 [DeadReckonUsingSpeedCalculator] Loaded 2022-03-18T16:41:56.576Z,1647621716.576 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2022-03-18T16:41:56.589Z,1647621716.589 [NavChart] Loaded 2022-03-18T16:41:56.590Z,1647621716.590 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-03-18T16:41:56.595Z,1647621716.595 [UniversalFixResidualReporter] Loaded 2022-03-18T16:41:56.595Z,1647621716.595 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-03-18T16:41:56.596Z,1647621716.596 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-03-18T16:41:56.596Z,1647621716.596 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-03-18T16:41:56.612Z,1647621716.612 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-03-18T16:41:56.613Z,1647621716.613 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-03-18T16:41:56.852Z,1647621716.852 [CTD_Seabird] Loaded 2022-03-18T16:41:56.852Z,1647621716.852 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-03-18T16:41:56.853Z,1647621716.853 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0 2022-03-18T16:41:56.854Z,1647621716.854 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 922 2022-03-18T16:41:56.873Z,1647621716.873 [PAR_Licor] Loaded 2022-03-18T16:41:56.873Z,1647621716.873 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-03-18T16:41:56.904Z,1647621716.904 [WetLabsBB2FL] Loaded 2022-03-18T16:41:56.904Z,1647621716.904 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-03-18T16:41:56.905Z,1647621716.905 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0 2022-03-18T16:41:56.906Z,1647621716.906 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 923 2022-03-18T16:41:56.906Z,1647621716.906 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-03-18T16:41:56.907Z,1647621716.907 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-03-18T16:41:56.975Z,1647621716.975 [VerticalControl](DEBUG): Construct VerticalControl. 2022-03-18T16:41:57.036Z,1647621717.036 [VerticalControl] Loaded 2022-03-18T16:41:57.036Z,1647621717.036 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-03-18T16:41:57.039Z,1647621717.039 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-03-18T16:41:57.079Z,1647621717.079 [HorizontalControl] Loaded 2022-03-18T16:41:57.079Z,1647621717.079 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-03-18T16:41:57.082Z,1647621717.082 [SpeedControl](DEBUG): Construct SpeedControl. 2022-03-18T16:41:57.085Z,1647621717.085 [SpeedControl] Loaded 2022-03-18T16:41:57.085Z,1647621717.085 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-03-18T16:41:57.088Z,1647621717.088 [LoopControl](DEBUG): Construct LoopControl. 2022-03-18T16:41:57.088Z,1647621717.088 [LoopControl] Loaded 2022-03-18T16:41:57.089Z,1647621717.089 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-03-18T16:41:57.089Z,1647621717.089 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-03-18T16:41:57.090Z,1647621717.090 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-03-18T16:41:57.159Z,1647621717.159 [DepthRateCalculator] Loaded 2022-03-18T16:41:57.159Z,1647621717.159 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-03-18T16:41:57.164Z,1647621717.164 [PitchRateCalculator] Loaded 2022-03-18T16:41:57.164Z,1647621717.164 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-03-18T16:41:57.173Z,1647621717.173 [SpeedCalculator] Loaded 2022-03-18T16:41:57.174Z,1647621717.174 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-03-18T16:41:57.178Z,1647621717.178 [YawRateCalculator] Loaded 2022-03-18T16:41:57.178Z,1647621717.178 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-03-18T16:41:57.196Z,1647621717.196 [ElevatorOffsetCalculator] Loaded 2022-03-18T16:41:57.196Z,1647621717.196 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-03-18T16:41:57.197Z,1647621717.197 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-03-18T16:41:57.197Z,1647621717.197 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-03-18T16:41:57.316Z,1647621717.316 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-03-18T16:41:57.317Z,1647621717.317 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-03-18T16:41:57.401Z,1647621717.401 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-03-18T16:41:57.402Z,1647621717.402 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-03-18T16:41:57.551Z,1647621717.551 [BuoyancyServo] Loaded 2022-03-18T16:41:57.551Z,1647621717.551 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-03-18T16:41:57.571Z,1647621717.571 [ElevatorServo] Loaded 2022-03-18T16:41:57.571Z,1647621717.571 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-03-18T16:41:57.590Z,1647621717.590 [MassServo] Loaded 2022-03-18T16:41:57.590Z,1647621717.590 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-03-18T16:41:57.609Z,1647621717.609 [RudderServo] Loaded 2022-03-18T16:41:57.609Z,1647621717.609 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-03-18T16:41:57.624Z,1647621717.624 [ThrusterHE] Loaded 2022-03-18T16:41:57.624Z,1647621717.624 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-03-18T16:41:57.624Z,1647621717.624 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-03-18T16:41:57.625Z,1647621717.625 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-03-18T16:41:57.765Z,1647621717.765 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-03-18T16:41:57.766Z,1647621717.766 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-03-18T16:41:58.607Z,1647621718.607 [AHRS_M2] Loaded 2022-03-18T16:41:58.607Z,1647621718.607 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-03-18T16:41:58.931Z,1647621718.931 [BackseatComponent] Loaded 2022-03-18T16:41:58.931Z,1647621718.931 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-03-18T16:41:58.965Z,1647621718.965 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A874E0 2022-03-18T16:41:58.965Z,1647621718.965 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 2022-03-18T16:41:58.968Z,1647621718.968 [LcmUniversalReporter] Loaded 2022-03-18T16:41:58.969Z,1647621718.969 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-03-18T16:41:59.913Z,1647621719.913 [BPC1] Loaded 2022-03-18T16:41:59.914Z,1647621719.914 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-03-18T16:42:00.054Z,1647621720.054 [DataOverHttps] Loaded 2022-03-18T16:42:00.055Z,1647621720.055 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-03-18T16:42:00.056Z,1647621720.056 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AB74E0 2022-03-18T16:42:00.056Z,1647621720.056 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925 2022-03-18T16:42:00.076Z,1647621720.076 [Depth_Keller] Loaded 2022-03-18T16:42:00.076Z,1647621720.076 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-03-18T16:42:00.081Z,1647621720.081 [DropWeight] Loaded 2022-03-18T16:42:00.082Z,1647621720.082 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-03-18T16:42:00.141Z,1647621720.141 [NAL9602] Loaded 2022-03-18T16:42:00.141Z,1647621720.141 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-03-18T16:42:00.170Z,1647621720.170 [Onboard] Loaded 2022-03-18T16:42:00.171Z,1647621720.171 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-03-18T16:42:00.172Z,1647621720.172 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AE74E0 2022-03-18T16:42:00.172Z,1647621720.172 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926 2022-03-18T16:42:00.178Z,1647621720.178 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2022-03-18T16:42:00.189Z,1647621720.189 [PowerOnly] Loaded 2022-03-18T16:42:00.189Z,1647621720.189 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2022-03-18T16:42:00.200Z,1647621720.200 [Power24vConverter] Loaded 2022-03-18T16:42:00.201Z,1647621720.201 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-03-18T16:42:00.214Z,1647621720.214 [Radio_Surface] Loaded 2022-03-18T16:42:00.214Z,1647621720.214 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-03-18T16:42:00.215Z,1647621720.215 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B174E0 2022-03-18T16:42:00.215Z,1647621720.215 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927 2022-03-18T16:42:00.301Z,1647621720.301 [DAT] Loaded 2022-03-18T16:42:00.301Z,1647621720.301 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-03-18T16:42:00.302Z,1647621720.302 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0 2022-03-18T16:42:00.303Z,1647621720.303 [DAT ThreadHandler](INFO): Protected caller Thread ID is 928 2022-03-18T16:42:00.303Z,1647621720.303 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-03-18T16:42:00.305Z,1647621720.305 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-03-18T16:42:00.476Z,1647621720.476 [SBIT](DEBUG): Construct Startup Built In Test. 2022-03-18T16:42:00.485Z,1647621720.485 [SBIT] Loaded 2022-03-18T16:42:00.485Z,1647621720.485 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-03-18T16:42:00.488Z,1647621720.488 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-03-18T16:42:00.502Z,1647621720.502 [IBIT] Loaded 2022-03-18T16:42:00.503Z,1647621720.503 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-03-18T16:42:00.509Z,1647621720.509 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-03-18T16:42:00.613Z,1647621720.613 [CBIT] Loaded 2022-03-18T16:42:00.613Z,1647621720.613 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-03-18T16:42:00.614Z,1647621720.614 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-03-18T16:42:00.614Z,1647621720.614 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-03-18T16:42:00.657Z,1647621720.657 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-03-18T16:42:00.664Z,1647621720.664 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-03-18T16:42:00.666Z,1647621720.666 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-03-18T16:42:00.677Z,1647621720.677 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-03-18T16:42:00.678Z,1647621720.678 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD54E0 2022-03-18T16:42:00.678Z,1647621720.678 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929 2022-03-18T16:42:00.683Z,1647621720.683 [Supervisor](INFO): Main Thread ID is 832 2022-03-18T16:42:00.683Z,1647621720.683 [Supervisor](DEBUG): Running supervisor. 2022-03-18T16:42:00.684Z,1647621720.684 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930 2022-03-18T16:42:00.684Z,1647621720.684 [CommandExec](INFO): Initializing the command executive. 2022-03-18T16:42:00.686Z,1647621720.686 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931 2022-03-18T16:42:00.688Z,1647621720.688 [controlThread ThreadHandler](INFO): Handler Thread ID is 932 2022-03-18T16:42:00.688Z,1647621720.688 [controlThread](DEBUG): Initializing ControlThread 2022-03-18T16:42:00.690Z,1647621720.690 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-18T16:42:00.690Z,1647621720.690 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-03-18T16:42:00.690Z,1647621720.690 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-03-18T16:42:00.692Z,1647621720.692 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-03-18T16:42:00.694Z,1647621720.694 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-03-18T16:42:00.694Z,1647621720.694 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-03-18T16:42:00.695Z,1647621720.695 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-03-18T16:42:00.695Z,1647621720.695 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-03-18T16:42:00.695Z,1647621720.695 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-03-18T16:42:00.696Z,1647621720.696 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-03-18T16:42:00.696Z,1647621720.696 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-03-18T16:42:00.696Z,1647621720.696 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-03-18T16:42:00.702Z,1647621720.702 [SBIT](INFO): Initialize SBIT Component. 2022-03-18T16:42:00.702Z,1647621720.702 [SBIT](IMPORTANT): git: 2022-03-18T16:42:00.703Z,1647621720.703 [SBIT](INFO): git hash: 668a399f6f79f260e08478366d8691aca360f216 2022-03-18T16:42:00.703Z,1647621720.703 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-03-18T16:42:00.704Z,1647621720.704 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-03-18T16:42:00.705Z,1647621720.705 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2022-03-18T16:42:00.706Z,1647621720.706 [IBIT](INFO): Initialize IBIT Component. 2022-03-18T16:42:00.707Z,1647621720.707 [CBIT](DEBUG): Initialize CBIT Component. 2022-03-18T16:42:00.708Z,1647621720.708 [logger ThreadHandler](INFO): Handler Thread ID is 933 2022-03-18T16:42:00.720Z,1647621720.720 [CBIT](DEBUG): Initialized mux pins. 2022-03-18T16:42:00.720Z,1647621720.720 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2022-03-18T16:42:00.720Z,1647621720.720 [CBIT](DEBUG): Initializing the watchdog timer. 2022-03-18T16:42:00.728Z,1647621720.728 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 934 2022-03-18T16:42:00.729Z,1647621720.729 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-03-18T16:42:00.733Z,1647621720.733 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 936 2022-03-18T16:42:00.736Z,1647621720.736 [WetLabsBB2FL](INFO): Powering up 2022-03-18T16:42:00.737Z,1647621720.737 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 938 2022-03-18T16:42:00.744Z,1647621720.744 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-03-18T16:42:00.746Z,1647621720.746 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939 2022-03-18T16:42:00.747Z,1647621720.747 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-03-18T16:42:00.749Z,1647621720.749 [CBIT](FAULT): Found backtrace file: 1647621268.584777 [CBIT]: Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1a5980] ./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1a53cc] 2022-03-18T16:42:00.754Z,1647621720.754 [Onboard ThreadHandler](INFO): Handler Thread ID is 940 2022-03-18T16:42:00.761Z,1647621720.761 [CBIT](FAULT): Renaming backtrace file to Data/backtrace8 2022-03-18T16:42:00.762Z,1647621720.762 [CBIT](DEBUG): Initializing heartbeat. 2022-03-18T16:42:00.792Z,1647621720.792 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941 2022-03-18T16:42:00.804Z,1647621720.804 [DAT ThreadHandler](INFO): Handler Thread ID is 942 2022-03-18T16:42:00.805Z,1647621720.805 [DAT](INFO): Powering up 2022-03-18T16:42:00.805Z,1647621720.805 [DAT](DEBUG): Initializing DAT. 2022-03-18T16:42:00.809Z,1647621720.809 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944 2022-03-18T16:42:00.817Z,1647621720.817 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-03-18T16:42:00.817Z,1647621720.817 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-03-18T16:42:00.818Z,1647621720.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-03-18T16:42:00.818Z,1647621720.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-03-18T16:42:00.818Z,1647621720.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-03-18T16:42:00.818Z,1647621720.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-03-18T16:42:00.818Z,1647621720.818 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2022-03-18T16:42:00.818Z,1647621720.818 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2022-03-18T16:42:00.819Z,1647621720.819 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-03-18T16:42:00.819Z,1647621720.819 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-03-18T16:42:00.819Z,1647621720.819 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2022-03-18T16:42:00.819Z,1647621720.819 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2022-03-18T16:42:00.819Z,1647621720.819 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2022-03-18T16:42:00.819Z,1647621720.819 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2022-03-18T16:42:00.820Z,1647621720.820 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2022-03-18T16:42:00.820Z,1647621720.820 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2022-03-18T16:42:00.832Z,1647621720.832 [CBIT](DEBUG): Deactivating GF circuits. 2022-03-18T16:42:00.832Z,1647621720.832 [CBIT](DEBUG): Deactivating emergency mode. 2022-03-18T16:42:00.868Z,1647621720.868 [CBIT](DEBUG): Backplane powered. 2022-03-18T16:42:00.869Z,1647621720.869 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-03-18T16:42:00.901Z,1647621720.901 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-03-18T16:42:00.941Z,1647621720.941 [MissionManager](DEBUG): 2022-03-18T16:42:00.942Z,1647621720.942 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-03-18T16:42:01.005Z,1647621721.005 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-03-18T16:42:01.006Z,1647621721.006 [Default:A.Wait](DEBUG): Construct Wait. 2022-03-18T16:42:01.041Z,1647621721.041 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-03-18T16:42:01.068Z,1647621721.068 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-03-18T16:42:01.071Z,1647621721.071 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-03-18T16:42:01.089Z,1647621721.089 [Default:E.Execute](DEBUG): Construct Execute. 2022-03-18T16:42:01.108Z,1647621721.108 [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 2022-03-18T16:42:01.113Z,1647621721.113 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-03-18T16:42:01.125Z,1647621721.125 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-03-18T16:42:01.196Z,1647621721.196 [Radio_Surface](INFO): Powering up 2022-03-18T16:42:01.295Z,1647621721.295 [Power24vConverter](INFO): Powering up. 2022-03-18T16:42:01.336Z,1647621721.336 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-03-18T16:42:01.344Z,1647621721.344 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-03-18T16:42:01.345Z,1647621721.345 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-03-18T16:42:01.352Z,1647621721.352 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-03-18T16:42:01.353Z,1647621721.353 [MassServo](DEBUG): Initializing EZServoServo. 2022-03-18T16:42:01.360Z,1647621721.360 [MassServo](DEBUG): Initializing MassServo. 2022-03-18T16:42:01.361Z,1647621721.361 [RudderServo](DEBUG): Initializing EZServoServo. 2022-03-18T16:42:01.368Z,1647621721.368 [RudderServo](DEBUG): Initializing RudderServo. 2022-03-18T16:42:01.369Z,1647621721.369 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-03-18T16:42:01.376Z,1647621721.376 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-03-18T16:42:01.549Z,1647621721.549 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-03-18T16:42:01.549Z,1647621721.549 [DropWeight] Hardware Fault, FailCount= 1 2022-03-18T16:42:01.549Z,1647621721.549 [DropWeight](ERROR): Hardware Fault 2022-03-18T16:42:01.614Z,1647621721.614 [CommandExec](FAULT): Scheduling is paused 2022-03-18T16:42:01.615Z,1647621721.615 [CBIT](INFO): Critical error at 20220318T164201 2022-03-18T16:42:01.615Z,1647621721.615 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-03-18T16:42:01.626Z,1647621721.626 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-03-18T16:42:01.626Z,1647621721.626 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-03-18T16:42:02.292Z,1647621722.292 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-03-18T16:42:02.292Z,1647621722.292 [RudderServo](FAULT): Rudder failed to initialize 2022-03-18T16:42:02.292Z,1647621722.292 [RudderServo] Communications Fault, FailCount= 1 2022-03-18T16:42:02.292Z,1647621722.292 [RudderServo](ERROR): Communications Fault 2022-03-18T16:42:02.294Z,1647621722.294 [CBIT](INFO): Critical error at 20220318T164201 2022-03-18T16:42:02.296Z,1647621722.296 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-03-18T16:42:02.485Z,1647621722.485 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-03-18T16:42:02.485Z,1647621722.485 [RudderServo](INFO): Powering down 2022-03-18T16:42:03.160Z,1647621723.160 [RudderServo](DEBUG): Initializing EZServoServo. 2022-03-18T16:42:03.281Z,1647621723.281 [RudderServo](DEBUG): Initializing RudderServo. 2022-03-18T16:42:03.285Z,1647621723.285 [CBIT](INFO): Clearing failed state for component RudderServo 2022-03-18T16:42:03.285Z,1647621723.285 [RudderServo] No Fault, FailCount= 1 2022-03-18T16:42:03.348Z,1647621723.348 [WetLabsBB2FL](INFO): Powering down 2022-03-18T16:42:07.077Z,1647621727.077 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-03-18T16:42:15.925Z,1647621735.925 [DAT](INFO): commRate: 800 2022-03-18T16:42:17.992Z,1647621737.992 [DAT](INFO): entering command mode 2022-03-18T16:42:18.193Z,1647621738.193 [DAT](INFO): setting verbose to 3 2022-03-18T16:42:18.445Z,1647621738.445 [DAT](INFO): set verbose to 3 2022-03-18T16:42:18.446Z,1647621738.446 [DAT](INFO): setting DatVerbose to 27440 2022-03-18T16:42:18.697Z,1647621738.697 [DAT](INFO): set DatVerbose to 27440 2022-03-18T16:42:18.698Z,1647621738.698 [DAT](INFO): setting transmit power to 8 2022-03-18T16:42:18.949Z,1647621738.949 [DAT](INFO): set transmit power to 8 2022-03-18T16:42:18.949Z,1647621738.949 [DAT](INFO): setting local address to 10 2022-03-18T16:42:19.201Z,1647621739.201 [DAT](INFO): set local address to 10 2022-03-18T16:42:19.202Z,1647621739.202 [DAT](INFO): Setting time to: 16:42:19 And date to:3/18/2022 2022-03-18T16:42:19.453Z,1647621739.453 [DAT](INFO): Local DAT time set to Fri Mar 18, 2022 16:42:19 2022-03-18T16:42:29.477Z,1647621749.477 [SBIT](IMPORTANT): Beginning Startup BIT 2022-03-18T16:42:29.485Z,1647621749.485 [CBIT](IMPORTANT): Beginning ground fault scan 2022-03-18T16:42:30.245Z,1647621750.245 [NAL9602](INFO): Powering up NAL9602 2022-03-18T16:42:40.426Z,1647621760.426 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000465 CHAN A1 (24V): 0.000354 CHAN A2 (12V): -0.005066 CHAN A3 (5V): -0.003008 CHAN B0 (3.3V): -0.000945 CHAN B1 (3.15aV): -0.001441 CHAN B2 (3.15bV): -0.001441 CHAN B3 (GND): -0.001004 OPEN