2022-09-21T19:31:24.717Z,1663788684.717 [Supervisor](DEBUG): Initializing supervisor. 2022-09-21T19:31:24.721Z,1663788684.721 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-09-21T19:31:24.722Z,1663788684.722 [SyncHandler](INFO): Protected caller Thread ID is 837 2022-09-21T19:31:24.722Z,1663788684.722 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-09-21T19:31:24.723Z,1663788684.723 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-09-21T19:31:24.723Z,1663788684.723 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2022-09-21T19:31:24.727Z,1663788684.727 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-09-21T19:31:24.745Z,1663788684.745 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-09-21T19:31:24.746Z,1663788684.746 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-09-21T19:31:24.747Z,1663788684.747 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2022-09-21T19:31:24.751Z,1663788684.751 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-09-21T19:31:24.752Z,1663788684.752 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-09-21T19:31:24.752Z,1663788684.752 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2022-09-21T19:31:24.754Z,1663788684.754 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-09-21T19:31:24.755Z,1663788684.755 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-09-21T19:31:24.755Z,1663788684.755 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2022-09-21T19:31:24.759Z,1663788684.759 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-09-21T19:31:24.759Z,1663788684.759 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-09-21T19:31:24.764Z,1663788684.764 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-09-21T19:31:24.860Z,1663788684.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-09-21T19:31:24.862Z,1663788684.862 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-09-21T19:31:25.506Z,1663788685.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-09-21T19:31:25.507Z,1663788685.507 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-09-21T19:31:25.727Z,1663788685.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-09-21T19:31:25.729Z,1663788685.729 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-09-21T19:31:25.810Z,1663788685.810 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-09-21T19:31:25.933Z,1663788685.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-09-21T19:31:25.935Z,1663788685.935 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-09-21T19:31:26.017Z,1663788686.017 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-09-21T19:31:26.125Z,1663788686.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-09-21T19:31:26.126Z,1663788686.126 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-09-21T19:31:26.485Z,1663788686.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-09-21T19:31:26.487Z,1663788686.487 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-09-21T19:31:27.042Z,1663788687.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-09-21T19:31:27.043Z,1663788687.043 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-09-21T19:31:27.256Z,1663788687.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-09-21T19:31:27.258Z,1663788687.258 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-09-21T19:31:27.444Z,1663788687.444 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-09-21T19:31:27.444Z,1663788687.444 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-09-21T19:31:27.905Z,1663788687.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-09-21T19:31:27.906Z,1663788687.906 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-09-21T19:31:28.551Z,1663788688.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-09-21T19:31:28.770Z,1663788688.770 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-09-21T19:31:29.096Z,1663788689.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-09-21T19:31:29.096Z,1663788689.096 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-09-21T19:31:29.457Z,1663788689.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-09-21T19:31:29.459Z,1663788689.459 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-09-21T19:31:29.790Z,1663788689.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-09-21T19:31:29.792Z,1663788689.792 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2022-09-21T19:31:29.795Z,1663788689.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2022-09-21T19:31:29.880Z,1663788689.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2022-09-21T19:31:30.090Z,1663788690.090 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2022-09-21T19:31:30.290Z,1663788690.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2022-09-21T19:31:30.403Z,1663788690.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2022-09-21T19:31:30.498Z,1663788690.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2022-09-21T19:31:30.679Z,1663788690.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2022-09-21T19:31:30.941Z,1663788690.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-09-21T19:31:30.941Z,1663788690.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2022-09-21T19:31:31.036Z,1663788691.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2022-09-21T19:31:31.136Z,1663788691.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2022-09-21T19:31:31.275Z,1663788691.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2022-09-21T19:31:31.374Z,1663788691.374 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-09-21T19:31:31.394Z,1663788691.394 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-09-21T19:31:31.528Z,1663788691.528 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-09-21T19:31:31.529Z,1663788691.529 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-09-21T19:31:31.598Z,1663788691.598 [VerticalControl](DEBUG): Construct VerticalControl. 2022-09-21T19:31:31.657Z,1663788691.657 [VerticalControl] Loaded 2022-09-21T19:31:31.657Z,1663788691.657 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-09-21T19:31:31.660Z,1663788691.660 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-09-21T19:31:31.699Z,1663788691.699 [HorizontalControl] Loaded 2022-09-21T19:31:31.699Z,1663788691.699 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-09-21T19:31:31.702Z,1663788691.702 [SpeedControl](DEBUG): Construct SpeedControl. 2022-09-21T19:31:31.705Z,1663788691.705 [SpeedControl] Loaded 2022-09-21T19:31:31.705Z,1663788691.705 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-09-21T19:31:31.708Z,1663788691.708 [LoopControl](DEBUG): Construct LoopControl. 2022-09-21T19:31:31.708Z,1663788691.708 [LoopControl] Loaded 2022-09-21T19:31:31.708Z,1663788691.708 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-09-21T19:31:31.709Z,1663788691.709 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-09-21T19:31:31.709Z,1663788691.709 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-09-21T19:31:31.779Z,1663788691.779 [DepthRateCalculator] Loaded 2022-09-21T19:31:31.780Z,1663788691.780 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-09-21T19:31:31.784Z,1663788691.784 [PitchRateCalculator] Loaded 2022-09-21T19:31:31.785Z,1663788691.785 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-09-21T19:31:31.795Z,1663788691.795 [SpeedCalculator] Loaded 2022-09-21T19:31:31.795Z,1663788691.795 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-09-21T19:31:31.799Z,1663788691.799 [YawRateCalculator] Loaded 2022-09-21T19:31:31.799Z,1663788691.799 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-09-21T19:31:31.817Z,1663788691.817 [ElevatorOffsetCalculator] Loaded 2022-09-21T19:31:31.817Z,1663788691.817 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-09-21T19:31:31.817Z,1663788691.817 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-09-21T19:31:31.818Z,1663788691.818 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-09-21T19:31:31.860Z,1663788691.860 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-09-21T19:31:31.861Z,1663788691.861 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-09-21T19:31:31.943Z,1663788691.943 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-09-21T19:31:31.944Z,1663788691.944 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-09-21T19:31:32.305Z,1663788692.305 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-09-21T19:31:32.305Z,1663788692.305 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-09-21T19:31:32.444Z,1663788692.444 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-09-21T19:31:32.445Z,1663788692.445 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-09-21T19:31:33.171Z,1663788693.171 [AHRS_M2] Loaded 2022-09-21T19:31:33.171Z,1663788693.171 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-09-21T19:31:33.419Z,1663788693.419 [BackseatComponent] Loaded 2022-09-21T19:31:33.420Z,1663788693.420 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-09-21T19:31:33.421Z,1663788693.421 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0 2022-09-21T19:31:33.421Z,1663788693.421 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923 2022-09-21T19:31:33.424Z,1663788693.424 [LcmUniversalReporter] Loaded 2022-09-21T19:31:33.424Z,1663788693.424 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-09-21T19:31:34.224Z,1663788694.224 [BPC1] Loaded 2022-09-21T19:31:34.225Z,1663788694.225 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-09-21T19:31:34.367Z,1663788694.367 [DataOverHttps] Loaded 2022-09-21T19:31:34.367Z,1663788694.367 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-09-21T19:31:34.368Z,1663788694.368 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0 2022-09-21T19:31:34.368Z,1663788694.368 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2022-09-21T19:31:34.388Z,1663788694.388 [Depth_Keller] Loaded 2022-09-21T19:31:34.388Z,1663788694.388 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-09-21T19:31:34.393Z,1663788694.393 [DropWeight] Loaded 2022-09-21T19:31:34.393Z,1663788694.393 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-09-21T19:31:34.453Z,1663788694.453 [NAL9602] Loaded 2022-09-21T19:31:34.453Z,1663788694.453 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-09-21T19:31:34.481Z,1663788694.481 [Onboard] Loaded 2022-09-21T19:31:34.481Z,1663788694.481 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-09-21T19:31:34.482Z,1663788694.482 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0 2022-09-21T19:31:34.483Z,1663788694.483 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2022-09-21T19:31:34.494Z,1663788694.494 [Power24vConverter] Loaded 2022-09-21T19:31:34.494Z,1663788694.494 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-09-21T19:31:34.507Z,1663788694.507 [Radio_Surface] Loaded 2022-09-21T19:31:34.507Z,1663788694.507 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-09-21T19:31:34.508Z,1663788694.508 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0 2022-09-21T19:31:34.508Z,1663788694.508 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2022-09-21T19:31:34.551Z,1663788694.551 [RDI_Pathfinder] Loaded 2022-09-21T19:31:34.551Z,1663788694.551 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2022-09-21T19:31:34.636Z,1663788694.636 [DAT] Loaded 2022-09-21T19:31:34.636Z,1663788694.636 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-09-21T19:31:34.637Z,1663788694.637 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A2D4E0 2022-09-21T19:31:34.638Z,1663788694.638 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927 2022-09-21T19:31:34.638Z,1663788694.638 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-09-21T19:31:34.639Z,1663788694.639 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-09-21T19:31:35.346Z,1663788695.346 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-09-21T19:31:35.346Z,1663788695.346 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-09-21T19:31:35.415Z,1663788695.415 [NavChart] Loaded 2022-09-21T19:31:35.416Z,1663788695.416 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-09-21T19:31:35.429Z,1663788695.429 [UniversalFixResidualReporter] Loaded 2022-09-21T19:31:35.429Z,1663788695.429 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-09-21T19:31:35.430Z,1663788695.430 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-09-21T19:31:35.668Z,1663788695.668 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-09-21T19:31:35.836Z,1663788695.836 [SBIT](DEBUG): Construct Startup Built In Test. 2022-09-21T19:31:35.845Z,1663788695.845 [SBIT] Loaded 2022-09-21T19:31:35.846Z,1663788695.846 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-09-21T19:31:35.848Z,1663788695.848 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-09-21T19:31:35.861Z,1663788695.861 [IBIT] Loaded 2022-09-21T19:31:35.862Z,1663788695.862 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-09-21T19:31:35.867Z,1663788695.867 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-09-21T19:31:35.973Z,1663788695.973 [CBIT] Loaded 2022-09-21T19:31:35.973Z,1663788695.973 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-09-21T19:31:35.974Z,1663788695.974 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-09-21T19:31:35.974Z,1663788695.974 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-09-21T19:31:36.126Z,1663788696.126 [BuoyancyServo] Loaded 2022-09-21T19:31:36.126Z,1663788696.126 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-09-21T19:31:36.147Z,1663788696.147 [ElevatorServo] Loaded 2022-09-21T19:31:36.147Z,1663788696.147 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-09-21T19:31:36.166Z,1663788696.166 [MassServo] Loaded 2022-09-21T19:31:36.166Z,1663788696.166 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-09-21T19:31:36.184Z,1663788696.184 [RudderServo] Loaded 2022-09-21T19:31:36.184Z,1663788696.184 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-09-21T19:31:36.202Z,1663788696.202 [ThrusterServo] Loaded 2022-09-21T19:31:36.202Z,1663788696.202 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2022-09-21T19:31:36.203Z,1663788696.203 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-09-21T19:31:36.203Z,1663788696.203 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-09-21T19:31:36.219Z,1663788696.219 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-09-21T19:31:36.220Z,1663788696.220 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-09-21T19:31:36.437Z,1663788696.437 [CTD_Seabird] Loaded 2022-09-21T19:31:36.437Z,1663788696.437 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-09-21T19:31:36.438Z,1663788696.438 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B774E0 2022-09-21T19:31:36.438Z,1663788696.438 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928 2022-09-21T19:31:36.458Z,1663788696.458 [PAR_Licor] Loaded 2022-09-21T19:31:36.458Z,1663788696.458 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-09-21T19:31:36.490Z,1663788696.490 [WetLabsBB2FL] Loaded 2022-09-21T19:31:36.490Z,1663788696.490 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-09-21T19:31:36.491Z,1663788696.491 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BA74E0 2022-09-21T19:31:36.491Z,1663788696.491 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929 2022-09-21T19:31:36.511Z,1663788696.511 [WetLabsUBAT] Loaded 2022-09-21T19:31:36.512Z,1663788696.512 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2022-09-21T19:31:36.513Z,1663788696.513 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0 2022-09-21T19:31:36.513Z,1663788696.513 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 930 2022-09-21T19:31:36.514Z,1663788696.514 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-09-21T19:31:36.520Z,1663788696.520 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-09-21T19:31:36.522Z,1663788696.522 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-09-21T19:31:36.533Z,1663788696.533 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-09-21T19:31:36.534Z,1663788696.534 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2022-09-21T19:31:36.534Z,1663788696.534 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 2022-09-21T19:31:36.539Z,1663788696.539 [Supervisor](INFO): Main Thread ID is 830 2022-09-21T19:31:36.539Z,1663788696.539 [Supervisor](DEBUG): Running supervisor. 2022-09-21T19:31:36.540Z,1663788696.540 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 2022-09-21T19:31:36.540Z,1663788696.540 [CommandExec](INFO): Initializing the command executive. 2022-09-21T19:31:36.542Z,1663788696.542 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 2022-09-21T19:31:36.544Z,1663788696.544 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 2022-09-21T19:31:36.544Z,1663788696.544 [controlThread](DEBUG): Initializing ControlThread 2022-09-21T19:31:36.545Z,1663788696.545 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-09-21T19:31:36.547Z,1663788696.547 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-09-21T19:31:36.547Z,1663788696.547 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-09-21T19:31:36.548Z,1663788696.548 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-09-21T19:31:36.548Z,1663788696.548 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-09-21T19:31:36.548Z,1663788696.548 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-09-21T19:31:36.549Z,1663788696.549 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-09-21T19:31:36.549Z,1663788696.549 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-09-21T19:31:36.549Z,1663788696.549 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-09-21T19:31:36.556Z,1663788696.556 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-09-21T19:31:36.556Z,1663788696.556 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-09-21T19:31:36.556Z,1663788696.556 [SBIT](INFO): Initialize SBIT Component. 2022-09-21T19:31:36.557Z,1663788696.557 [SBIT](IMPORTANT): git: 2022-06-07-3-g3731f8b3f 2022-09-21T19:31:36.557Z,1663788696.557 [SBIT](INFO): git hash: 3731f8b3f5f07755356c125fa6a04ab4ea762347 2022-09-21T19:31:36.557Z,1663788696.557 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-09-21T19:31:36.558Z,1663788696.558 [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 2022-09-21T19:31:36.560Z,1663788696.560 [SBIT](INFO): Beginning SBIT in 45.000000 seconds. 2022-09-21T19:31:36.560Z,1663788696.560 [IBIT](INFO): Initialize IBIT Component. 2022-09-21T19:31:36.561Z,1663788696.561 [CBIT](DEBUG): Initialize CBIT Component. 2022-09-21T19:31:36.562Z,1663788696.562 [logger ThreadHandler](INFO): Handler Thread ID is 935 2022-09-21T19:31:36.573Z,1663788696.573 [CBIT](DEBUG): Initialized mux pins. 2022-09-21T19:31:36.573Z,1663788696.573 [CBIT](DEBUG): Initializing the watchdog timer. 2022-09-21T19:31:36.582Z,1663788696.582 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936 2022-09-21T19:31:36.594Z,1663788696.594 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937 2022-09-21T19:31:36.595Z,1663788696.595 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-09-21T19:31:36.597Z,1663788696.597 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-09-21T19:31:36.598Z,1663788696.598 [CBIT](DEBUG): Initializing heartbeat. 2022-09-21T19:31:36.606Z,1663788696.606 [Onboard ThreadHandler](INFO): Handler Thread ID is 938 2022-09-21T19:31:36.623Z,1663788696.623 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939 2022-09-21T19:31:36.642Z,1663788696.642 [DAT ThreadHandler](INFO): Handler Thread ID is 940 2022-09-21T19:31:36.643Z,1663788696.643 [DAT](INFO): Powering up 2022-09-21T19:31:36.643Z,1663788696.643 [DAT](DEBUG): Initializing DAT. 2022-09-21T19:31:36.647Z,1663788696.647 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942 2022-09-21T19:31:36.647Z,1663788696.647 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-09-21T19:31:36.650Z,1663788696.650 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944 2022-09-21T19:31:36.654Z,1663788696.654 [WetLabsBB2FL](INFO): Powering up 2022-09-21T19:31:36.655Z,1663788696.655 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 946 2022-09-21T19:31:36.658Z,1663788696.658 [WetLabsUBAT](INFO): Powering up 2022-09-21T19:31:36.659Z,1663788696.659 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 948 2022-09-21T19:31:36.667Z,1663788696.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-09-21T19:31:36.668Z,1663788696.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-09-21T19:31:36.668Z,1663788696.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-09-21T19:31:36.668Z,1663788696.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-09-21T19:31:36.668Z,1663788696.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-09-21T19:31:36.668Z,1663788696.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-09-21T19:31:36.668Z,1663788696.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-09-21T19:31:36.669Z,1663788696.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-09-21T19:31:36.669Z,1663788696.669 [CBIT](DEBUG): Deactivating GF circuits. 2022-09-21T19:31:36.669Z,1663788696.669 [CBIT](DEBUG): Deactivating emergency mode. 2022-09-21T19:31:36.705Z,1663788696.705 [CBIT](DEBUG): Backplane powered. 2022-09-21T19:31:36.710Z,1663788696.710 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-09-21T19:31:36.747Z,1663788696.747 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-09-21T19:31:36.782Z,1663788696.782 [MissionManager](DEBUG): 2022-09-21T19:31:36.783Z,1663788696.783 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-09-21T19:31:36.878Z,1663788696.878 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-09-21T19:31:36.879Z,1663788696.879 [Default:A.Wait](DEBUG): Construct Wait. 2022-09-21T19:31:36.882Z,1663788696.882 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-09-21T19:31:36.909Z,1663788696.909 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-09-21T19:31:36.927Z,1663788696.927 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-09-21T19:31:36.950Z,1663788696.950 [Default:E.Execute](DEBUG): Construct Execute. 2022-09-21T19:31:36.969Z,1663788696.969 [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-09-21T19:31:36.974Z,1663788696.974 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,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, 2022-09-21T19:31:36.980Z,1663788696.980 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-09-21T19:31:37.037Z,1663788697.037 [Radio_Surface](INFO): Powering up 2022-09-21T19:31:37.128Z,1663788697.128 [Power24vConverter](INFO): Powering up. 2022-09-21T19:31:37.129Z,1663788697.129 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2022-09-21T19:31:37.195Z,1663788697.195 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-09-21T19:31:37.202Z,1663788697.202 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-09-21T19:31:37.203Z,1663788697.203 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-09-21T19:31:37.209Z,1663788697.209 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-09-21T19:31:37.210Z,1663788697.210 [MassServo](DEBUG): Initializing EZServoServo. 2022-09-21T19:31:37.217Z,1663788697.217 [MassServo](DEBUG): Initializing MassServo. 2022-09-21T19:31:37.218Z,1663788697.218 [RudderServo](DEBUG): Initializing EZServoServo. 2022-09-21T19:31:37.226Z,1663788697.226 [RudderServo](DEBUG): Initializing RudderServo. 2022-09-21T19:31:37.227Z,1663788697.227 [ThrusterServo](DEBUG): Initializing EZServoServo. 2022-09-21T19:31:37.237Z,1663788697.237 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2022-09-21T19:31:37.410Z,1663788697.410 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-09-21T19:31:37.410Z,1663788697.410 [DropWeight] Hardware Fault, FailCount= 1 2022-09-21T19:31:37.411Z,1663788697.411 [DropWeight](ERROR): Hardware Fault 2022-09-21T19:31:37.451Z,1663788697.451 [CommandExec](FAULT): Scheduling is paused 2022-09-21T19:31:37.452Z,1663788697.452 [CBIT](INFO): Critical error at 20220921T193137 2022-09-21T19:31:37.452Z,1663788697.452 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-09-21T19:31:37.463Z,1663788697.463 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-09-21T19:31:37.464Z,1663788697.464 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-09-21T19:31:38.149Z,1663788698.149 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-09-21T19:31:38.149Z,1663788698.149 [RudderServo](FAULT): Rudder failed to initialize 2022-09-21T19:31:38.149Z,1663788698.149 [RudderServo] Communications Fault, FailCount= 1 2022-09-21T19:31:38.149Z,1663788698.149 [RudderServo](ERROR): Communications Fault 2022-09-21T19:31:38.247Z,1663788698.247 [CBIT](INFO): Critical error at 20220921T193137 2022-09-21T19:31:38.250Z,1663788698.250 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-09-21T19:31:38.426Z,1663788698.426 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-09-21T19:31:38.426Z,1663788698.426 [RudderServo](INFO): Powering down 2022-09-21T19:31:39.110Z,1663788699.110 [RudderServo](DEBUG): Initializing EZServoServo. 2022-09-21T19:31:39.230Z,1663788699.230 [RudderServo](DEBUG): Initializing RudderServo. 2022-09-21T19:31:39.235Z,1663788699.235 [CBIT](INFO): Clearing failed state for component RudderServo 2022-09-21T19:31:39.235Z,1663788699.235 [RudderServo] No Fault, FailCount= 1 2022-09-21T19:31:39.265Z,1663788699.265 [WetLabsBB2FL](INFO): Powering down 2022-09-21T19:31:45.978Z,1663788705.978 [WetLabsUBAT](INFO): Powering down 2022-09-21T19:31:48.739Z,1663788708.739 [DAT](INFO): DAT read: 2022-09-21T19:31:48.743Z,1663788708.743 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2022-09-21T19:31:50.250Z,1663788710.250 [DAT](INFO): DAT read: MF Frequency Band 2022-09-21T19:31:50.251Z,1663788710.251 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2022-09-21T19:31:50.252Z,1663788710.252 [DAT](INFO): DAT read: Sep 21 2022 19:31:38 2022-09-21T19:31:51.259Z,1663788711.259 [DAT](INFO): DAT read: Features enabled [Bearing] 2022-09-21T19:31:51.260Z,1663788711.260 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2022-09-21T19:31:51.261Z,1663788711.261 [DAT](INFO): commRate: 800 2022-09-21T19:31:53.325Z,1663788713.325 [DAT](INFO): entering command mode 2022-09-21T19:31:53.526Z,1663788713.526 [DAT](INFO): DAT read: 2022-09-21T19:31:53.526Z,1663788713.526 [DAT](INFO): DAT read: user:1> 2022-09-21T19:31:53.527Z,1663788713.527 [DAT](INFO): setting verbose to 3 2022-09-21T19:31:53.778Z,1663788713.778 [DAT](INFO): DAT read: user:1> 2022-09-21T19:31:53.779Z,1663788713.779 [DAT](INFO): DAT read: Verbose | 3 2022-09-21T19:31:53.779Z,1663788713.779 [DAT](INFO): set verbose to 3 2022-09-21T19:31:53.780Z,1663788713.780 [DAT](INFO): setting DatVerbose to 27440 2022-09-21T19:31:54.030Z,1663788714.030 [DAT](INFO): DAT read: user:2> 2022-09-21T19:31:54.031Z,1663788714.031 [DAT](INFO): DAT read: DatVerbose | 27440 2022-09-21T19:31:54.031Z,1663788714.031 [DAT](INFO): set DatVerbose to 27440 2022-09-21T19:31:54.031Z,1663788714.031 [DAT](INFO): setting transmit power to 8 2022-09-21T19:31:54.282Z,1663788714.282 [DAT](INFO): DAT read: user:3> 2022-09-21T19:31:54.283Z,1663788714.283 [DAT](INFO): DAT read: TxPower | 8 (Max) 2022-09-21T19:31:54.283Z,1663788714.283 [DAT](INFO): set transmit power to 8 2022-09-21T19:31:54.283Z,1663788714.283 [DAT](INFO): setting local address to 9 2022-09-21T19:31:54.534Z,1663788714.534 [DAT](INFO): DAT read: user:4> 2022-09-21T19:31:54.535Z,1663788714.535 [DAT](INFO): DAT read: LocalAddr | 9 2022-09-21T19:31:54.535Z,1663788714.535 [DAT](INFO): set local address to 9 2022-09-21T19:32:04.523Z,1663788724.523 [NAL9602](INFO): Powering up NAL9602 2022-09-21T19:32:15.439Z,1663788735.439 [NAL9602](INFO): NAL9602 initialized 2022-09-21T19:32:22.323Z,1663788742.323 [SBIT](IMPORTANT): Beginning Startup BIT 2022-09-21T19:32:22.348Z,1663788742.348 [CBIT](IMPORTANT): Beginning ground fault scan 2022-09-21T19:32:33.666Z,1663788753.666 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.009015 CHAN A1 (24V): 0.000215 CHAN A2 (12V): -0.008006 CHAN A3 (5V): -0.002365 CHAN B0 (3.3V): -0.000065 CHAN B1 (3.15aV): -0.001075 CHAN B2 (3.15bV): -0.000411 CHAN B3 (GND): 0.001454 OPEN: -0.003844 Full Scale: +/- 1 mA 2022-09-21T19:33:16.122Z,1663788796.122 [SBIT](IMPORTANT): SBIT PASSED 2022-09-21T19:33:16.122Z,1663788796.122 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-09-21T19:33:16.123Z,1663788796.123 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2022-09-21T19:33:16.123Z,1663788796.123 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2022-09-21T19:33:16.123Z,1663788796.123 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour; 2022-09-21T19:33:16.123Z,1663788796.123 [SBIT](IMPORTANT): DAT.baud=115200 bit_per_second; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): DAT.verbosity=2 count; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): Express none _.adinos; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): Express none _.aother; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): Express linearApproximation _.dinoflagellates 10000000000.000000 none; 2022-09-21T19:33:16.124Z,1663788796.124 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour; 2022-09-21T19:33:16.125Z,1663788796.125 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2022-09-21T19:33:16.125Z,1663788796.125 [SBIT](IMPORTANT): Power24vConverter.simulateHardware=0 bool; 2022-09-21T19:33:16.128Z,1663788796.128 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool; 2022-09-21T19:33:16.129Z,1663788796.129 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=222.313197 cubic_centimeter; 2022-09-21T19:33:16.129Z,1663788796.129 [SBIT](IMPORTANT): VerticalControl.massDefault=6.495498 millimeter; 2022-09-21T19:33:16.463Z,1663788796.463 [MissionManager](IMPORTANT): Started mission Startup 2022-09-21T19:33:16.464Z,1663788796.464 [Startup] Running Loop=1 2022-09-21T19:33:16.464Z,1663788796.464 [Startup](DEBUG): Aggregate::initialize Startup 2022-09-21T19:33:16.464Z,1663788796.464 [Startup:A.GoToSurface] Running Loop=1 2022-09-21T19:33:16.464Z,1663788796.464 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-09-21T19:33:16.464Z,1663788796.464 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-09-21T19:33:16.465Z,1663788796.465 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-09-21T19:33:16.465Z,1663788796.465 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-09-21T19:33:16.466Z,1663788796.466 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-09-21T19:33:16.466Z,1663788796.466 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-09-21T19:33:16.467Z,1663788796.467 [Startup:StartupSatComms] Running Loop=1 2022-09-21T19:33:16.468Z,1663788796.468 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-09-21T19:33:16.468Z,1663788796.468 [Startup:StartupSatComms:A] Running Loop=1 2022-09-21T19:33:16.888Z,1663788796.888 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-09-21T19:34:03.920Z,1663788843.920 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004697 2022-09-21T19:34:16.657Z,1663788856.657 [Startup:StartupSatComms:A](INFO): Timed out from 2022-09-21T19:33:16.5Z 2022-09-21T19:34:16.657Z,1663788856.657 [Startup:StartupSatComms:A] Stopped 2022-09-21T19:34:16.657Z,1663788856.657 [Startup:StartupSatComms:B] Running Loop=1 2022-09-21T19:34:17.075Z,1663788857.075 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-09-21T19:34:36.915Z,1663788876.915 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-09-21T19:34:36.915Z,1663788876.915 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:34:36.979Z,1663788876.979 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:34:37.345Z,1663788877.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:34:37.346Z,1663788877.346 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-09-21T19:34:48.955Z,1663788888.955 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2022-09-21T19:34:48.955Z,1663788888.955 [RDI_Pathfinder] Communications Fault, FailCount= 1 2022-09-21T19:34:48.955Z,1663788888.955 [RDI_Pathfinder](ERROR): Communications Fault 2022-09-21T19:34:49.045Z,1663788889.045 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-09-21T19:34:49.525Z,1663788889.525 [RDI_Pathfinder](INFO): Powering down 2022-09-21T19:34:50.250Z,1663788890.250 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-09-21T19:34:50.250Z,1663788890.250 [RDI_Pathfinder] No Fault, FailCount= 1 2022-09-21T19:34:50.598Z,1663788890.598 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2022-09-21T19:35:12.810Z,1663788912.810 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2022-09-21T19:35:12.810Z,1663788912.810 [RDI_Pathfinder] Communications Fault, FailCount= 2 2022-09-21T19:35:12.810Z,1663788912.810 [RDI_Pathfinder](ERROR): Communications Fault 2022-09-21T19:35:12.925Z,1663788912.925 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-09-21T19:35:13.365Z,1663788913.365 [RDI_Pathfinder](INFO): Powering down 2022-09-21T19:35:14.113Z,1663788914.113 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-09-21T19:35:14.113Z,1663788914.113 [RDI_Pathfinder] No Fault, FailCount= 2 2022-09-21T19:35:14.415Z,1663788914.415 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2022-09-21T19:35:16.868Z,1663788916.868 [Startup:StartupSatComms:B](INFO): Timed out from 2022-09-21T19:34:16.7Z 2022-09-21T19:35:16.868Z,1663788916.868 [Startup:StartupSatComms:B] Stopped 2022-09-21T19:35:16.868Z,1663788916.868 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-09-21T19:35:16.868Z,1663788916.868 [Startup:StartupSatComms] Stopped 2022-09-21T19:35:16.868Z,1663788916.868 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-09-21T19:35:16.870Z,1663788916.870 [Startup](INFO): Completed Startup 2022-09-21T19:35:16.870Z,1663788916.870 [MissionManager](INFO): Startup is completed. 2022-09-21T19:35:16.870Z,1663788916.870 [MissionManager](INFO): Uninitializing Mission Startup 2022-09-21T19:35:16.870Z,1663788916.870 [Startup] Stopped 2022-09-21T19:35:16.870Z,1663788916.870 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-09-21T19:35:16.870Z,1663788916.870 [Startup:A.GoToSurface] Stopped 2022-09-21T19:35:16.870Z,1663788916.870 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-09-21T19:35:17.263Z,1663788917.263 [MissionManager](IMPORTANT): Started mission Default 2022-09-21T19:35:17.263Z,1663788917.263 [Default] Running Loop=1 2022-09-21T19:35:17.263Z,1663788917.263 [Default](DEBUG): Aggregate::initialize Default 2022-09-21T19:35:17.264Z,1663788917.264 [Default:B.GoToSurface] Running Loop=1 2022-09-21T19:35:17.264Z,1663788917.264 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-09-21T19:35:17.264Z,1663788917.264 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-09-21T19:35:17.264Z,1663788917.264 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-09-21T19:35:17.265Z,1663788917.265 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-09-21T19:35:17.265Z,1663788917.265 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-09-21T19:35:17.265Z,1663788917.265 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-09-21T19:35:17.266Z,1663788917.266 [Default:A.Wait] Running Loop=1 2022-09-21T19:35:17.266Z,1663788917.266 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-09-21T19:35:30.575Z,1663788930.575 [Default:A.Wait](INFO): Done Waiting. 2022-09-21T19:35:30.575Z,1663788930.575 [Default:A.Wait] Stopped 2022-09-21T19:35:30.575Z,1663788930.575 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-09-21T19:35:30.984Z,1663788930.984 [Default:CheckIn] Running Loop=1 2022-09-21T19:35:30.984Z,1663788930.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-09-21T19:35:30.984Z,1663788930.984 [Default:CheckIn:Read_GPS] Running Loop=1 2022-09-21T19:35:31.440Z,1663788931.440 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-09-21T19:35:36.627Z,1663788936.627 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2022-09-21T19:35:36.627Z,1663788936.627 [RDI_Pathfinder] Communications Fault, FailCount= 3 2022-09-21T19:35:36.627Z,1663788936.627 [RDI_Pathfinder](ERROR): Communications Fault 2022-09-21T19:35:36.725Z,1663788936.725 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2022-09-21T19:35:36.726Z,1663788936.726 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2022-09-21T19:35:37.101Z,1663788937.101 [RDI_Pathfinder](INFO): Powering down 2022-09-21T19:35:37.171Z,1663788937.171 [CBIT](INFO): Critical error at 20220921T193536 2022-09-21T19:37:18.850Z,1663789038.850 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-09-21T19:37:37.874Z,1663789057.874 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-09-21T19:37:37.874Z,1663789057.874 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:37:37.941Z,1663789057.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:37:38.321Z,1663789058.321 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:37:38.322Z,1663789058.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-09-21T19:38:36.889Z,1663789116.889 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-09-21T19:38:36.889Z,1663789116.889 [RDI_Pathfinder] No Fault, FailCount= 3 2022-09-21T19:38:37.216Z,1663789117.216 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2022-09-21T19:38:49.750Z,1663789129.750 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2022-09-21T19:38:49.750Z,1663789129.750 [RDI_Pathfinder] Data Fault, FailCount= 1 2022-09-21T19:38:49.750Z,1663789129.750 [RDI_Pathfinder](ERROR): Data Fault 2022-09-21T19:38:49.849Z,1663789129.849 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2022-09-21T19:38:50.230Z,1663789130.230 [RDI_Pathfinder](INFO): Powering down 2022-09-21T19:38:51.034Z,1663789131.034 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2022-09-21T19:38:51.034Z,1663789131.034 [RDI_Pathfinder] No Fault, FailCount= 1 2022-09-21T19:38:51.355Z,1663789131.355 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2022-09-21T19:39:06.772Z,1663789146.772 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder 2022-09-21T19:39:24.078Z,1663789164.078 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-09-21T19:39:24.080Z,1663789164.080 [BPC1](INFO): Received data from all battery sticks. 2022-09-21T19:40:31.172Z,1663789231.172 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-09-21T19:35:30.0Z 2022-09-21T19:40:31.172Z,1663789231.172 [Default:CheckIn:Read_GPS] Stopped 2022-09-21T19:40:31.172Z,1663789231.172 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-09-21T19:40:31.571Z,1663789231.571 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-09-21T19:40:38.865Z,1663789238.865 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-09-21T19:40:38.865Z,1663789238.865 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:40:38.939Z,1663789238.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:40:39.305Z,1663789239.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:40:39.305Z,1663789239.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-09-21T19:42:16.259Z,1663789336.259 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-09-21T19:42:16.259Z,1663789336.259 [NAL9602] Data Fault, FailCount= 1 2022-09-21T19:42:16.259Z,1663789336.259 [NAL9602](ERROR): Data Fault 2022-09-21T19:42:16.353Z,1663789336.353 [CBIT](ERROR): Data Fault in component: NAL9602 2022-09-21T19:42:16.642Z,1663789336.642 [NAL9602](INFO): Powering down 2022-09-21T19:42:17.549Z,1663789337.549 [CBIT](INFO): Clearing failed state for component NAL9602 2022-09-21T19:42:17.549Z,1663789337.549 [NAL9602] No Fault, FailCount= 1 2022-09-21T19:42:46.939Z,1663789366.939 [NAL9602](INFO): Powering up NAL9602 2022-09-21T19:42:57.851Z,1663789377.851 [NAL9602](INFO): NAL9602 initialized 2022-09-21T19:43:39.879Z,1663789419.879 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-09-21T19:43:39.879Z,1663789419.879 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:43:39.945Z,1663789419.945 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:43:40.390Z,1663789420.390 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:43:40.390Z,1663789420.390 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-09-21T19:46:37.717Z,1663789597.717 [CBIT](INFO): Clearing failed state for component DropWeight 2022-09-21T19:46:37.717Z,1663789597.717 [DropWeight] No Fault, FailCount= 1 2022-09-21T19:46:40.908Z,1663789600.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-09-21T19:46:40.908Z,1663789600.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:46:40.997Z,1663789600.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:46:41.358Z,1663789601.358 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:46:41.358Z,1663789601.358 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-09-21T19:49:41.894Z,1663789781.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-09-21T19:49:41.895Z,1663789781.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:49:42.005Z,1663789782.005 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:49:42.402Z,1663789782.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:49:42.402Z,1663789782.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-09-21T19:49:43.104Z,1663789783.104 [RDI_Pathfinder](ERROR): only read 0 of 4 data items 2022-09-21T19:52:42.952Z,1663789962.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2022-09-21T19:52:42.952Z,1663789962.952 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:52:43.025Z,1663789963.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:52:43.385Z,1663789963.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:52:43.385Z,1663789963.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2022-09-21T19:53:00.279Z,1663789980.279 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-09-21T19:53:00.279Z,1663789980.279 [NAL9602] Data Fault, FailCount= 2 2022-09-21T19:53:00.279Z,1663789980.279 [NAL9602](ERROR): Data Fault 2022-09-21T19:53:00.349Z,1663789980.349 [CBIT](ERROR): Data Fault in component: NAL9602 2022-09-21T19:53:00.690Z,1663789980.690 [NAL9602](INFO): Powering down 2022-09-21T19:53:01.577Z,1663789981.577 [CBIT](INFO): Clearing failed state for component NAL9602 2022-09-21T19:53:01.577Z,1663789981.577 [NAL9602] No Fault, FailCount= 2 2022-09-21T19:53:21.530Z,1663790001.530 [CommandExec](IMPORTANT): got command gfscan 2022-09-21T19:53:21.773Z,1663790001.773 [CBIT](IMPORTANT): Beginning ground fault scan 2022-09-21T19:53:30.995Z,1663790010.995 [NAL9602](INFO): Powering up NAL9602 2022-09-21T19:53:32.739Z,1663790012.739 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007594 CHAN A1 (24V): 0.000937 CHAN A2 (12V): -0.007191 CHAN A3 (5V): -0.002192 CHAN B0 (3.3V): 0.000028 CHAN B1 (3.15aV): -0.000620 CHAN B2 (3.15bV): 0.000420 CHAN B3 (GND): 0.002197 OPEN: 0.005224 Full Scale: +/- 1 mA 2022-09-21T19:53:41.891Z,1663790021.891 [NAL9602](INFO): NAL9602 initialized 2022-09-21T19:55:43.909Z,1663790143.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2022-09-21T19:55:43.909Z,1663790143.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:55:43.979Z,1663790143.979 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:55:44.385Z,1663790144.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:55:44.385Z,1663790144.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2022-09-21T19:58:44.975Z,1663790324.975 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2022-09-21T19:58:44.975Z,1663790324.975 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T19:58:45.010Z,1663790325.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T19:58:45.376Z,1663790325.376 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T19:58:45.376Z,1663790325.376 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2022-09-21T20:01:45.971Z,1663790505.971 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2022-09-21T20:01:45.971Z,1663790505.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:01:45.983Z,1663790505.983 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:01:46.397Z,1663790506.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:01:46.397Z,1663790506.397 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2022-09-21T20:03:44.323Z,1663790624.323 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-09-21T20:03:44.323Z,1663790624.323 [NAL9602] Data Fault, FailCount= 3 2022-09-21T20:03:44.323Z,1663790624.323 [NAL9602](ERROR): Data Fault 2022-09-21T20:03:44.378Z,1663790624.378 [CBIT](ERROR): Data Fault in component: NAL9602 2022-09-21T20:03:44.754Z,1663790624.754 [NAL9602](INFO): Powering down 2022-09-21T20:03:45.555Z,1663790625.555 [CBIT](INFO): Clearing failed state for component NAL9602 2022-09-21T20:03:45.555Z,1663790625.555 [NAL9602] No Fault, FailCount= 3 2022-09-21T20:04:15.023Z,1663790655.023 [NAL9602](INFO): Powering up NAL9602 2022-09-21T20:04:25.935Z,1663790665.935 [NAL9602](INFO): NAL9602 initialized 2022-09-21T20:04:46.993Z,1663790686.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2022-09-21T20:04:46.997Z,1663790686.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:04:47.016Z,1663790687.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:04:47.419Z,1663790687.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:04:47.419Z,1663790687.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2022-09-21T20:07:47.950Z,1663790867.950 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2022-09-21T20:07:47.950Z,1663790867.950 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:07:47.960Z,1663790867.960 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:07:48.372Z,1663790868.372 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:07:48.372Z,1663790868.372 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2022-09-21T20:10:48.950Z,1663791048.950 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2022-09-21T20:10:48.950Z,1663791048.950 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:10:48.981Z,1663791048.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:10:49.364Z,1663791049.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:10:49.364Z,1663791049.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2022-09-21T20:11:29.018Z,1663791089.018 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-09-21T20:13:50.018Z,1663791230.018 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2022-09-21T20:13:50.018Z,1663791230.018 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:13:50.028Z,1663791230.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:13:50.400Z,1663791230.400 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:13:50.400Z,1663791230.400 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2022-09-21T20:14:28.363Z,1663791268.363 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-09-21T20:14:28.363Z,1663791268.363 [NAL9602] Data Fault, FailCount= 4 2022-09-21T20:14:28.363Z,1663791268.363 [NAL9602](ERROR): Data Fault 2022-09-21T20:14:28.380Z,1663791268.380 [CBIT](ERROR): Data Fault in component: NAL9602 2022-09-21T20:14:28.775Z,1663791268.775 [NAL9602](INFO): Powering down 2022-09-21T20:14:29.591Z,1663791269.591 [CBIT](INFO): Clearing failed state for component NAL9602 2022-09-21T20:14:29.591Z,1663791269.591 [NAL9602] No Fault, FailCount= 4 2022-09-21T20:14:59.063Z,1663791299.063 [NAL9602](INFO): Powering up NAL9602 2022-09-21T20:15:01.462Z,1663791301.462 [CommandExec](IMPORTANT): got command failComponent 2022-09-21T20:15:01.462Z,1663791301.462 [CommandExec](IMPORTANT): Failed components: 2022-09-21T20:15:01.463Z,1663791301.463 [CommandExec](IMPORTANT): No failed Components. 2022-09-21T20:15:09.991Z,1663791309.991 [NAL9602](INFO): NAL9602 initialized 2022-09-21T20:15:16.219Z,1663791316.219 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2022-09-21T20:15:16.220Z,1663791316.220 [CommandExec](IMPORTANT): Onboard.Humidity 22.952021 % 2022-09-21T20:15:23.295Z,1663791323.295 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2022-09-21T20:15:23.295Z,1663791323.295 [CommandExec](IMPORTANT): Onboard.Pressure 53770.449219 Pa 2022-09-21T20:16:38.887Z,1663791398.887 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-09-21T20:16:38.888Z,1663791398.888 [DropWeight] Hardware Fault, FailCount= 1 2022-09-21T20:16:38.888Z,1663791398.888 [DropWeight](ERROR): Hardware Fault 2022-09-21T20:16:38.964Z,1663791398.964 [CBIT](INFO): Critical error at 20220921T201638 2022-09-21T20:16:38.972Z,1663791398.972 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-09-21T20:16:38.972Z,1663791398.972 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-09-21T20:16:39.330Z,1663791399.330 [CBIT](INFO): Critical error at 20220921T201638 2022-09-21T20:16:51.086Z,1663791411.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2022-09-21T20:16:51.086Z,1663791411.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:16:51.104Z,1663791411.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:16:51.455Z,1663791411.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:16:51.455Z,1663791411.455 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2022-09-21T20:19:52.102Z,1663791592.102 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2022-09-21T20:19:52.102Z,1663791592.102 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-09-21T20:19:52.134Z,1663791592.134 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-09-21T20:19:52.526Z,1663791592.526 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-09-21T20:19:52.526Z,1663791592.526 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2022-09-21T20:21:00.198Z,1663791660.198 [CommandExec](IMPORTANT): got command failComponent 2022-09-21T20:21:00.198Z,1663791660.198 [CommandExec](IMPORTANT): Failed components: 2022-09-21T20:21:00.198Z,1663791660.198 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2022-09-21T20:21:08.359Z,1663791668.359 [CommandExec](IMPORTANT): got command restart system 2022-09-21T20:21:10.482Z,1663791670.482 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:10.482Z,1663791670.482 [CommandExec](INFO): Uninitializing the command executive. 2022-09-21T20:21:10.482Z,1663791670.482 [CommandExec](INFO): Uninitializing the command scheduler. 2022-09-21T20:21:10.482Z,1663791670.482 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:10.613Z,1663791670.613 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-09-21T20:21:10.613Z,1663791670.613 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-09-21T20:21:10.614Z,1663791670.614 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:10.614Z,1663791670.614 [NavChartDb](INFO): Join timeout helper Thread ID is 1022 2022-09-21T20:21:10.662Z,1663791670.662 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:10.662Z,1663791670.662 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:10.677Z,1663791670.677 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2022-09-21T20:21:10.677Z,1663791670.677 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:10.678Z,1663791670.678 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1023 2022-09-21T20:21:10.758Z,1663791670.758 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:10.758Z,1663791670.758 [WetLabsUBAT](INFO): Powering down 2022-09-21T20:21:10.759Z,1663791670.759 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:10.765Z,1663791670.765 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-09-21T20:21:10.765Z,1663791670.765 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:10.766Z,1663791670.766 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1024 2022-09-21T20:21:11.079Z,1663791671.079 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:11.080Z,1663791671.080 [WetLabsBB2FL](INFO): Powering down 2022-09-21T20:21:11.082Z,1663791671.082 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.085Z,1663791671.085 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-09-21T20:21:11.085Z,1663791671.085 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.086Z,1663791671.086 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1025 2022-09-21T20:21:11.389Z,1663791671.389 [CTD_Seabird](INFO): Powering down 2022-09-21T20:21:11.401Z,1663791671.401 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:11.402Z,1663791671.402 [CTD_Seabird](INFO): Powering down 2022-09-21T20:21:11.417Z,1663791671.417 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.421Z,1663791671.421 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-09-21T20:21:11.421Z,1663791671.421 [DAT ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.422Z,1663791671.422 [DAT](INFO): Join timeout helper Thread ID is 1026 2022-09-21T20:21:11.482Z,1663791671.482 [DAT](INFO): Powering down 2022-09-21T20:21:11.550Z,1663791671.550 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:11.550Z,1663791671.550 [DAT](INFO): Powering down 2022-09-21T20:21:11.551Z,1663791671.551 [DAT ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.570Z,1663791671.570 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-09-21T20:21:11.570Z,1663791671.570 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.571Z,1663791671.571 [Radio_Surface](INFO): Join timeout helper Thread ID is 1027 2022-09-21T20:21:11.913Z,1663791671.913 [Radio_Surface](INFO): Powering down 2022-09-21T20:21:11.914Z,1663791671.914 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:11.915Z,1663791671.915 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.940Z,1663791671.940 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-09-21T20:21:11.940Z,1663791671.940 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:11.940Z,1663791671.940 [Onboard](INFO): Join timeout helper Thread ID is 1028 2022-09-21T20:21:14.145Z,1663791674.145 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:14.146Z,1663791674.146 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:14.166Z,1663791674.166 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-09-21T20:21:14.166Z,1663791674.166 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:14.167Z,1663791674.167 [DataOverHttps](INFO): Join timeout helper Thread ID is 1029 2022-09-21T20:21:15.097Z,1663791675.097 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:15.101Z,1663791675.101 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.123Z,1663791675.123 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-09-21T20:21:15.123Z,1663791675.123 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.124Z,1663791675.124 [BackseatComponent](INFO): Join timeout helper Thread ID is 1030 2022-09-21T20:21:15.149Z,1663791675.149 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:15.150Z,1663791675.150 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.159Z,1663791675.159 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-09-21T20:21:15.159Z,1663791675.159 [logger ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.159Z,1663791675.159 [logger](INFO): Join timeout helper Thread ID is 1031 2022-09-21T20:21:15.159Z,1663791675.159 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:15.160Z,1663791675.160 [logger ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.161Z,1663791675.161 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-09-21T20:21:15.161Z,1663791675.161 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.162Z,1663791675.162 [CommandLine](INFO): Join timeout helper Thread ID is 1032 2022-09-21T20:21:15.189Z,1663791675.189 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-09-21T20:21:15.190Z,1663791675.190 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.202Z,1663791675.202 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-09-21T20:21:15.202Z,1663791675.202 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.202Z,1663791675.202 [CommandExec](INFO): Join timeout helper Thread ID is 1033 2022-09-21T20:21:15.204Z,1663791675.204 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-09-21T20:21:15.204Z,1663791675.204 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-09-21T20:21:15.204Z,1663791675.204 [controlThread](INFO): Join timeout helper Thread