2022-11-28T19:45:25.542Z,1669664725.542 [Supervisor](DEBUG): Initializing supervisor. 2022-11-28T19:45:25.547Z,1669664725.547 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-11-28T19:45:25.547Z,1669664725.547 [SyncHandler](INFO): Protected caller Thread ID is 8068 2022-11-28T19:45:25.548Z,1669664725.548 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-11-28T19:45:25.549Z,1669664725.549 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-11-28T19:45:25.549Z,1669664725.549 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8069 2022-11-28T19:45:25.553Z,1669664725.553 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-11-28T19:45:25.570Z,1669664725.570 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-11-28T19:45:25.571Z,1669664725.571 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-11-28T19:45:25.571Z,1669664725.571 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8070 2022-11-28T19:45:25.576Z,1669664725.576 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-11-28T19:45:25.577Z,1669664725.577 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-11-28T19:45:25.577Z,1669664725.577 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8071 2022-11-28T19:45:25.579Z,1669664725.579 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-11-28T19:45:25.580Z,1669664725.580 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-11-28T19:45:25.581Z,1669664725.581 [logger ThreadHandler](INFO): Protected caller Thread ID is 8072 2022-11-28T19:45:25.584Z,1669664725.584 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-11-28T19:45:25.585Z,1669664725.585 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-11-28T19:45:25.586Z,1669664725.586 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-11-28T19:45:26.008Z,1669664726.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-11-28T19:45:26.008Z,1669664726.008 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-11-28T19:45:26.302Z,1669664726.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-11-28T19:45:26.303Z,1669664726.303 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-11-28T19:45:26.383Z,1669664726.383 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-11-28T19:45:26.595Z,1669664726.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-11-28T19:45:26.596Z,1669664726.596 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-11-28T19:45:26.673Z,1669664726.673 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-11-28T19:45:26.766Z,1669664726.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-11-28T19:45:26.767Z,1669664726.767 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-11-28T19:45:27.090Z,1669664727.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-11-28T19:45:27.091Z,1669664727.091 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-11-28T19:45:27.218Z,1669664727.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-11-28T19:45:27.219Z,1669664727.219 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-11-28T19:45:27.711Z,1669664727.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-11-28T19:45:27.712Z,1669664727.712 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-11-28T19:45:27.888Z,1669664727.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-11-28T19:45:27.889Z,1669664727.889 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-11-28T19:45:28.248Z,1669664728.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-11-28T19:45:28.249Z,1669664728.249 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-11-28T19:45:28.518Z,1669664728.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-11-28T19:45:28.518Z,1669664728.518 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-11-28T19:45:28.626Z,1669664728.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-11-28T19:45:28.627Z,1669664728.627 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-11-28T19:45:29.325Z,1669664729.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-11-28T19:45:29.326Z,1669664729.326 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-11-28T19:45:30.231Z,1669664730.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-11-28T19:45:30.232Z,1669664730.232 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-11-28T19:45:30.454Z,1669664730.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-11-28T19:45:30.456Z,1669664730.456 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2022-11-28T19:45:30.457Z,1669664730.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2022-11-28T19:45:30.645Z,1669664730.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2022-11-28T19:45:30.728Z,1669664730.728 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2022-11-28T19:45:30.862Z,1669664730.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2022-11-28T19:45:30.947Z,1669664730.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2022-11-28T19:45:31.207Z,1669664731.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-11-28T19:45:31.207Z,1669664731.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2022-11-28T19:45:31.308Z,1669664731.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2022-11-28T19:45:31.417Z,1669664731.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2022-11-28T19:45:31.521Z,1669664731.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2022-11-28T19:45:31.659Z,1669664731.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2022-11-28T19:45:31.754Z,1669664731.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2022-11-28T19:45:31.846Z,1669664731.846 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2022-11-28T19:45:31.846Z,1669664731.846 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-11-28T19:45:31.859Z,1669664731.859 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-11-28T19:45:31.875Z,1669664731.875 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-11-28T19:45:31.875Z,1669664731.875 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-11-28T19:45:31.992Z,1669664731.992 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-11-28T19:45:31.993Z,1669664731.993 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-11-28T19:45:32.060Z,1669664732.060 [VerticalControl](DEBUG): Construct VerticalControl. 2022-11-28T19:45:32.119Z,1669664732.119 [VerticalControl] Loaded 2022-11-28T19:45:32.119Z,1669664732.119 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-11-28T19:45:32.122Z,1669664732.122 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-11-28T19:45:32.162Z,1669664732.162 [HorizontalControl] Loaded 2022-11-28T19:45:32.162Z,1669664732.162 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-11-28T19:45:32.165Z,1669664732.165 [SpeedControl](DEBUG): Construct SpeedControl. 2022-11-28T19:45:32.168Z,1669664732.168 [SpeedControl] Loaded 2022-11-28T19:45:32.168Z,1669664732.168 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-11-28T19:45:32.171Z,1669664732.171 [LoopControl](DEBUG): Construct LoopControl. 2022-11-28T19:45:32.171Z,1669664732.171 [LoopControl] Loaded 2022-11-28T19:45:32.171Z,1669664732.171 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-11-28T19:45:32.172Z,1669664732.172 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-11-28T19:45:32.173Z,1669664732.173 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-11-28T19:45:32.321Z,1669664732.321 [BuoyancyServo] Loaded 2022-11-28T19:45:32.321Z,1669664732.321 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-11-28T19:45:32.340Z,1669664732.340 [ElevatorServo] Loaded 2022-11-28T19:45:32.341Z,1669664732.341 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-11-28T19:45:32.360Z,1669664732.360 [MassServo] Loaded 2022-11-28T19:45:32.360Z,1669664732.360 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-11-28T19:45:32.379Z,1669664732.379 [RudderServo] Loaded 2022-11-28T19:45:32.379Z,1669664732.379 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-11-28T19:45:32.394Z,1669664732.394 [ThrusterHE] Loaded 2022-11-28T19:45:32.394Z,1669664732.394 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-11-28T19:45:32.394Z,1669664732.394 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-11-28T19:45:32.395Z,1669664732.395 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-11-28T19:45:32.465Z,1669664732.465 [DepthRateCalculator] Loaded 2022-11-28T19:45:32.466Z,1669664732.466 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-11-28T19:45:32.470Z,1669664732.470 [PitchRateCalculator] Loaded 2022-11-28T19:45:32.471Z,1669664732.471 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-11-28T19:45:32.480Z,1669664732.480 [SpeedCalculator] Loaded 2022-11-28T19:45:32.481Z,1669664732.481 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-11-28T19:45:32.485Z,1669664732.485 [YawRateCalculator] Loaded 2022-11-28T19:45:32.485Z,1669664732.485 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-11-28T19:45:32.503Z,1669664732.503 [ElevatorOffsetCalculator] Loaded 2022-11-28T19:45:32.503Z,1669664732.503 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-11-28T19:45:32.504Z,1669664732.504 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-11-28T19:45:32.504Z,1669664732.504 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-11-28T19:45:32.640Z,1669664732.640 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-11-28T19:45:32.640Z,1669664732.640 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-11-28T19:45:32.654Z,1669664732.654 [NavChart] Loaded 2022-11-28T19:45:32.654Z,1669664732.654 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-11-28T19:45:32.659Z,1669664732.659 [UniversalFixResidualReporter] Loaded 2022-11-28T19:45:32.660Z,1669664732.660 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-11-28T19:45:32.660Z,1669664732.660 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-11-28T19:45:32.661Z,1669664732.661 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-11-28T19:45:33.351Z,1669664733.351 [AHRS_M2] Loaded 2022-11-28T19:45:33.351Z,1669664733.351 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-11-28T19:45:33.604Z,1669664733.604 [BackseatComponent] Loaded 2022-11-28T19:45:33.604Z,1669664733.604 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-11-28T19:45:33.605Z,1669664733.605 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0 2022-11-28T19:45:33.606Z,1669664733.606 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8155 2022-11-28T19:45:33.608Z,1669664733.608 [LcmUniversalReporter] Loaded 2022-11-28T19:45:33.609Z,1669664733.609 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-11-28T19:45:34.527Z,1669664734.527 [BPC1] Loaded 2022-11-28T19:45:34.527Z,1669664734.527 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-11-28T19:45:34.876Z,1669664734.876 [DataOverHttps] Loaded 2022-11-28T19:45:34.876Z,1669664734.876 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-11-28T19:45:34.877Z,1669664734.877 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0 2022-11-28T19:45:34.877Z,1669664734.877 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8156 2022-11-28T19:45:34.898Z,1669664734.898 [Depth_Keller] Loaded 2022-11-28T19:45:34.898Z,1669664734.898 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-11-28T19:45:34.903Z,1669664734.903 [DropWeight] Loaded 2022-11-28T19:45:34.904Z,1669664734.904 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-11-28T19:45:34.965Z,1669664734.965 [NAL9602] Loaded 2022-11-28T19:45:34.965Z,1669664734.965 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-11-28T19:45:35.070Z,1669664735.070 [Onboard] Loaded 2022-11-28T19:45:35.070Z,1669664735.070 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-11-28T19:45:35.072Z,1669664735.072 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0 2022-11-28T19:45:35.072Z,1669664735.072 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8157 2022-11-28T19:45:35.092Z,1669664735.092 [Power24vConverter] Loaded 2022-11-28T19:45:35.100Z,1669664735.100 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-11-28T19:45:35.146Z,1669664735.146 [Radio_Surface] Loaded 2022-11-28T19:45:35.146Z,1669664735.146 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-11-28T19:45:35.182Z,1669664735.182 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0 2022-11-28T19:45:35.182Z,1669664735.182 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8158 2022-11-28T19:45:35.277Z,1669664735.277 [DAT] Loaded 2022-11-28T19:45:35.278Z,1669664735.278 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-11-28T19:45:35.279Z,1669664735.279 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409904E0 2022-11-28T19:45:35.279Z,1669664735.279 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8159 2022-11-28T19:45:35.280Z,1669664735.280 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-11-28T19:45:35.280Z,1669664735.280 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-11-28T19:45:35.363Z,1669664735.363 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-11-28T19:45:35.364Z,1669664735.364 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-11-28T19:45:35.552Z,1669664735.552 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-11-28T19:45:35.552Z,1669664735.552 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-11-28T19:45:35.598Z,1669664735.598 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-11-28T19:45:35.599Z,1669664735.599 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-11-28T19:45:35.824Z,1669664735.824 [CTD_Seabird] Loaded 2022-11-28T19:45:35.824Z,1669664735.824 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-11-28T19:45:35.825Z,1669664735.825 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0 2022-11-28T19:45:35.826Z,1669664735.826 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8160 2022-11-28T19:45:35.846Z,1669664735.846 [PAR_Licor] Loaded 2022-11-28T19:45:35.846Z,1669664735.846 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-11-28T19:45:35.876Z,1669664735.876 [WetLabsBB2FL] Loaded 2022-11-28T19:45:35.877Z,1669664735.877 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-11-28T19:45:35.878Z,1669664735.878 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0 2022-11-28T19:45:35.878Z,1669664735.878 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8161 2022-11-28T19:45:35.879Z,1669664735.879 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-11-28T19:45:35.880Z,1669664735.880 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-11-28T19:45:36.251Z,1669664736.251 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-11-28T19:45:36.252Z,1669664736.252 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-11-28T19:45:36.422Z,1669664736.422 [SBIT](DEBUG): Construct Startup Built In Test. 2022-11-28T19:45:36.431Z,1669664736.431 [SBIT] Loaded 2022-11-28T19:45:36.431Z,1669664736.431 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-11-28T19:45:36.434Z,1669664736.434 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-11-28T19:45:36.447Z,1669664736.447 [IBIT] Loaded 2022-11-28T19:45:36.447Z,1669664736.447 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-11-28T19:45:36.453Z,1669664736.453 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-11-28T19:45:36.557Z,1669664736.557 [CBIT] Loaded 2022-11-28T19:45:36.558Z,1669664736.558 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-11-28T19:45:36.558Z,1669664736.558 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-11-28T19:45:36.564Z,1669664736.564 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-11-28T19:45:36.567Z,1669664736.567 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-11-28T19:45:36.578Z,1669664736.578 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-11-28T19:45:36.579Z,1669664736.579 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0 2022-11-28T19:45:36.579Z,1669664736.579 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8162 2022-11-28T19:45:36.584Z,1669664736.584 [Supervisor](INFO): Main Thread ID is 8067 2022-11-28T19:45:36.584Z,1669664736.584 [Supervisor](DEBUG): Running supervisor. 2022-11-28T19:45:36.585Z,1669664736.585 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8163 2022-11-28T19:45:36.585Z,1669664736.585 [CommandExec](INFO): Initializing the command executive. 2022-11-28T19:45:36.587Z,1669664736.587 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8164 2022-11-28T19:45:36.589Z,1669664736.589 [controlThread ThreadHandler](INFO): Handler Thread ID is 8165 2022-11-28T19:45:36.589Z,1669664736.589 [controlThread](DEBUG): Initializing ControlThread 2022-11-28T19:45:36.590Z,1669664736.590 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-11-28T19:45:36.592Z,1669664736.592 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-11-28T19:45:36.593Z,1669664736.593 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-11-28T19:45:36.593Z,1669664736.593 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-11-28T19:45:36.595Z,1669664736.595 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-11-28T19:45:36.595Z,1669664736.595 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-11-28T19:45:36.595Z,1669664736.595 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-11-28T19:45:36.596Z,1669664736.596 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-11-28T19:45:36.596Z,1669664736.596 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-11-28T19:45:36.597Z,1669664736.597 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-11-28T19:45:36.597Z,1669664736.597 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-11-28T19:45:36.605Z,1669664736.605 [SBIT](INFO): Initialize SBIT Component. 2022-11-28T19:45:36.605Z,1669664736.605 [SBIT](IMPORTANT): git: 2022-08-02 2022-11-28T19:45:36.605Z,1669664736.605 [SBIT](INFO): git hash: f84e5919489b70ff94ab72b61eefa777bac519a0 2022-11-28T19:45:36.606Z,1669664736.606 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-11-28T19:45:36.607Z,1669664736.607 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-11-28T19:45:36.608Z,1669664736.608 [SBIT](INFO): Beginning SBIT in 61.000000 seconds. 2022-11-28T19:45:36.609Z,1669664736.609 [IBIT](INFO): Initialize IBIT Component. 2022-11-28T19:45:36.609Z,1669664736.609 [CBIT](DEBUG): Initialize CBIT Component. 2022-11-28T19:45:36.610Z,1669664736.610 [logger ThreadHandler](INFO): Handler Thread ID is 8166 2022-11-28T19:45:36.620Z,1669664736.620 [CBIT](DEBUG): Initialized mux pins. 2022-11-28T19:45:36.620Z,1669664736.620 [CBIT](DEBUG): Initializing the watchdog timer. 2022-11-28T19:45:36.629Z,1669664736.629 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8167 2022-11-28T19:45:36.641Z,1669664736.641 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8168 2022-11-28T19:45:36.642Z,1669664736.642 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-11-28T19:45:36.645Z,1669664736.645 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-11-28T19:45:36.645Z,1669664736.645 [CBIT](DEBUG): Initializing heartbeat. 2022-11-28T19:45:36.653Z,1669664736.653 [Onboard ThreadHandler](INFO): Handler Thread ID is 8169 2022-11-28T19:45:36.670Z,1669664736.670 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8170 2022-11-28T19:45:36.689Z,1669664736.689 [DAT ThreadHandler](INFO): Handler Thread ID is 8171 2022-11-28T19:45:36.690Z,1669664736.690 [DAT](INFO): Powering up 2022-11-28T19:45:36.690Z,1669664736.690 [DAT](DEBUG): Initializing DAT. 2022-11-28T19:45:36.694Z,1669664736.694 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8173 2022-11-28T19:45:36.694Z,1669664736.694 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-11-28T19:45:36.698Z,1669664736.698 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8175 2022-11-28T19:45:36.701Z,1669664736.701 [WetLabsBB2FL](INFO): Powering up 2022-11-28T19:45:36.702Z,1669664736.702 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8177 2022-11-28T19:45:36.710Z,1669664736.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000 2022-11-28T19:45:36.710Z,1669664736.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000 2022-11-28T19:45:36.711Z,1669664736.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000 2022-11-28T19:45:36.711Z,1669664736.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000 2022-11-28T19:45:36.711Z,1669664736.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000 2022-11-28T19:45:36.711Z,1669664736.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000 2022-11-28T19:45:36.711Z,1669664736.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000 2022-11-28T19:45:36.711Z,1669664736.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000 2022-11-28T19:45:36.712Z,1669664736.712 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000 2022-11-28T19:45:36.712Z,1669664736.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000 2022-11-28T19:45:36.712Z,1669664736.712 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000 2022-11-28T19:45:36.712Z,1669664736.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000 2022-11-28T19:45:36.712Z,1669664736.712 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000 2022-11-28T19:45:36.713Z,1669664736.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000 2022-11-28T19:45:36.713Z,1669664736.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DD.000 2022-11-28T19:45:36.713Z,1669664736.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DD.000 2022-11-28T19:45:36.713Z,1669664736.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DE.000 2022-11-28T19:45:36.713Z,1669664736.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DE.000 2022-11-28T19:45:36.713Z,1669664736.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DF.000 2022-11-28T19:45:36.714Z,1669664736.714 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DF.000 2022-11-28T19:45:36.714Z,1669664736.714 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DG.000 2022-11-28T19:45:36.714Z,1669664736.714 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DG.000 2022-11-28T19:45:36.714Z,1669664736.714 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DH.000 2022-11-28T19:45:36.714Z,1669664736.714 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DH.000 2022-11-28T19:45:36.714Z,1669664736.714 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CC.000 2022-11-28T19:45:36.715Z,1669664736.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CC.000 2022-11-28T19:45:36.715Z,1669664736.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CD.000 2022-11-28T19:45:36.715Z,1669664736.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CD.000 2022-11-28T19:45:36.715Z,1669664736.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CE.000 2022-11-28T19:45:36.715Z,1669664736.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CE.000 2022-11-28T19:45:36.715Z,1669664736.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QE.000 2022-11-28T19:45:36.716Z,1669664736.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QE.000 2022-11-28T19:45:36.716Z,1669664736.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QF.000 2022-11-28T19:45:36.716Z,1669664736.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QF.000 2022-11-28T19:45:36.716Z,1669664736.716 [CBIT](DEBUG): Deactivating GF circuits. 2022-11-28T19:45:36.716Z,1669664736.716 [CBIT](DEBUG): Deactivating emergency mode. 2022-11-28T19:45:36.717Z,1669664736.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QG.000 2022-11-28T19:45:36.717Z,1669664736.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QG.000 2022-11-28T19:45:36.717Z,1669664736.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PB.000 2022-11-28T19:45:36.717Z,1669664736.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PB.000 2022-11-28T19:45:36.718Z,1669664736.718 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PC.000 2022-11-28T19:45:36.718Z,1669664736.718 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PC.000 2022-11-28T19:45:36.718Z,1669664736.718 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PD.000 2022-11-28T19:45:36.718Z,1669664736.718 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PD.000 2022-11-28T19:45:36.718Z,1669664736.718 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PE.000 2022-11-28T19:45:36.718Z,1669664736.718 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PE.000 2022-11-28T19:45:36.719Z,1669664736.719 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PF.000 2022-11-28T19:45:36.719Z,1669664736.719 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PF.000 2022-11-28T19:45:36.719Z,1669664736.719 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PG.000 2022-11-28T19:45:36.719Z,1669664736.719 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PG.000 2022-11-28T19:45:36.719Z,1669664736.719 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5WI22M.000 2022-11-28T19:45:36.719Z,1669664736.719 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5WI22M.000 2022-11-28T19:45:36.752Z,1669664736.752 [CBIT](DEBUG): Backplane powered. 2022-11-28T19:45:36.753Z,1669664736.753 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-11-28T19:45:36.794Z,1669664736.794 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-11-28T19:45:36.825Z,1669664736.825 [MissionManager](DEBUG): 2022-11-28T19:45:36.826Z,1669664736.826 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-11-28T19:45:36.901Z,1669664736.901 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-11-28T19:45:36.902Z,1669664736.902 [Default:A.Wait](DEBUG): Construct Wait. 2022-11-28T19:45:36.905Z,1669664736.905 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-11-28T19:45:36.962Z,1669664736.962 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-11-28T19:45:36.980Z,1669664736.980 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-11-28T19:45:36.986Z,1669664736.986 [Default:E.Execute](DEBUG): Construct Execute. 2022-11-28T19:45:37.009Z,1669664737.009 [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-11-28T19:45:37.014Z,1669664737.014 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-11-28T19:45:37.024Z,1669664737.024 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-11-28T19:45:37.076Z,1669664737.076 [Radio_Surface](INFO): Powering up 2022-11-28T19:45:37.091Z,1669664737.091 [Power24vConverter](INFO): Powering up. 2022-11-28T19:45:37.172Z,1669664737.172 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-11-28T19:45:37.181Z,1669664737.181 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-11-28T19:45:37.182Z,1669664737.182 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-11-28T19:45:37.189Z,1669664737.189 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-11-28T19:45:37.190Z,1669664737.190 [MassServo](DEBUG): Initializing EZServoServo. 2022-11-28T19:45:37.201Z,1669664737.201 [MassServo](DEBUG): Initializing MassServo. 2022-11-28T19:45:37.202Z,1669664737.202 [RudderServo](DEBUG): Initializing EZServoServo. 2022-11-28T19:45:37.209Z,1669664737.209 [RudderServo](DEBUG): Initializing RudderServo. 2022-11-28T19:45:37.210Z,1669664737.210 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-11-28T19:45:37.217Z,1669664737.217 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-11-28T19:45:38.181Z,1669664738.181 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-11-28T19:45:38.181Z,1669664738.181 [RudderServo](FAULT): Rudder failed to initialize 2022-11-28T19:45:38.181Z,1669664738.181 [RudderServo] Communications Fault, FailCount= 1 2022-11-28T19:45:38.181Z,1669664738.181 [RudderServo](ERROR): Communications Fault 2022-11-28T19:45:38.187Z,1669664738.187 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-11-28T19:45:38.397Z,1669664738.397 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-11-28T19:45:38.397Z,1669664738.397 [RudderServo](INFO): Powering down 2022-11-28T19:45:38.808Z,1669664738.808 [WetLabsBB2FL](INFO): Powering down 2022-11-28T19:45:39.061Z,1669664739.061 [RudderServo](DEBUG): Initializing EZServoServo. 2022-11-28T19:45:39.181Z,1669664739.181 [RudderServo](DEBUG): Initializing RudderServo. 2022-11-28T19:45:39.185Z,1669664739.185 [CBIT](INFO): Clearing failed state for component RudderServo 2022-11-28T19:45:39.185Z,1669664739.185 [RudderServo] No Fault, FailCount= 1 2022-11-28T19:45:42.957Z,1669664742.957 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-11-28T19:45:46.849Z,1669664746.849 [CBIT](CRITICAL): Environmental Failure. Press:14.920335 PSI. Humidity:38%. Temp:24 C. ABORTING MISSION 2022-11-28T19:45:46.849Z,1669664746.849 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2022-11-28T19:45:47.250Z,1669664747.250 [CommandExec](FAULT): Scheduling is paused 2022-11-28T19:45:47.251Z,1669664747.251 [CBIT](INFO): Critical error at 20221128T194546 2022-11-28T19:45:50.884Z,1669664750.884 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004753 2022-11-28T19:45:51.559Z,1669664751.559 [DAT](INFO): commRate: 800 2022-11-28T19:45:53.624Z,1669664753.624 [DAT](INFO): entering command mode 2022-11-28T19:45:53.825Z,1669664753.825 [DAT](INFO): setting verbose to 3 2022-11-28T19:45:54.078Z,1669664754.078 [DAT](INFO): set verbose to 3 2022-11-28T19:45:54.078Z,1669664754.078 [DAT](INFO): setting DatVerbose to 27440 2022-11-28T19:45:54.330Z,1669664754.330 [DAT](INFO): set DatVerbose to 27440 2022-11-28T19:45:54.330Z,1669664754.330 [DAT](INFO): setting transmit power to 8 2022-11-28T19:45:54.582Z,1669664754.582 [DAT](INFO): set transmit power to 8 2022-11-28T19:45:54.582Z,1669664754.582 [DAT](INFO): setting local address to 5 2022-11-28T19:45:54.834Z,1669664754.834 [DAT](INFO): set local address to 5 2022-11-28T19:45:54.835Z,1669664754.835 [DAT](INFO): Setting time to: 19:45:54 And date to:11/28/2022 2022-11-28T19:45:55.086Z,1669664755.086 [DAT](INFO): Local DAT time set to Mon Nov 28, 2022 19:45:54 2022-11-28T19:46:05.306Z,1669664765.306 [NAL9602](INFO): Powering up NAL9602 2022-11-28T19:46:16.218Z,1669664776.218 [NAL9602](INFO): NAL9602 initialized 2022-11-28T19:46:30.334Z,1669664790.334 [CommandExec](IMPORTANT): got command show variable dataOverHttps 2022-11-28T19:46:30.499Z,1669664790.499 [CommandExec](IMPORTANT): DataOverHttps.loadAtStartup (bool) 2022-11-28T19:46:30.500Z,1669664790.500 [CommandExec](IMPORTANT): DataOverHttps.connectionTimeout (second) 2022-11-28T19:46:30.500Z,1669664790.500 [CommandExec](IMPORTANT): DataOverHttps.period (second) 2022-11-28T19:46:30.501Z,1669664790.501 [CommandExec](IMPORTANT): DataOverHttps.power (watt) 2022-11-28T19:46:30.501Z,1669664790.501 [CommandExec](IMPORTANT): DataOverHttps.timeout (minute) 2022-11-28T19:46:30.502Z,1669664790.502 [CommandExec](IMPORTANT): DataOverHttps.verbosity (count) 2022-11-28T19:46:30.579Z,1669664790.579 [CommandExec](IMPORTANT): DataOverHttps.enableBroadcast (bool) 2022-11-28T19:46:30.580Z,1669664790.580 [CommandExec](IMPORTANT): DataOverHttps.platform_communications (bool) 2022-11-28T19:46:30.580Z,1669664790.580 [CommandExec](IMPORTANT): DataOverHttps.connectionStatus (bool) 2022-11-28T19:46:38.088Z,1669664798.088 [SBIT](IMPORTANT): Beginning Startup BIT 2022-11-28T19:46:38.093Z,1669664798.093 [CBIT](IMPORTANT): Beginning ground fault scan 2022-11-28T19:46:45.542Z,1669664805.542 [CommandExec](IMPORTANT): got command get DataOverHttps.connectionStatus 2022-11-28T19:46:45.542Z,1669664805.542 [CommandExec](IMPORTANT): DataOverHttps.connectionStatus 1 bool 2022-11-28T19:46:48.973Z,1669664808.973 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.018831 CHAN A1 (24V): -0.007887 CHAN A2 (12V): -0.004852 CHAN A3 (5V): -0.003117 CHAN B0 (3.3V): -0.001152 CHAN B1 (3.15aV): -0.001128 CHAN B2 (3.15bV): -0.001046 CHAN B3 (GND): 0.002646 OPEN: 0.004880 Full Scale: +/- 1 mA 2022-11-28T19:46:50.555Z,1669664810.555 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-11-28T19:47:03.888Z,1669664823.888 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-11-28T19:47:17.220Z,1669664837.220 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-11-28T19:47:30.550Z,1669664850.550 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-11-28T19:47:32.206Z,1669664852.206 [SBIT](IMPORTANT): SBIT PASSED 2022-11-28T19:47:32.207Z,1669664852.207 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-11-28T19:47:32.207Z,1669664852.207 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2022-11-28T19:47:32.207Z,1669664852.207 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool; 2022-11-28T19:47:32.208Z,1669664852.208 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool; 2022-11-28T19:47:32.208Z,1669664852.208 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit; 2022-11-28T19:47:32.208Z,1669664852.208 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2022-11-28T19:47:32.208Z,1669664852.208 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2022-11-28T19:47:32.209Z,1669664852.209 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2022-11-28T19:47:32.209Z,1669664852.209 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=30 ampere_hour; 2022-11-28T19:47:32.209Z,1669664852.209 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2022-11-28T19:47:32.209Z,1669664852.209 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter; 2022-11-28T19:47:32.209Z,1669664852.209 [SBIT](IMPORTANT): VerticalControl.massDefault=10.355450 millimeter; 2022-11-28T19:47:32.603Z,1669664852.603 [MissionManager](IMPORTANT): Started mission Startup 2022-11-28T19:47:32.603Z,1669664852.603 [Startup] Running Loop=1 2022-11-28T19:47:32.603Z,1669664852.603 [Startup](DEBUG): Aggregate::initialize Startup 2022-11-28T19:47:32.603Z,1669664852.603 [Startup:A.GoToSurface] Running Loop=1 2022-11-28T19:47:32.603Z,1669664852.603 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-28T19:47:32.604Z,1669664852.604 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-28T19:47:32.604Z,1669664852.604 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-28T19:47:32.605Z,1669664852.605 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-28T19:47:32.605Z,1669664852.605 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-28T19:47:32.606Z,1669664852.606 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-28T19:47:32.608Z,1669664852.608 [Startup:StartupSatComms] Running Loop=1 2022-11-28T19:47:32.608Z,1669664852.608 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-11-28T19:47:32.608Z,1669664852.608 [Startup:StartupSatComms:A] Running Loop=1 2022-11-28T19:47:33.011Z,1669664853.011 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-11-28T19:47:43.883Z,1669664863.883 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-11-28T19:47:57.214Z,1669664877.214 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2022-11-28T19:48:32.793Z,1669664912.793 [Startup:StartupSatComms:A](INFO): Timed out from 2022-11-28T19:47:32.6Z 2022-11-28T19:48:32.794Z,1669664912.794 [Startup:StartupSatComms:A] Stopped 2022-11-28T19:48:32.794Z,1669664912.794 [Startup:StartupSatComms:B] Running Loop=1 2022-11-28T19:48:33.187Z,1669664913.187 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-11-28T19:48:36.985Z,1669664916.985 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-11-28T19:48:36.985Z,1669664916.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-11-28T19:48:37.019Z,1669664917.019 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-11-28T19:48:37.281Z,1669664917.281 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-11-28T19:48:37.281Z,1669664917.281 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-11-28T19:49:33.051Z,1669664973.051 [Startup:StartupSatComms:B](INFO): Timed out from 2022-11-28T19:48:32.8Z 2022-11-28T19:49:33.051Z,1669664973.051 [Startup:StartupSatComms:B] Stopped 2022-11-28T19:49:33.052Z,1669664973.052 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-11-28T19:49:33.052Z,1669664973.052 [Startup:StartupSatComms] Stopped 2022-11-28T19:49:33.052Z,1669664973.052 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-11-28T19:49:33.053Z,1669664973.053 [Startup](INFO): Completed Startup 2022-11-28T19:49:33.053Z,1669664973.053 [MissionManager](INFO): Startup is completed. 2022-11-28T19:49:33.053Z,1669664973.053 [MissionManager](INFO): Uninitializing Mission Startup 2022-11-28T19:49:33.053Z,1669664973.053 [Startup] Stopped 2022-11-28T19:49:33.053Z,1669664973.053 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-11-28T19:49:33.053Z,1669664973.053 [Startup:A.GoToSurface] Stopped 2022-11-28T19:49:33.053Z,1669664973.053 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-11-28T19:49:33.458Z,1669664973.458 [MissionManager](IMPORTANT): Started mission Default 2022-11-28T19:49:33.459Z,1669664973.459 [Default] Running Loop=1 2022-11-28T19:49:33.459Z,1669664973.459 [Default](DEBUG): Aggregate::initialize Default 2022-11-28T19:49:33.459Z,1669664973.459 [Default:B.GoToSurface] Running Loop=1 2022-11-28T19:49:33.459Z,1669664973.459 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-11-28T19:49:33.459Z,1669664973.459 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-11-28T19:49:33.460Z,1669664973.460 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-11-28T19:49:33.460Z,1669664973.460 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-11-28T19:49:33.461Z,1669664973.461 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-11-28T19:49:33.461Z,1669664973.461 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-11-28T19:49:33.461Z,1669664973.461 [Default:A.Wait] Running Loop=1 2022-11-28T19:49:33.462Z,1669664973.462 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-11-28T19:49:46.914Z,1669664986.914 [Default:A.Wait](INFO): Done Waiting. 2022-11-28T19:49:46.914Z,1669664986.914 [Default:A.Wait] Stopped 2022-11-28T19:49:46.914Z,1669664986.914 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-11-28T19:49:47.271Z,1669664987.271 [Default:CheckIn] Running Loop=1 2022-11-28T19:49:47.271Z,1669664987.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-11-28T19:49:47.271Z,1669664987.271 [Default:CheckIn:Read_GPS] Running Loop=1 2022-11-28T19:49:47.684Z,1669664987.684 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-11-28T19:50:29.810Z,1669665029.810 [CommandExec](IMPORTANT): got command restart system 2022-11-28T19:50:31.865Z,1669665031.865 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-28T19:50:31.865Z,1669665031.865 [CommandExec](INFO): Uninitializing the command executive. 2022-11-28T19:50:31.865Z,1669665031.865 [CommandExec](INFO): Uninitializing the command scheduler. 2022-11-28T19:50:31.865Z,1669665031.865 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:31.924Z,1669665031.924 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-11-28T19:50:31.925Z,1669665031.925 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-11-28T19:50:31.925Z,1669665031.925 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:31.926Z,1669665031.926 [NavChartDb](INFO): Join timeout helper Thread ID is 8214 2022-11-28T19:50:32.261Z,1669665032.261 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-28T19:50:32.261Z,1669665032.261 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:32.268Z,1669665032.268 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-11-28T19:50:32.268Z,1669665032.268 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:32.269Z,1669665032.269 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8215 2022-11-28T19:50:32.653Z,1669665032.653 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-28T19:50:32.653Z,1669665032.653 [WetLabsBB2FL](INFO): Powering down 2022-11-28T19:50:32.654Z,1669665032.654 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:32.668Z,1669665032.668 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-11-28T19:50:32.669Z,1669665032.669 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:32.669Z,1669665032.669 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8216 2022-11-28T19:50:33.009Z,1669665033.009 [CTD_Seabird](INFO): Powering down 2022-11-28T19:50:33.020Z,1669665033.020 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-28T19:50:33.021Z,1669665033.021 [CTD_Seabird](INFO): Powering down 2022-11-28T19:50:33.045Z,1669665033.045 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:33.048Z,1669665033.048 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-11-28T19:50:33.049Z,1669665033.049 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:33.049Z,1669665033.049 [DAT](INFO): Join timeout helper Thread ID is 8217 2022-11-28T19:50:33.205Z,1669665033.205 [DAT](INFO): Powering down 2022-11-28T19:50:33.273Z,1669665033.273 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-28T19:50:33.273Z,1669665033.273 [DAT](INFO): Powering down 2022-11-28T19:50:33.274Z,1669665033.274 [DAT ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:33.280Z,1669665033.280 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-11-28T19:50:33.281Z,1669665033.281 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:33.281Z,1669665033.281 [Radio_Surface](INFO): Join timeout helper Thread ID is 8218 2022-11-28T19:50:33.457Z,1669665033.457 [Radio_Surface](INFO): Powering down 2022-11-28T19:50:33.458Z,1669665033.458 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-11-28T19:50:33.458Z,1669665033.458 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-11-28T19:50:33.477Z,1669665033.477 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-11-28T19:50:33.477Z,1669665033.47